2025-09-06T03:30:53.467Z,1757129453.467 [CommandExec](IMPORTANT): got command restart logs 2025-09-06T03:30:53.796Z,1757129453.796 [keepstation:E] Stopped 2025-09-06T03:30:53.796Z,1757129453.796 [keepstation:StartingMission] Running Loop=1 2025-09-06T03:30:53.796Z,1757129453.796 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-09-06T03:30:54.203Z,1757129454.203 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T03:30:54.203Z,1757129454.203 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T03:30:54.204Z,1757129454.204 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T03:30:54.204Z,1757129454.204 [keepstation:SurfaceComms:A] Stopped 2025-09-06T03:30:54.204Z,1757129454.204 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T03:30:54.204Z,1757129454.204 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T03:30:54.584Z,1757129454.584 [keepstation:NeedComms] Running Loop=1 2025-09-06T03:30:54.584Z,1757129454.584 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T03:30:54.584Z,1757129454.584 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T03:30:54.585Z,1757129454.585 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T03:30:54.585Z,1757129454.585 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T03:30:54.585Z,1757129454.585 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T03:30:54.586Z,1757129454.586 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T03:30:54.586Z,1757129454.586 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T03:30:54.590Z,1757129454.590 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T03:30:54.591Z,1757129454.591 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T03:30:54.591Z,1757129454.591 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T03:30:54.592Z,1757129454.592 [keepstation:NeedComms:A](INFO): last time_fix was: 1757126743.000000 second since 1970/01/01T00:00:00Z 2025-09-06T03:30:54.592Z,1757129454.592 [keepstation:NeedComms:A] Stopped 2025-09-06T03:30:55.055Z,1757129455.055 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T03:30:55.411Z,1757129455.411 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-09-06T03:31:04.215Z,1757129464.215 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-09-06T03:31:11.683Z,1757129471.683 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:31:18.825Z,1757129478.825 [NAL9602](INFO): SBD MO Status=2, MOMSN=22989, MT Status=2, MTMSN=0 2025-09-06T03:31:18.825Z,1757129478.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:31:42.810Z,1757129502.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:31:47.500Z,1757129507.500 [NAL9602](INFO): SBD MO Status=2, MOMSN=22989, MT Status=2, MTMSN=0 2025-09-06T03:31:47.500Z,1757129507.500 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:32:04.875Z,1757129524.875 [NAL9602](INFO): SBD MO Status=2, MOMSN=22989, MT Status=2, MTMSN=0 2025-09-06T03:32:04.875Z,1757129524.875 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:32:13.932Z,1757129533.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:32:40.807Z,1757129560.807 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003235 2025-09-06T03:33:33.749Z,1757129613.749 [NAL9602](INFO): SBD MO Status=2, MOMSN=22989, MT Status=2, MTMSN=0 2025-09-06T03:33:33.749Z,1757129613.749 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:34:24.250Z,1757129664.250 [NAL9602](INFO): SBD MO Status=0, MOMSN=22989, MT Status=0, MTMSN=0 2025-09-06T03:34:24.250Z,1757129664.250 [NAL9602](INFO): No messages in MT queue 2025-09-06T03:34:25.467Z,1757129665.467 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033424.00,A,3646.43138,N,12153.25456,W,1.886,157.26,060925,,,A*78 2025-09-06T03:34:25.469Z,1757129665.469 [NAL9602](INFO): GPS fix at 20250906T033424: (36.773856, -121.887576) 2025-09-06T03:34:25.482Z,1757129665.482 [UniversalFixResidualReporter](INFO): Fix residual: 38.1 %DT, over the last 3831.9 m. Residual distance 1461.4 m at bearing -60.9 degrees. Fix at (36.7739, -121.8876) with 1421.1 m made good. 2025-09-06T03:34:25.484Z,1757129665.484 [keepstation:NeedComms:C] Stopped 2025-09-06T03:34:25.485Z,1757129665.485 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T03:34:25.893Z,1757129665.893 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-09-06T03:34:57.408Z,1757129697.408 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T03:34:59.150Z,1757129699.150 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250905T130000/Courier0066.lzma 2025-09-06T03:35:10.193Z,1757129710.193 [DataOverHttps](INFO): Moved sent file to Logs/20250905T130000/Courier0066.lzma.bak 2025-09-06T03:35:10.193Z,1757129710.193 [DataOverHttps](INFO): SBD MOMSN=25936098 2025-09-06T03:35:36.977Z,1757129736.977 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20250906T033053/Courier0000.lzma 2025-09-06T03:35:48.016Z,1757129748.016 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0000.lzma.bak 2025-09-06T03:35:48.016Z,1757129748.016 [DataOverHttps](INFO): SBD MOMSN=25936101 2025-09-06T03:36:14.611Z,1757129774.611 [DVL_micro](ERROR): only read 2 of 4 data items for beam range. Device response is::RD,+0000.30,+999900.68 2025-09-06T03:36:14.611Z,1757129774.611 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+999900.68 2025-09-06T03:36:15.064Z,1757129775.064 [DataOverHttps](INFO): Sending 1765 bytes from file Logs/20250905T130000/Express0067.lzma 2025-09-06T03:36:26.101Z,1757129786.101 [DataOverHttps](INFO): Moved sent file to Logs/20250905T130000/Express0067.lzma.bak 2025-09-06T03:36:26.101Z,1757129786.101 [DataOverHttps](INFO): SBD MOMSN=25936105 2025-09-06T03:36:53.509Z,1757129813.509 [DataOverHttps](INFO): Sending 530 bytes from file Logs/20250906T033053/Express0001.lzma 2025-09-06T03:37:04.548Z,1757129824.548 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0001.lzma.bak 2025-09-06T03:37:04.548Z,1757129824.548 [DataOverHttps](INFO): SBD MOMSN=25936127 2025-09-06T03:37:05.923Z,1757129825.923 [keepstation:NeedComms:D] Stopped 2025-09-06T03:37:05.924Z,1757129825.924 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T03:37:06.297Z,1757129826.297 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-09-06T03:37:07.915Z,1757129827.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033707.00,A,3646.40960,N,12153.26321,W,0.564,195.54,060925,,,A*73 2025-09-06T03:37:07.918Z,1757129827.918 [NAL9602](INFO): GPS fix at 20250906T033707: (36.773493, -121.887720) 2025-09-06T03:37:07.996Z,1757129827.996 [keepstation:NeedComms:E] Stopped 2025-09-06T03:37:07.997Z,1757129827.997 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T03:37:07.997Z,1757129827.997 [keepstation:NeedComms] Stopped 2025-09-06T03:37:07.997Z,1757129827.997 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T03:37:07.997Z,1757129827.997 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T03:37:07.997Z,1757129827.997 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T03:37:08.359Z,1757129828.359 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T03:37:08.359Z,1757129828.359 [keepstation:SurfaceComms:B] Stopped 2025-09-06T03:37:08.360Z,1757129828.360 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T03:37:08.360Z,1757129828.360 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T03:37:08.360Z,1757129828.360 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T03:37:08.360Z,1757129828.360 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T03:37:08.735Z,1757129828.735 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T03:37:08.735Z,1757129828.735 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T03:37:08.735Z,1757129828.735 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T03:37:08.735Z,1757129828.735 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T03:37:08.735Z,1757129828.735 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T03:37:08.736Z,1757129828.736 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T03:37:09.137Z,1757129829.137 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T03:37:09.138Z,1757129829.138 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T03:37:09.138Z,1757129829.138 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T03:37:09.138Z,1757129829.138 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T03:37:09.138Z,1757129829.138 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T03:37:09.138Z,1757129829.138 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T03:37:09.558Z,1757129829.558 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T03:37:09.559Z,1757129829.559 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T03:37:09.559Z,1757129829.559 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T03:37:09.559Z,1757129829.559 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T03:37:09.559Z,1757129829.559 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T03:37:09.559Z,1757129829.559 [keepstation:SurfaceComms] Stopped 2025-09-06T03:37:09.559Z,1757129829.559 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T03:37:10.000Z,1757129830.000 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-09-06T03:37:10.000Z,1757129830.000 [keepstation:StartingMission] Stopped 2025-09-06T03:37:10.000Z,1757129830.000 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-09-06T03:37:10.000Z,1757129830.000 [keepstation:TransitToStation] Running Loop=1 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation:Dive] Running Loop=1 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-09-06T03:37:10.001Z,1757129830.001 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:TransitToStation:Dive] Running Loop=1 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd] Running Loop=1 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T03:37:10.364Z,1757129830.364 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-09-06T03:37:10.365Z,1757129830.365 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-09-06T03:37:10.366Z,1757129830.366 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-09-06T03:37:10.366Z,1757129830.366 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-09-06T03:37:10.366Z,1757129830.366 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T03:37:10.366Z,1757129830.366 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T03:37:10.366Z,1757129830.366 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T03:37:10.366Z,1757129830.366 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T03:37:10.370Z,1757129830.370 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T03:37:10.371Z,1757129830.371 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T03:37:10.371Z,1757129830.371 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T03:37:10.371Z,1757129830.371 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T03:37:10.371Z,1757129830.371 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-09-06T03:37:10.372Z,1757129830.372 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T03:37:10.373Z,1757129830.373 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T03:37:10.373Z,1757129830.373 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T03:37:10.373Z,1757129830.373 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T03:37:10.373Z,1757129830.373 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T03:37:10.374Z,1757129830.374 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T03:37:10.374Z,1757129830.374 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T03:37:17.189Z,1757129837.189 [DVL_micro](ERROR): Failed to parse: :SA,-02.51,+00.31,227.8 2025-09-06T03:37:30.520Z,1757129850.520 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T03:37:30.520Z,1757129850.520 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:38:37.043Z,1757129917.043 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-06T03:39:08.167Z,1757129948.167 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:39:39.311Z,1757129979.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:39:46.487Z,1757129986.487 [Radio_Surface](INFO): Powering down 2025-09-06T03:40:10.451Z,1757130010.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:40:11.455Z,1757130011.455 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T03:40:54.627Z,1757130054.627 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.627045 2025-09-06T03:40:55.765Z,1757130055.765 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T03:40:55.765Z,1757130055.765 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T03:40:55.765Z,1757130055.765 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T03:40:55.765Z,1757130055.765 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T03:40:56.228Z,1757130056.228 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T03:40:56.589Z,1757130056.589 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-09-06T03:40:56.590Z,1757130056.590 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-09-06T03:40:56.590Z,1757130056.590 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-09-06T03:40:56.590Z,1757130056.590 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-09-06T03:40:56.590Z,1757130056.590 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-09-06T03:40:56.590Z,1757130056.590 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-09-06T03:40:56.591Z,1757130056.591 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T03:40:56.592Z,1757130056.592 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T03:40:56.593Z,1757130056.593 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T03:40:56.593Z,1757130056.593 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T03:40:56.997Z,1757130056.997 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.83 mm (4 active estimators). 2025-09-06T03:41:20.402Z,1757130080.402 [DVL_micro](ERROR): Failed to parse: :0000.00,+00000000.00,9999.99,000.00 2025-09-06T03:41:44.233Z,1757130104.233 [DVL_micro](ERROR): Failed to parse: :WI,-00150,+00872,-00007,+00000,A 2025-09-06T03:42:10.497Z,1757130130.497 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T03:42:41.248Z,1757130161.248 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T03:43:21.280Z,1757130201.280 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-09-06T03:43:21.280Z,1757130201.280 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-09-06T03:43:21.281Z,1757130201.281 [keepstation:TransitToStation:C] Running Loop=1 2025-09-06T03:43:21.681Z,1757130201.681 [keepstation:TransitToStation:C] Stopped 2025-09-06T03:43:21.681Z,1757130201.681 [keepstation:TransitToStation:D] Running Loop=1 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T03:43:21.682Z,1757130201.682 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T03:43:21.683Z,1757130201.683 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T03:43:21.683Z,1757130201.683 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T03:43:21.683Z,1757130201.683 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T03:43:21.684Z,1757130201.684 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T03:43:21.684Z,1757130201.684 [keepstation:DiveCmd:TransitToStation] Stopped 2025-09-06T03:43:21.684Z,1757130201.684 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-09-06T03:43:21.684Z,1757130201.684 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-09-06T03:43:21.684Z,1757130201.684 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-09-06T03:43:21.684Z,1757130201.684 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-09-06T03:43:21.685Z,1757130201.685 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T03:43:21.685Z,1757130201.685 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-09-06T03:43:21.685Z,1757130201.685 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-09-06T03:43:21.685Z,1757130201.685 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-09-06T03:43:22.094Z,1757130202.094 [keepstation:TransitToStation:D] Stopped 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation] Stopped 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation:Dive] Stopped 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation:Dive:A] Stopped 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:OnStation] Running Loop=1 2025-09-06T03:43:22.095Z,1757130202.095 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-09-06T03:43:22.484Z,1757130202.484 [keepstation:NeedComms] Running Loop=1 2025-09-06T03:43:22.484Z,1757130202.484 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T03:43:22.484Z,1757130202.484 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T03:43:22.484Z,1757130202.484 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T03:43:22.485Z,1757130202.485 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T03:43:22.485Z,1757130202.485 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T03:43:22.485Z,1757130202.485 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T03:43:22.486Z,1757130202.486 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T03:43:22.486Z,1757130202.486 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T03:43:22.487Z,1757130202.487 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T03:43:22.487Z,1757130202.487 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T03:43:22.488Z,1757130202.488 [keepstation:NeedComms:A](INFO): last time_fix was: 1757129827.000000 second since 1970/01/01T00:00:00Z 2025-09-06T03:43:22.489Z,1757130202.489 [keepstation:NeedComms:A] Stopped 2025-09-06T03:44:35.307Z,1757130275.307 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T03:44:35.779Z,1757130275.779 [Radio_Surface](INFO): Powering up 2025-09-06T03:44:41.563Z,1757130281.563 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T03:45:11.683Z,1757130311.683 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:45:14.900Z,1757130314.900 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T03:45:14.901Z,1757130314.901 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:45:42.811Z,1757130342.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:45:43.172Z,1757130343.172 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T03:45:43.173Z,1757130343.173 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:46:13.935Z,1757130373.935 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:46:15.094Z,1757130375.094 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+9999.99,+0000.30,+0000.49 2025-09-06T03:46:45.067Z,1757130405.067 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:47:03.580Z,1757130423.580 [DVL_micro](ERROR): Failed to parse: :WI,+00000,+00000,+00000,+00000,I 2025-09-06T03:47:04.131Z,1757130424.131 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003416 2025-09-06T03:47:35.507Z,1757130455.507 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T03:47:35.507Z,1757130455.507 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:49:37.900Z,1757130577.900 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T03:49:37.900Z,1757130577.900 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:49:39.535Z,1757130579.535 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T03:49:45.185Z,1757130585.185 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034944.00,A,3646.39919,N,12153.31718,W,0.739,214.32,060925,,,D*70 2025-09-06T03:49:45.189Z,1757130585.189 [NAL9602](INFO): GPS fix at 20250906T034944: (36.773320, -121.888620) 2025-09-06T03:49:45.243Z,1757130585.243 [UniversalFixResidualReporter](INFO): Fix residual: 16.1 %DT, over the last 630.7 m. Residual distance 101.4 m at bearing 49.9 degrees. Fix at (36.7733, -121.8886) with 82.4 m made good. 2025-09-06T03:49:45.259Z,1757130585.259 [keepstation:NeedComms:C] Stopped 2025-09-06T03:49:45.259Z,1757130585.259 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T03:49:58.238Z,1757130598.238 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250906T033053/Courier0003.lzma 2025-09-06T03:50:09.292Z,1757130609.292 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0003.lzma.bak 2025-09-06T03:50:09.292Z,1757130609.292 [DataOverHttps](INFO): SBD MOMSN=25936140 2025-09-06T03:50:17.538Z,1757130617.538 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T03:50:41.836Z,1757130641.836 [DataOverHttps](INFO): Sending 513 bytes from file Logs/20250906T033053/Express0004.lzma 2025-09-06T03:50:52.888Z,1757130652.888 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0004.lzma.bak 2025-09-06T03:50:52.888Z,1757130652.888 [DataOverHttps](INFO): SBD MOMSN=25936143 2025-09-06T03:50:54.306Z,1757130654.306 [keepstation:NeedComms:D] Stopped 2025-09-06T03:50:54.306Z,1757130654.306 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T03:50:56.310Z,1757130656.310 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,035055.00,A,3646.39004,N,12153.32155,W,0.797,146.71,060925,,,D*76 2025-09-06T03:50:56.320Z,1757130656.320 [NAL9602](INFO): GPS fix at 20250906T035055: (36.773167, -121.888693) 2025-09-06T03:50:56.351Z,1757130656.351 [keepstation:NeedComms:E] Stopped 2025-09-06T03:50:56.352Z,1757130656.352 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T03:50:56.352Z,1757130656.352 [keepstation:NeedComms] Stopped 2025-09-06T03:50:56.352Z,1757130656.352 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T03:50:56.352Z,1757130656.352 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T03:50:56.352Z,1757130656.352 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T03:50:56.763Z,1757130656.763 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-09-06T03:50:56.763Z,1757130656.763 [keepstation:OnStation] Stopped 2025-09-06T03:50:56.763Z,1757130656.763 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-09-06T03:50:56.763Z,1757130656.763 [keepstation:KeepStation] Running Loop=1 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-09-06T03:50:56.764Z,1757130656.764 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-09-06T03:50:57.172Z,1757130657.172 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T03:51:27.025Z,1757130687.025 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-06T03:51:27.098Z,1757130687.098 [NAL9602](ERROR): received: +SBDI: 2, 22990, 2, 0, 0, 0 OK 2025-09-06T03:51:56.928Z,1757130716.928 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T03:51:56.928Z,1757130716.928 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T03:52:55.799Z,1757130775.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:53:11.807Z,1757130791.807 [Radio_Surface](INFO): Powering down 2025-09-06T03:53:26.923Z,1757130806.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T03:53:27.927Z,1757130807.927 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T03:54:45.259Z,1757130885.259 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.753906 2025-09-06T03:55:58.583Z,1757130958.583 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T03:56:29.295Z,1757130989.295 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T04:03:09.671Z,1757131389.671 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,+022,+00000,A 2025-09-06T04:03:09.671Z,1757131389.671 [DVL_micro](ERROR): Failed to parse: :BI,+022,+00000,A 2025-09-06T04:18:25.157Z,1757132305.157 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T04:18:25.158Z,1757132305.158 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T04:18:25.159Z,1757132305.159 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T04:18:25.160Z,1757132305.160 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2025-09-06T04:18:25.162Z,1757132305.162 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T04:25:55.213Z,1757132755.213 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.8,0000.0,148BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-06T04:25:55.213Z,1757132755.213 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.8,0000.0,148BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-06T04:28:50.555Z,1757132930.555 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,0000000009.99 2025-09-06T04:28:50.555Z,1757132930.555 [DVL_micro](ERROR): Failed to parse: :TS,0000000009.99 2025-09-06T04:34:07.285Z,1757133247.285 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+9999.99,+99999.99,+9999.99 2025-09-06T04:34:07.285Z,1757133247.285 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2025-09-06T04:40:29.879Z,1757133629.879 [DVL_micro](ERROR): Failed to parse: :SA,-02.25,+00.21,115.6 2025-09-06T04:50:07.591Z,1757134207.591 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.9,000042,+00000,I 2025-09-06T04:50:07.592Z,1757134207.592 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.9,000042,+00000,I 2025-09-06T04:50:56.503Z,1757134256.503 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T04:50:56.503Z,1757134256.503 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T04:50:56.504Z,1757134256.504 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T04:50:56.505Z,1757134256.505 [keepstation:SurfaceComms:A] Stopped 2025-09-06T04:50:56.505Z,1757134256.505 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T04:50:56.506Z,1757134256.506 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T04:50:56.889Z,1757134256.889 [keepstation:KeepStation:Dive] Stopped 2025-09-06T04:50:56.889Z,1757134256.889 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T04:50:56.889Z,1757134256.889 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T04:50:56.889Z,1757134256.889 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T04:50:56.889Z,1757134256.889 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T04:50:56.889Z,1757134256.889 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T04:50:56.890Z,1757134256.890 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T04:50:56.890Z,1757134256.890 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T04:50:56.890Z,1757134256.890 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T04:50:56.890Z,1757134256.890 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T04:50:56.892Z,1757134256.892 [keepstation:NeedComms] Running Loop=1 2025-09-06T04:50:56.892Z,1757134256.892 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T04:50:56.892Z,1757134256.892 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T04:50:56.892Z,1757134256.892 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T04:50:56.892Z,1757134256.892 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T04:50:56.893Z,1757134256.893 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T04:50:56.893Z,1757134256.893 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T04:50:56.893Z,1757134256.893 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T04:50:56.894Z,1757134256.894 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T04:50:56.894Z,1757134256.894 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T04:50:56.894Z,1757134256.894 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T04:50:56.896Z,1757134256.896 [keepstation:NeedComms:A](INFO): last time_fix was: 1757130655.000000 second since 1970/01/01T00:00:00Z 2025-09-06T04:50:56.896Z,1757134256.896 [keepstation:NeedComms:A] Stopped 2025-09-06T04:52:06.847Z,1757134326.847 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T04:52:07.319Z,1757134327.319 [Radio_Surface](INFO): Powering up 2025-09-06T04:52:13.591Z,1757134333.591 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T04:52:43.711Z,1757134363.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T04:53:14.835Z,1757134394.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T04:53:35.756Z,1757134415.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T04:53:35.756Z,1757134415.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:53:45.959Z,1757134425.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T04:54:01.220Z,1757134441.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T04:54:01.220Z,1757134441.220 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:54:17.087Z,1757134457.087 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T04:54:36.149Z,1757134476.149 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004946 2025-09-06T04:54:42.437Z,1757134482.437 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T04:54:42.437Z,1757134482.437 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:55:18.400Z,1757134518.400 [NAL9602](INFO): SBD MO Status=2, MOMSN=22990, MT Status=2, MTMSN=0 2025-09-06T04:55:18.400Z,1757134518.400 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:55:49.508Z,1757134549.508 [NAL9602](INFO): SBD MO Status=0, MOMSN=22990, MT Status=0, MTMSN=0 2025-09-06T04:55:49.508Z,1757134549.508 [NAL9602](INFO): No messages in MT queue 2025-09-06T04:55:50.706Z,1757134550.706 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045549.00,A,3646.14643,N,12153.37249,W,0.816,208.86,060925,,,A*7A 2025-09-06T04:55:50.708Z,1757134550.708 [NAL9602](INFO): GPS fix at 20250906T045549: (36.769107, -121.889542) 2025-09-06T04:55:50.729Z,1757134550.729 [keepstation:NeedComms:C] Stopped 2025-09-06T04:55:50.730Z,1757134550.730 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T04:55:54.753Z,1757134554.753 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250906T033053/Courier0006.lzma 2025-09-06T04:56:05.532Z,1757134565.532 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0006.lzma.bak 2025-09-06T04:56:05.532Z,1757134565.532 [DataOverHttps](INFO): SBD MOMSN=25936353 2025-09-06T04:56:23.792Z,1757134583.792 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T04:56:32.434Z,1757134592.434 [DataOverHttps](INFO): Sending 415 bytes from file Logs/20250906T033053/Express0007.lzma 2025-09-06T04:56:43.472Z,1757134603.472 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0007.lzma.bak 2025-09-06T04:56:43.472Z,1757134603.472 [DataOverHttps](INFO): SBD MOMSN=25936372 2025-09-06T04:56:44.820Z,1757134604.820 [keepstation:NeedComms:D] Stopped 2025-09-06T04:56:44.820Z,1757134604.820 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T04:56:46.814Z,1757134606.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045646.00,A,3646.14385,N,12153.37604,W,0.525,214.76,060925,,,A*7B 2025-09-06T04:56:46.816Z,1757134606.816 [NAL9602](INFO): GPS fix at 20250906T045646: (36.769064, -121.889601) 2025-09-06T04:56:46.864Z,1757134606.864 [keepstation:NeedComms:E] Stopped 2025-09-06T04:56:46.865Z,1757134606.865 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T04:56:46.865Z,1757134606.865 [keepstation:NeedComms] Stopped 2025-09-06T04:56:46.865Z,1757134606.865 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T04:56:46.865Z,1757134606.865 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T04:56:46.865Z,1757134606.865 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T04:56:47.267Z,1757134607.267 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T04:56:47.267Z,1757134607.267 [keepstation:SurfaceComms:B] Stopped 2025-09-06T04:56:47.268Z,1757134607.268 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T04:56:47.268Z,1757134607.268 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T04:56:47.268Z,1757134607.268 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T04:56:47.268Z,1757134607.268 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T04:56:47.677Z,1757134607.677 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T04:56:47.677Z,1757134607.677 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T04:56:47.677Z,1757134607.677 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T04:56:47.677Z,1757134607.677 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T04:56:47.677Z,1757134607.677 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T04:56:47.677Z,1757134607.677 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T04:56:48.056Z,1757134608.056 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T04:56:48.056Z,1757134608.056 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T04:56:48.056Z,1757134608.056 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T04:56:48.057Z,1757134608.057 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T04:56:48.057Z,1757134608.057 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T04:56:48.057Z,1757134608.057 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T04:56:48.472Z,1757134608.472 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T04:56:48.473Z,1757134608.473 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T04:56:48.473Z,1757134608.473 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T04:56:48.473Z,1757134608.473 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T04:56:48.473Z,1757134608.473 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T04:56:48.473Z,1757134608.473 [keepstation:SurfaceComms] Stopped 2025-09-06T04:56:48.473Z,1757134608.473 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T04:56:48.877Z,1757134608.877 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T04:56:48.877Z,1757134608.877 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T04:56:48.877Z,1757134608.877 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T04:56:48.877Z,1757134608.877 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T04:56:48.877Z,1757134608.877 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T04:56:48.878Z,1757134608.878 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T04:56:48.883Z,1757134608.883 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T04:56:48.883Z,1757134608.883 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T04:56:48.884Z,1757134608.884 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T04:56:48.884Z,1757134608.884 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T04:56:48.884Z,1757134608.884 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T04:57:10.656Z,1757134630.656 [DVL_micro](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BI,-00,I 2025-09-06T04:57:10.656Z,1757134630.656 [DVL_micro](ERROR): Failed to parse: :BI,-00,I 2025-09-06T04:57:11.056Z,1757134631.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=22991, MT Status=2, MTMSN=0 2025-09-06T04:57:11.056Z,1757134631.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:57:27.626Z,1757134647.626 [NAL9602](INFO): SBD MO Status=2, MOMSN=22991, MT Status=2, MTMSN=0 2025-09-06T04:57:27.627Z,1757134647.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:58:06.815Z,1757134686.815 [NAL9602](INFO): SBD MO Status=2, MOMSN=22991, MT Status=2, MTMSN=0 2025-09-06T04:58:06.816Z,1757134686.816 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T04:58:15.683Z,1757134695.683 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-06T04:58:46.831Z,1757134726.831 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T04:59:13.195Z,1757134753.195 [Radio_Surface](INFO): Powering down 2025-09-06T04:59:17.955Z,1757134757.955 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T04:59:18.959Z,1757134758.959 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T04:59:56.112Z,1757134796.112 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-06T05:00:00.806Z,1757134800.806 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000600 CHAN A1 (24V): 0.013607 CHAN A2 (12V): -0.005775 CHAN A3 (5V): -0.015829 CHAN B0 (3.3V): -0.009473 CHAN B1 (3.15aV): -0.008779 CHAN B2 (3.15bV): -0.008701 CHAN B3 (GND): -0.011019 OPEN: 0.007864 Full Scale: +/- 1 mA 2025-09-06T05:00:19.529Z,1757134819.529 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T05:00:19.529Z,1757134819.529 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T05:00:19.530Z,1757134819.530 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T05:00:19.530Z,1757134819.530 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T05:00:19.908Z,1757134819.908 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T05:00:19.908Z,1757134819.908 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T05:00:19.908Z,1757134819.908 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T05:00:19.908Z,1757134819.908 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T05:00:19.908Z,1757134819.908 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T05:00:19.908Z,1757134819.908 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T05:00:20.301Z,1757134820.301 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T05:00:20.301Z,1757134820.301 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T05:00:20.301Z,1757134820.301 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T05:00:20.301Z,1757134820.301 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T05:00:20.302Z,1757134820.302 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T05:00:20.302Z,1757134820.302 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T05:00:20.302Z,1757134820.302 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T05:00:20.302Z,1757134820.302 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T05:00:20.303Z,1757134820.303 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T05:00:20.303Z,1757134820.303 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T05:00:20.303Z,1757134820.303 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T05:00:20.303Z,1757134820.303 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T05:00:20.716Z,1757134820.716 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T05:00:20.716Z,1757134820.716 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T05:00:20.717Z,1757134820.717 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T05:00:20.717Z,1757134820.717 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T05:00:20.717Z,1757134820.717 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T05:00:20.717Z,1757134820.717 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T05:00:20.717Z,1757134820.717 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T05:00:34.267Z,1757134834.267 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.914810 2025-09-06T05:01:49.576Z,1757134909.576 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T05:02:20.281Z,1757134940.281 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T05:22:11.288Z,1757136131.288 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:2.550000m. At depth:16.327026m. 2025-09-06T05:26:02.764Z,1757136362.764 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T05:26:02.765Z,1757136362.765 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T05:26:02.766Z,1757136362.766 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T05:26:02.767Z,1757136362.767 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-06T05:26:02.769Z,1757136362.769 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T05:31:09.405Z,1757136669.405 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T05:36:06.094Z,1757136966.094 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+0006.95,+0000.30,+0006.00 2025-09-06T05:39:43.763Z,1757137183.763 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T05:39:44.178Z,1757137184.178 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 30.83 mm. 2025-09-06T05:39:44.180Z,1757137184.180 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm. 2025-09-06T05:41:24.749Z,1757137284.749 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-06T05:43:51.802Z,1757137431.802 [DVL_micro](ERROR): Failed to parse: :SA,-00.71,-00.29,176.7 2025-09-06T05:48:17.644Z,1757137697.644 [DVL_micro](ERROR): Failed to parse: :BI,+01163,+00000,+00000,+00000,I 2025-09-06T05:48:18.846Z,1757137698.846 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-06T05:56:47.117Z,1757138207.117 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T05:56:47.117Z,1757138207.117 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T05:56:47.117Z,1757138207.117 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T05:56:47.118Z,1757138207.118 [keepstation:SurfaceComms:A] Stopped 2025-09-06T05:56:47.118Z,1757138207.118 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T05:56:47.118Z,1757138207.118 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T05:56:47.497Z,1757138207.497 [keepstation:KeepStation:Dive] Stopped 2025-09-06T05:56:47.497Z,1757138207.497 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T05:56:47.497Z,1757138207.497 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T05:56:47.497Z,1757138207.497 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T05:56:47.498Z,1757138207.498 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T05:56:47.498Z,1757138207.498 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T05:56:47.498Z,1757138207.498 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T05:56:47.498Z,1757138207.498 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T05:56:47.498Z,1757138207.498 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T05:56:47.498Z,1757138207.498 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T05:56:47.500Z,1757138207.500 [keepstation:NeedComms] Running Loop=1 2025-09-06T05:56:47.500Z,1757138207.500 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T05:56:47.500Z,1757138207.500 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T05:56:47.500Z,1757138207.500 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T05:56:47.501Z,1757138207.501 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T05:56:47.501Z,1757138207.501 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T05:56:47.501Z,1757138207.501 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T05:56:47.502Z,1757138207.502 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T05:56:47.502Z,1757138207.502 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T05:56:47.503Z,1757138207.503 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T05:56:47.503Z,1757138207.503 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T05:56:47.504Z,1757138207.504 [keepstation:NeedComms:A](INFO): last time_fix was: 1757134606.000000 second since 1970/01/01T00:00:00Z 2025-09-06T05:56:47.504Z,1757138207.504 [keepstation:NeedComms:A] Stopped 2025-09-06T05:58:13.184Z,1757138293.184 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T05:58:13.939Z,1757138293.939 [Radio_Surface](INFO): Powering up 2025-09-06T05:58:19.815Z,1757138299.815 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T05:58:45.911Z,1757138325.911 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-06T05:58:45.994Z,1757138325.994 [NAL9602](ERROR): received: +CSQ:0 OK991, 2, 0, 0, 0 OK 2025-09-06T05:58:49.935Z,1757138329.935 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T05:59:21.059Z,1757138361.059 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T05:59:21.481Z,1757138361.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=22991, MT Status=2, MTMSN=0 2025-09-06T05:59:21.481Z,1757138361.481 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T05:59:38.438Z,1757138378.438 [NAL9602](INFO): SBD MO Status=2, MOMSN=22991, MT Status=2, MTMSN=0 2025-09-06T05:59:38.439Z,1757138378.439 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T05:59:52.183Z,1757138392.183 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T06:00:23.307Z,1757138423.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T06:00:42.060Z,1757138442.060 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003917 2025-09-06T06:01:56.601Z,1757138516.601 [NAL9602](INFO): SBD MO Status=2, MOMSN=22991, MT Status=2, MTMSN=0 2025-09-06T06:01:56.601Z,1757138516.601 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T06:03:17.409Z,1757138597.409 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T06:03:25.887Z,1757138605.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060325.00,A,3645.90213,N,12153.31800,W,0.661,219.70,060925,,,A*79 2025-09-06T06:03:25.889Z,1757138605.889 [NAL9602](INFO): GPS fix at 20250906T060325: (36.765036, -121.888633) 2025-09-06T06:03:25.901Z,1757138605.901 [UniversalFixResidualReporter](INFO): Fix residual: 34.2 %DT, over the last 996.3 m. Residual distance 340.4 m at bearing 15.1 degrees. Fix at (36.7650, -121.8886) with 456.2 m made good. 2025-09-06T06:03:25.904Z,1757138605.904 [keepstation:NeedComms:C] Stopped 2025-09-06T06:03:25.905Z,1757138605.905 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T06:03:33.570Z,1757138613.570 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250906T033053/Courier0009.lzma 2025-09-06T06:03:44.629Z,1757138624.629 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0009.lzma.bak 2025-09-06T06:03:44.629Z,1757138624.629 [DataOverHttps](INFO): SBD MOMSN=25936553 2025-09-06T06:03:58.629Z,1757138638.629 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T06:04:11.029Z,1757138651.029 [DataOverHttps](INFO): Sending 958 bytes from file Logs/20250906T033053/Express0010.lzma 2025-09-06T06:04:22.068Z,1757138662.068 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0010.lzma.bak 2025-09-06T06:04:22.069Z,1757138662.069 [DataOverHttps](INFO): SBD MOMSN=25936556 2025-09-06T06:04:23.299Z,1757138663.299 [keepstation:NeedComms:D] Stopped 2025-09-06T06:04:23.299Z,1757138663.299 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T06:04:25.290Z,1757138665.290 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060424.00,A,3645.90257,N,12153.32062,W,0.311,328.46,060925,,,A*74 2025-09-06T06:04:25.292Z,1757138665.292 [NAL9602](INFO): GPS fix at 20250906T060424: (36.765043, -121.888677) 2025-09-06T06:04:25.341Z,1757138665.341 [keepstation:NeedComms:E] Stopped 2025-09-06T06:04:25.341Z,1757138665.341 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T06:04:25.342Z,1757138665.342 [keepstation:NeedComms] Stopped 2025-09-06T06:04:25.342Z,1757138665.342 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T06:04:25.342Z,1757138665.342 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T06:04:25.342Z,1757138665.342 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T06:04:25.753Z,1757138665.753 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T06:04:25.753Z,1757138665.753 [keepstation:SurfaceComms:B] Stopped 2025-09-06T06:04:25.754Z,1757138665.754 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T06:04:25.754Z,1757138665.754 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T06:04:25.754Z,1757138665.754 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T06:04:25.754Z,1757138665.754 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T06:04:26.132Z,1757138666.132 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T06:04:26.132Z,1757138666.132 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T06:04:26.132Z,1757138666.132 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T06:04:26.132Z,1757138666.132 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T06:04:26.132Z,1757138666.132 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T06:04:26.132Z,1757138666.132 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T06:04:26.531Z,1757138666.531 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T06:04:26.531Z,1757138666.531 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T06:04:26.532Z,1757138666.532 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T06:04:26.532Z,1757138666.532 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T06:04:26.532Z,1757138666.532 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T06:04:26.532Z,1757138666.532 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms] Stopped 2025-09-06T06:04:26.989Z,1757138666.989 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T06:04:27.327Z,1757138667.327 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T06:04:27.327Z,1757138667.327 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T06:04:27.328Z,1757138667.328 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T06:04:27.328Z,1757138667.328 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T06:04:27.328Z,1757138667.328 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T06:04:27.328Z,1757138667.328 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T06:04:27.328Z,1757138667.328 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T06:04:27.329Z,1757138667.329 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T06:04:27.330Z,1757138667.330 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T06:04:27.330Z,1757138667.330 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T06:04:27.330Z,1757138667.330 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T06:04:56.013Z,1757138696.013 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-06T06:04:56.091Z,1757138696.091 [NAL9602](ERROR): received: +CSQ:0 OK991, 2, 0, 0, 0 OK 2025-09-06T06:05:19.428Z,1757138719.428 [NAL9602](INFO): SBD MO Status=0, MOMSN=22991, MT Status=0, MTMSN=0 2025-09-06T06:05:19.428Z,1757138719.428 [NAL9602](INFO): No messages in MT queue 2025-09-06T06:05:50.127Z,1757138750.127 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T06:06:54.155Z,1757138814.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T06:07:25.279Z,1757138845.279 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T06:07:29.451Z,1757138849.451 [Radio_Surface](INFO): Powering down 2025-09-06T06:07:56.403Z,1757138876.403 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T06:07:57.415Z,1757138877.415 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T06:08:35.785Z,1757138915.785 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T06:08:35.786Z,1757138915.786 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T06:08:35.786Z,1757138915.786 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T06:08:35.786Z,1757138915.786 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T06:08:36.200Z,1757138916.200 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T06:08:36.200Z,1757138916.200 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T06:08:36.200Z,1757138916.200 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T06:08:36.200Z,1757138916.200 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T06:08:36.201Z,1757138916.201 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T06:08:36.201Z,1757138916.201 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T06:08:36.596Z,1757138916.596 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T06:08:36.596Z,1757138916.596 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T06:08:36.596Z,1757138916.596 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T06:08:36.597Z,1757138916.597 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T06:08:36.598Z,1757138916.598 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T06:08:36.598Z,1757138916.598 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T06:08:36.992Z,1757138916.992 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T06:09:11.711Z,1757138951.711 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.727203 2025-09-06T06:10:48.693Z,1757139048.693 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-06T06:18:19.159Z,1757139499.159 [DVL_micro](ERROR): Failed to parse: :SA,+04.24,-00.14,026.6 2025-09-06T06:30:21.932Z,1757140221.932 [DVL_micro](ERROR): Failed to parse: :BI,+00125,+00013,-00042,+00000,I 2025-09-06T06:30:23.961Z,1757140223.961 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-06T06:30:26.350Z,1757140226.350 [DVL_micro](ERROR): Failed to parse: :SA,+00.16,-00.24,337.8 2025-09-06T06:33:37.851Z,1757140417.851 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T06:33:37.852Z,1757140417.852 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T06:33:37.853Z,1757140417.853 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T06:33:37.853Z,1757140417.853 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-06T06:33:37.856Z,1757140417.856 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T06:35:46.728Z,1757140546.728 [DVL_micro](ERROR): Failed to parse: 2025-09-06T06:42:14.997Z,1757140934.997 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T06:42:15.369Z,1757140935.369 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm (3 active estimators). 2025-09-06T06:50:07.099Z,1757141407.099 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T06:52:10.709Z,1757141530.709 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.7,0:BD,+00000000.00,+00000000.00,+00000000.00,0080.00,000.00 2025-09-06T06:52:10.709Z,1757141530.709 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.7,0:BD,+00000000.00,+00000000.00,+00000000.00,0080.00,000.00 2025-09-06T06:57:01.635Z,1757141821.635 [DVL_micro](ERROR): Failed to parse: :WI,-00116,+00246,+00018,+00000,A 2025-09-06T06:57:03.649Z,1757141823.649 [DVL_micro](ERROR): Failed to parse: :BI,+00007,+00086,-00010,+00000,A 2025-09-06T06:57:05.682Z,1757141825.682 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0081.50,000.00 2025-09-06T06:57:08.084Z,1757141828.084 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-09-06T06:57:08.084Z,1757141828.084 [DVL_micro] Communications Fault, FailCount= 1 2025-09-06T06:57:08.084Z,1757141828.084 [DVL_micro](ERROR): Communications Fault 2025-09-06T06:57:08.251Z,1757141828.251 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-06T06:57:08.579Z,1757141828.579 [DVL_micro](INFO): Powering down 2025-09-06T06:57:09.458Z,1757141829.458 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-06T06:57:09.458Z,1757141829.458 [DVL_micro] No Fault, FailCount= 1 2025-09-06T06:58:30.508Z,1757141910.508 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T07:04:25.608Z,1757142265.608 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T07:04:25.608Z,1757142265.608 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T07:04:25.608Z,1757142265.608 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T07:04:25.609Z,1757142265.609 [keepstation:SurfaceComms:A] Stopped 2025-09-06T07:04:25.609Z,1757142265.609 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T07:04:25.609Z,1757142265.609 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T07:04:25.611Z,1757142265.611 [keepstation:NeedComms] Running Loop=1 2025-09-06T07:04:25.611Z,1757142265.611 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T07:04:25.611Z,1757142265.611 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T07:04:25.611Z,1757142265.611 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T07:04:25.612Z,1757142265.612 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T07:04:25.612Z,1757142265.612 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T07:04:25.612Z,1757142265.612 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T07:04:25.613Z,1757142265.613 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T07:04:25.613Z,1757142265.613 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T07:04:25.613Z,1757142265.613 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T07:04:25.614Z,1757142265.614 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T07:04:25.615Z,1757142265.615 [keepstation:NeedComms:A](INFO): last time_fix was: 1757138664.000000 second since 1970/01/01T00:00:00Z 2025-09-06T07:04:25.615Z,1757142265.615 [keepstation:NeedComms:A] Stopped 2025-09-06T07:04:26.012Z,1757142266.012 [keepstation:KeepStation:Dive] Stopped 2025-09-06T07:04:26.012Z,1757142266.012 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T07:04:26.012Z,1757142266.012 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T07:04:26.012Z,1757142266.012 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T07:04:26.013Z,1757142266.013 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T07:04:26.013Z,1757142266.013 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T07:04:26.013Z,1757142266.013 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T07:04:26.013Z,1757142266.013 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T07:04:26.013Z,1757142266.013 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T07:04:26.013Z,1757142266.013 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T07:05:48.794Z,1757142348.794 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T07:05:49.403Z,1757142349.403 [Radio_Surface](INFO): Powering up 2025-09-06T07:05:54.823Z,1757142354.823 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T07:06:19.486Z,1757142379.486 [NAL9602](INFO): SBD MO Status=2, MOMSN=22992, MT Status=2, MTMSN=0 2025-09-06T07:06:19.486Z,1757142379.486 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T07:06:24.967Z,1757142384.967 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T07:06:56.107Z,1757142416.107 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T07:07:27.231Z,1757142447.231 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T07:07:37.052Z,1757142457.052 [NAL9602](INFO): SBD MO Status=0, MOMSN=22992, MT Status=0, MTMSN=0 2025-09-06T07:07:37.052Z,1757142457.052 [NAL9602](INFO): No messages in MT queue 2025-09-06T07:07:38.261Z,1757142458.261 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,070737.00,A,3645.83834,N,12153.19618,W,0.428,305.12,060925,,,A*78 2025-09-06T07:07:38.263Z,1757142458.263 [NAL9602](INFO): GPS fix at 20250906T070737: (36.763972, -121.886603) 2025-09-06T07:07:38.275Z,1757142458.275 [UniversalFixResidualReporter](INFO): Fix residual: 25.2 %DT, over the last 1117.2 m. Residual distance 281.5 m at bearing -51.0 degrees. Fix at (36.7640, -121.8866) with 219.8 m made good. 2025-09-06T07:07:38.278Z,1757142458.278 [keepstation:NeedComms:C] Stopped 2025-09-06T07:07:38.291Z,1757142458.291 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T07:07:58.371Z,1757142478.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T07:08:02.149Z,1757142482.149 [NAL9602](INFO): SBD MO Status=2, MOMSN=22993, MT Status=2, MTMSN=0 2025-09-06T07:08:02.150Z,1757142482.150 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T07:08:17.195Z,1757142497.195 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003291 2025-09-06T07:08:21.630Z,1757142501.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=22993, MT Status=2, MTMSN=0 2025-09-06T07:08:21.634Z,1757142501.634 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T07:08:24.894Z,1757142504.894 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20250906T033053/Courier0012.lzma 2025-09-06T07:08:35.936Z,1757142515.936 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0012.lzma.bak 2025-09-06T07:08:35.936Z,1757142515.936 [DataOverHttps](INFO): SBD MOMSN=25936683 2025-09-06T07:08:52.304Z,1757142532.304 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T07:09:02.436Z,1757142542.436 [DataOverHttps](INFO): Sending 565 bytes from file Logs/20250906T033053/Express0013.lzma 2025-09-06T07:09:13.476Z,1757142553.476 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0013.lzma.bak 2025-09-06T07:09:13.476Z,1757142553.476 [DataOverHttps](INFO): SBD MOMSN=25936686 2025-09-06T07:09:14.952Z,1757142554.952 [keepstation:NeedComms:D] Stopped 2025-09-06T07:09:14.952Z,1757142554.952 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T07:09:16.950Z,1757142556.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,070916.00,A,3645.84098,N,12153.20280,W,0.389,312.99,060925,,,A*7A 2025-09-06T07:09:16.953Z,1757142556.953 [NAL9602](INFO): GPS fix at 20250906T070916: (36.764016, -121.886713) 2025-09-06T07:09:17.001Z,1757142557.001 [keepstation:NeedComms:E] Stopped 2025-09-06T07:09:17.001Z,1757142557.001 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T07:09:17.001Z,1757142557.001 [keepstation:NeedComms] Stopped 2025-09-06T07:09:17.001Z,1757142557.001 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T07:09:17.002Z,1757142557.002 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T07:09:17.002Z,1757142557.002 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T07:09:17.435Z,1757142557.435 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T07:09:17.435Z,1757142557.435 [keepstation:SurfaceComms:B] Stopped 2025-09-06T07:09:17.435Z,1757142557.435 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T07:09:17.435Z,1757142557.435 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T07:09:17.436Z,1757142557.436 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T07:09:17.436Z,1757142557.436 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T07:09:17.787Z,1757142557.787 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T07:09:17.787Z,1757142557.787 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T07:09:17.787Z,1757142557.787 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T07:09:17.787Z,1757142557.787 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T07:09:17.787Z,1757142557.787 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T07:09:17.787Z,1757142557.787 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T07:09:18.176Z,1757142558.176 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T07:09:18.176Z,1757142558.176 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T07:09:18.176Z,1757142558.176 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T07:09:18.177Z,1757142558.177 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T07:09:18.177Z,1757142558.177 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T07:09:18.177Z,1757142558.177 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T07:09:18.592Z,1757142558.592 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T07:09:18.592Z,1757142558.592 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T07:09:18.592Z,1757142558.592 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T07:09:18.592Z,1757142558.592 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T07:09:18.592Z,1757142558.592 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T07:09:18.592Z,1757142558.592 [keepstation:SurfaceComms] Stopped 2025-09-06T07:09:18.593Z,1757142558.593 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T07:09:19.030Z,1757142559.030 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T07:09:19.030Z,1757142559.030 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T07:09:19.031Z,1757142559.031 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T07:09:19.031Z,1757142559.031 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T07:09:19.031Z,1757142559.031 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T07:09:19.031Z,1757142559.031 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T07:09:19.031Z,1757142559.031 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T07:09:19.032Z,1757142559.032 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T07:09:19.033Z,1757142559.033 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T07:09:19.033Z,1757142559.033 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T07:09:19.033Z,1757142559.033 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T07:09:31.127Z,1757142571.127 [NAL9602](INFO): SBD MO Status=1, MOMSN=22993, MT Status=0, MTMSN=0 2025-09-06T07:09:31.127Z,1757142571.127 [NAL9602](INFO): No messages in MT queue 2025-09-06T07:10:01.794Z,1757142601.794 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T07:11:17.175Z,1757142677.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T07:11:48.311Z,1757142708.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T07:11:48.970Z,1757142708.970 [Radio_Surface](INFO): Powering down 2025-09-06T07:11:49.315Z,1757142709.315 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T07:12:54.360Z,1757142774.360 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T07:12:54.360Z,1757142774.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T07:12:54.360Z,1757142774.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T07:12:54.360Z,1757142774.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T07:12:54.721Z,1757142774.721 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T07:12:54.721Z,1757142774.721 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T07:12:54.721Z,1757142774.721 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T07:12:54.721Z,1757142774.721 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T07:12:54.722Z,1757142774.722 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T07:12:54.722Z,1757142774.722 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T07:12:55.125Z,1757142775.125 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T07:12:55.126Z,1757142775.126 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T07:12:55.126Z,1757142775.126 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T07:12:55.126Z,1757142775.126 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T07:12:55.127Z,1757142775.127 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T07:12:55.541Z,1757142775.541 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T07:13:12.489Z,1757142792.489 [DVL_micro](ERROR): Failed to parse: :WI,+00146,-00096,-00003,+00000,A 2025-09-06T07:13:34.735Z,1757142814.735 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743103 2025-09-06T07:16:00.549Z,1757142960.549 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+0010.62,+0000.30,+0005.14 2025-09-06T07:16:38.194Z,1757142998.194 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:4.620000m. At depth:20.681471m. 2025-09-06T07:17:44.776Z,1757143064.776 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+9999.99,+0000.30,+0003.65 2025-09-06T07:18:09.029Z,1757143089.029 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T07:18:39.791Z,1757143119.791 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,+9999.99.52 2025-09-06T07:18:39.791Z,1757143119.791 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99.52 2025-09-06T07:26:03.013Z,1757143563.013 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T07:26:45.032Z,1757143605.032 [DVL_micro](ERROR): Failed to parse: :9.99,+9999.99,+0097.99 2025-09-06T07:29:19.354Z,1757143759.354 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T07:30:10.677Z,1757143810.677 [DVL_micro](ERROR): Failed to parse: :WI,-00332,+00000,+00000,+00000,I 2025-09-06T07:36:39.699Z,1757144199.699 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T07:36:40.064Z,1757144200.064 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm. 2025-09-06T07:41:16.492Z,1757144476.492 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T07:41:16.492Z,1757144476.492 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T07:41:16.493Z,1757144476.493 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T07:41:16.494Z,1757144476.494 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-06T07:41:16.497Z,1757144476.497 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T07:43:18.107Z,1757144598.107 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T07:46:41.027Z,1757144801.027 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00 2025-09-06T07:49:45.532Z,1757144985.532 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T07:49:45.930Z,1757144985.930 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.83 mm. 2025-09-06T07:49:49.156Z,1757144989.156 [DVL_micro](ERROR): Failed to parse::SA,-01.80,+05.94,153.2 2025-09-06T07:50:17.463Z,1757145017.463 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T07:53:44.191Z,1757145224.191 [DVL_micro](ERROR): Failed to parse: :BI,+03542,+03624,+01512,+00000,A 2025-09-06T07:55:14.813Z,1757145314.813 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T07:55:25.305Z,1757145325.305 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T08:00:47.899Z,1757145647.899 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T08:01:57.386Z,1757145717.386 [DVL_micro](ERROR): Failed to parse: :WI,-00094,+00044,-00016,+00000,A 2025-09-06T08:01:59.396Z,1757145719.396 [DVL_micro](ERROR): Failed to parse: :BI,-00062,+00079,-00032,+00000,A 2025-09-06T08:02:01.420Z,1757145721.420 [DVL_micro](ERROR): Failed to parse: :BI,-00097,+00068,-00023,+00000,I 2025-09-06T08:02:03.437Z,1757145723.437 [DVL_micro](ERROR): Failed to parse: :RD,+0118.27,+0145.62,+9999.99,+0146.02 2025-09-06T08:02:03.831Z,1757145723.831 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-09-06T08:02:03.831Z,1757145723.831 [DVL_micro] Communications Fault, FailCount= 1 2025-09-06T08:02:03.831Z,1757145723.831 [DVL_micro](ERROR): Communications Fault 2025-09-06T08:02:03.990Z,1757145723.990 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-06T08:02:04.331Z,1757145724.331 [DVL_micro](INFO): Powering down 2025-09-06T08:02:05.222Z,1757145725.222 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-06T08:02:05.222Z,1757145725.222 [DVL_micro] No Fault, FailCount= 1 2025-09-06T08:04:56.342Z,1757145896.342 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00000.00,9999.99,000.00 2025-09-06T08:04:56.342Z,1757145896.342 [DVL_micro](ERROR): Failed to parse: :BI,-00000.00,9999.99,000.00 2025-09-06T08:09:17.357Z,1757146157.357 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T08:09:17.357Z,1757146157.357 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T08:09:17.357Z,1757146157.357 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T08:09:17.358Z,1757146157.358 [keepstation:SurfaceComms:A] Stopped 2025-09-06T08:09:17.358Z,1757146157.358 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T08:09:17.358Z,1757146157.358 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T08:09:17.759Z,1757146157.759 [keepstation:KeepStation:Dive] Stopped 2025-09-06T08:09:17.759Z,1757146157.759 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T08:09:17.759Z,1757146157.759 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T08:09:17.759Z,1757146157.759 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T08:09:17.760Z,1757146157.760 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T08:09:17.760Z,1757146157.760 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T08:09:17.760Z,1757146157.760 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T08:09:17.760Z,1757146157.760 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T08:09:17.760Z,1757146157.760 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T08:09:17.760Z,1757146157.760 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T08:09:17.762Z,1757146157.762 [keepstation:NeedComms] Running Loop=1 2025-09-06T08:09:17.762Z,1757146157.762 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T08:09:17.762Z,1757146157.762 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T08:09:17.762Z,1757146157.762 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T08:09:17.763Z,1757146157.763 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T08:09:17.763Z,1757146157.763 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T08:09:17.764Z,1757146157.764 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T08:09:17.764Z,1757146157.764 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T08:09:17.764Z,1757146157.764 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T08:09:17.765Z,1757146157.765 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T08:09:17.765Z,1757146157.765 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T08:09:17.766Z,1757146157.766 [keepstation:NeedComms:A](INFO): last time_fix was: 1757142556.000000 second since 1970/01/01T00:00:00Z 2025-09-06T08:09:17.767Z,1757146157.767 [keepstation:NeedComms:A] Stopped 2025-09-06T08:10:42.596Z,1757146242.596 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T08:10:43.115Z,1757146243.115 [Radio_Surface](INFO): Powering up 2025-09-06T08:10:48.868Z,1757146248.868 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T08:11:02.397Z,1757146262.397 [NAL9602](INFO): SBD MO Status=2, MOMSN=22994, MT Status=2, MTMSN=0 2025-09-06T08:11:02.397Z,1757146262.397 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T08:11:09.668Z,1757146269.668 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 63 Device respsone is::TS,00000000000000,35.0,+13.0,00118,+00041,+00063,+00000,A 2025-09-06T08:11:09.670Z,1757146269.670 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0104.80,000.00 2025-09-06T08:11:18.991Z,1757146278.991 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:11:25.016Z,1757146285.016 [NAL9602](INFO): SBD MO Status=2, MOMSN=22994, MT Status=2, MTMSN=0 2025-09-06T08:11:25.017Z,1757146285.017 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T08:11:45.632Z,1757146305.632 [NAL9602](INFO): SBD MO Status=2, MOMSN=22994, MT Status=2, MTMSN=0 2025-09-06T08:11:45.632Z,1757146305.632 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T08:11:50.122Z,1757146310.122 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:12:21.251Z,1757146341.251 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:12:24.407Z,1757146344.407 [NAL9602](INFO): SBD MO Status=2, MOMSN=22994, MT Status=2, MTMSN=0 2025-09-06T08:12:24.407Z,1757146344.407 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T08:12:52.375Z,1757146372.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:13:07.695Z,1757146387.695 [NAL9602](INFO): SBD MO Status=2, MOMSN=22994, MT Status=2, MTMSN=0 2025-09-06T08:13:07.695Z,1757146387.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T08:13:11.583Z,1757146391.583 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003296 2025-09-06T08:13:33.190Z,1757146413.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=22994, MT Status=2, MTMSN=0 2025-09-06T08:13:33.190Z,1757146413.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T08:14:47.097Z,1757146487.097 [NAL9602](INFO): SBD MO Status=1, MOMSN=22994, MT Status=0, MTMSN=0 2025-09-06T08:14:47.097Z,1757146487.097 [NAL9602](INFO): No messages in MT queue 2025-09-06T08:14:48.316Z,1757146488.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081447.00,A,3645.86212,N,12152.88444,W,0.136,230.42,060925,,,D*76 2025-09-06T08:14:48.318Z,1757146488.318 [NAL9602](INFO): GPS fix at 20250906T081447: (36.764369, -121.881407) 2025-09-06T08:14:48.330Z,1757146488.330 [UniversalFixResidualReporter](INFO): Fix residual: 6.2 %DT, over the last 4284.4 m. Residual distance 267.1 m at bearing -105.4 degrees. Fix at (36.7644, -121.8814) with 474.3 m made good. 2025-09-06T08:14:48.333Z,1757146488.333 [keepstation:NeedComms:C] Stopped 2025-09-06T08:14:48.334Z,1757146488.334 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T08:14:56.610Z,1757146496.610 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250906T033053/Courier0015.lzma 2025-09-06T08:15:07.652Z,1757146507.652 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0015.lzma.bak 2025-09-06T08:15:07.652Z,1757146507.652 [DataOverHttps](INFO): SBD MOMSN=25936864 2025-09-06T08:15:20.706Z,1757146520.706 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T08:15:34.462Z,1757146534.462 [DataOverHttps](INFO): Sending 1157 bytes from file Logs/20250906T033053/Express0016.lzma 2025-09-06T08:15:45.516Z,1757146545.516 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0016.lzma.bak 2025-09-06T08:15:45.517Z,1757146545.517 [DataOverHttps](INFO): SBD MOMSN=25936868 2025-09-06T08:15:47.007Z,1757146547.007 [keepstation:NeedComms:D] Stopped 2025-09-06T08:15:47.007Z,1757146547.007 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T08:15:49.025Z,1757146549.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081548.00,A,3645.86429,N,12152.88463,W,0.564,97.17,060925,,,A*4A 2025-09-06T08:15:49.032Z,1757146549.032 [NAL9602](INFO): GPS fix at 20250906T081548: (36.764405, -121.881411) 2025-09-06T08:15:49.059Z,1757146549.059 [keepstation:NeedComms:E] Stopped 2025-09-06T08:15:49.059Z,1757146549.059 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T08:15:49.059Z,1757146549.059 [keepstation:NeedComms] Stopped 2025-09-06T08:15:49.059Z,1757146549.059 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T08:15:49.060Z,1757146549.060 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T08:15:49.060Z,1757146549.060 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T08:15:49.419Z,1757146549.419 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T08:15:49.420Z,1757146549.420 [keepstation:SurfaceComms:B] Stopped 2025-09-06T08:15:49.420Z,1757146549.420 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T08:15:49.420Z,1757146549.420 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T08:15:49.420Z,1757146549.420 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T08:15:49.420Z,1757146549.420 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T08:15:49.810Z,1757146549.810 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T08:15:49.810Z,1757146549.810 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T08:15:49.810Z,1757146549.810 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T08:15:49.810Z,1757146549.810 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T08:15:49.810Z,1757146549.810 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T08:15:49.810Z,1757146549.810 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T08:15:50.236Z,1757146550.236 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T08:15:50.236Z,1757146550.236 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T08:15:50.236Z,1757146550.236 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T08:15:50.236Z,1757146550.236 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T08:15:50.236Z,1757146550.236 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T08:15:50.236Z,1757146550.236 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T08:15:50.718Z,1757146550.718 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T08:15:50.727Z,1757146550.727 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T08:15:50.727Z,1757146550.727 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T08:15:50.727Z,1757146550.727 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T08:15:50.727Z,1757146550.727 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T08:15:50.727Z,1757146550.727 [keepstation:SurfaceComms] Stopped 2025-09-06T08:15:50.727Z,1757146550.727 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T08:15:51.071Z,1757146551.071 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T08:15:51.072Z,1757146551.072 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T08:15:51.072Z,1757146551.072 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T08:15:51.072Z,1757146551.072 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T08:15:51.072Z,1757146551.072 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T08:15:51.072Z,1757146551.072 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T08:15:51.073Z,1757146551.073 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T08:15:51.074Z,1757146551.074 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T08:15:51.074Z,1757146551.074 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T08:15:51.074Z,1757146551.074 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T08:15:51.074Z,1757146551.074 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T08:15:58.690Z,1757146558.690 [DVL_micro](ERROR): Failed to parse: :RD,+0119.55,+0136.05,+9999.99,+0133.89 2025-09-06T08:16:00.711Z,1757146560.711 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0105.90,000.00 2025-09-06T08:16:14.840Z,1757146574.840 [NAL9602](INFO): SBD MO Status=1, MOMSN=22995, MT Status=0, MTMSN=0 2025-09-06T08:16:14.840Z,1757146574.840 [NAL9602](INFO): No messages in MT queue 2025-09-06T08:16:45.535Z,1757146605.535 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T08:17:47.275Z,1757146667.275 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:18:18.399Z,1757146698.399 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:18:25.011Z,1757146705.011 [Radio_Surface](INFO): Powering down 2025-09-06T08:18:49.531Z,1757146729.531 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T08:18:50.535Z,1757146730.535 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T08:19:53.006Z,1757146793.006 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T08:19:53.007Z,1757146793.007 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T08:19:53.007Z,1757146793.007 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T08:19:53.007Z,1757146793.007 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T08:19:53.409Z,1757146793.409 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T08:19:53.409Z,1757146793.409 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T08:19:53.409Z,1757146793.409 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T08:19:53.409Z,1757146793.409 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T08:19:53.409Z,1757146793.409 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T08:19:53.409Z,1757146793.409 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T08:19:53.823Z,1757146793.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T08:19:53.823Z,1757146793.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T08:19:53.823Z,1757146793.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T08:19:53.823Z,1757146793.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T08:19:53.824Z,1757146793.824 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T08:19:53.825Z,1757146793.825 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T08:19:54.239Z,1757146794.239 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T08:20:05.847Z,1757146805.847 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739532 2025-09-06T08:22:54.406Z,1757146974.406 [DVL_micro](ERROR): Failed to parse: :RD,+0106.27,+0110.87,+9999.99,+0121.64 2025-09-06T08:40:51.455Z,1757148051.455 [DVL_micro](ERROR): Failed to parse: :WI,-00136,-00117,+00017,+00000,A 2025-09-06T08:41:29.428Z,1757148089.428 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,+0094.90.43 2025-09-06T08:41:29.428Z,1757148089.428 [DVL_micro](ERROR): Failed to parse: :RD,+0094.90.43 2025-09-06T08:47:35.095Z,1757148455.095 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-09-06T08:48:54.704Z,1757148534.704 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T08:48:54.704Z,1757148534.704 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T08:48:54.705Z,1757148534.705 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T08:48:54.706Z,1757148534.706 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-06T08:48:54.709Z,1757148534.709 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T08:53:59.696Z,1757148839.696 [DVL_micro](ERROR): Failed to parse: :BI,-00044,+00118,+00005,+00000,A 2025-09-06T08:59:16.448Z,1757149156.448 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0073.40,000.00 2025-09-06T08:59:18.467Z,1757149158.467 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0073.60,000.00 2025-09-06T08:59:20.519Z,1757149160.519 [DVL_micro](ERROR): Failed to parse: :RD,+0079.93,+0085.55,+9999.99,+0092.27 2025-09-06T09:09:32.967Z,1757149772.967 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T09:15:49.386Z,1757150149.386 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T09:15:49.386Z,1757150149.386 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T09:15:49.386Z,1757150149.386 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T09:15:49.387Z,1757150149.387 [keepstation:SurfaceComms:A] Stopped 2025-09-06T09:15:49.387Z,1757150149.387 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T09:15:49.387Z,1757150149.387 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T09:15:49.389Z,1757150149.389 [keepstation:NeedComms] Running Loop=1 2025-09-06T09:15:49.389Z,1757150149.389 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T09:15:49.389Z,1757150149.389 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T09:15:49.389Z,1757150149.389 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T09:15:49.389Z,1757150149.389 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T09:15:49.390Z,1757150149.390 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T09:15:49.390Z,1757150149.390 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T09:15:49.391Z,1757150149.391 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T09:15:49.391Z,1757150149.391 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T09:15:49.391Z,1757150149.391 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T09:15:49.391Z,1757150149.391 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T09:15:49.393Z,1757150149.393 [keepstation:NeedComms:A](INFO): last time_fix was: 1757146548.000000 second since 1970/01/01T00:00:00Z 2025-09-06T09:15:49.393Z,1757150149.393 [keepstation:NeedComms:A] Stopped 2025-09-06T09:15:49.794Z,1757150149.794 [keepstation:KeepStation:Dive] Stopped 2025-09-06T09:15:49.794Z,1757150149.794 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T09:15:49.794Z,1757150149.794 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T09:15:49.794Z,1757150149.794 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T09:15:49.795Z,1757150149.795 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T09:15:49.795Z,1757150149.795 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T09:15:49.795Z,1757150149.795 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T09:15:49.795Z,1757150149.795 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T09:15:49.795Z,1757150149.795 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T09:15:49.795Z,1757150149.795 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T09:17:11.388Z,1757150231.388 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T09:17:12.075Z,1757150232.075 [Radio_Surface](INFO): Powering up 2025-09-06T09:17:18.027Z,1757150238.027 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T09:17:48.147Z,1757150268.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T09:18:19.271Z,1757150299.271 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T09:18:28.544Z,1757150308.544 [NAL9602](INFO): SBD MO Status=2, MOMSN=22996, MT Status=2, MTMSN=0 2025-09-06T09:18:28.545Z,1757150308.545 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T09:18:50.396Z,1757150330.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T09:19:18.645Z,1757150358.645 [NAL9602](INFO): SBD MO Status=2, MOMSN=22996, MT Status=2, MTMSN=0 2025-09-06T09:19:18.645Z,1757150358.645 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T09:19:21.527Z,1757150361.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T09:19:40.228Z,1757150380.228 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003285 2025-09-06T09:20:52.779Z,1757150452.779 [NAL9602](INFO): SBD MO Status=1, MOMSN=22996, MT Status=0, MTMSN=0 2025-09-06T09:20:52.780Z,1757150452.780 [NAL9602](INFO): No messages in MT queue 2025-09-06T09:20:54.009Z,1757150454.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092053.00,A,3645.88014,N,12152.89566,W,0.447,323.38,060925,,,D*72 2025-09-06T09:20:54.012Z,1757150454.012 [NAL9602](INFO): GPS fix at 20250906T092053: (36.764669, -121.881594) 2025-09-06T09:20:54.024Z,1757150454.024 [UniversalFixResidualReporter](INFO): Fix residual: 9.9 %DT, over the last 689.3 m. Residual distance 68.5 m at bearing -104.8 degrees. Fix at (36.7647, -121.8816) with 33.6 m made good. 2025-09-06T09:20:54.027Z,1757150454.027 [keepstation:NeedComms:C] Stopped 2025-09-06T09:20:54.028Z,1757150454.028 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T09:21:01.680Z,1757150461.680 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250906T033053/Courier0018.lzma 2025-09-06T09:21:12.720Z,1757150472.720 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0018.lzma.bak 2025-09-06T09:21:12.720Z,1757150472.720 [DataOverHttps](INFO): SBD MOMSN=25936960 2025-09-06T09:21:26.712Z,1757150486.712 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T09:21:39.043Z,1757150499.043 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20250906T033053/Express0019.lzma 2025-09-06T09:21:50.088Z,1757150510.088 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0019.lzma.bak 2025-09-06T09:21:50.089Z,1757150510.089 [DataOverHttps](INFO): SBD MOMSN=25936965 2025-09-06T09:21:51.377Z,1757150511.377 [keepstation:NeedComms:D] Stopped 2025-09-06T09:21:51.377Z,1757150511.377 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T09:21:53.374Z,1757150513.374 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092152.00,A,3645.88225,N,12152.89424,W,0.253,337.25,060925,,,D*7F 2025-09-06T09:21:53.376Z,1757150513.376 [NAL9602](INFO): GPS fix at 20250906T092152: (36.764704, -121.881571) 2025-09-06T09:21:53.417Z,1757150513.417 [keepstation:NeedComms:E] Stopped 2025-09-06T09:21:53.422Z,1757150513.422 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T09:21:53.422Z,1757150513.422 [keepstation:NeedComms] Stopped 2025-09-06T09:21:53.423Z,1757150513.423 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T09:21:53.423Z,1757150513.423 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T09:21:53.423Z,1757150513.423 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T09:21:53.872Z,1757150513.872 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T09:21:53.872Z,1757150513.872 [keepstation:SurfaceComms:B] Stopped 2025-09-06T09:21:53.872Z,1757150513.872 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T09:21:53.872Z,1757150513.872 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T09:21:53.872Z,1757150513.872 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T09:21:53.873Z,1757150513.873 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T09:21:54.208Z,1757150514.208 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T09:21:54.208Z,1757150514.208 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T09:21:54.208Z,1757150514.208 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T09:21:54.208Z,1757150514.208 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T09:21:54.208Z,1757150514.208 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T09:21:54.208Z,1757150514.208 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T09:21:54.614Z,1757150514.614 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T09:21:54.618Z,1757150514.618 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T09:21:54.618Z,1757150514.618 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T09:21:54.619Z,1757150514.619 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T09:21:54.619Z,1757150514.619 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T09:21:54.619Z,1757150514.619 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T09:21:55.038Z,1757150515.038 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T09:21:55.038Z,1757150515.038 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T09:21:55.038Z,1757150515.038 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T09:21:55.038Z,1757150515.038 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T09:21:55.042Z,1757150515.042 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T09:21:55.042Z,1757150515.042 [keepstation:SurfaceComms] Stopped 2025-09-06T09:21:55.043Z,1757150515.043 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T09:21:55.423Z,1757150515.423 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T09:21:55.424Z,1757150515.424 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T09:21:55.424Z,1757150515.424 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T09:21:55.424Z,1757150515.424 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T09:21:55.424Z,1757150515.424 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T09:21:55.424Z,1757150515.424 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T09:21:55.424Z,1757150515.424 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T09:21:55.425Z,1757150515.425 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T09:21:55.425Z,1757150515.425 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T09:21:55.425Z,1757150515.425 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T09:21:55.425Z,1757150515.425 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T09:21:55.425Z,1757150515.425 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T09:21:55.425Z,1757150515.425 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T09:21:55.426Z,1757150515.426 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T09:21:55.426Z,1757150515.426 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T09:21:55.426Z,1757150515.426 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T09:21:55.426Z,1757150515.426 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T09:22:10.748Z,1757150530.748 [NAL9602](INFO): SBD MO Status=1, MOMSN=22997, MT Status=0, MTMSN=0 2025-09-06T09:22:10.748Z,1757150530.748 [NAL9602](INFO): No messages in MT queue 2025-09-06T09:22:41.450Z,1757150561.450 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T09:23:53.983Z,1757150633.983 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T09:24:25.124Z,1757150665.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T09:24:25.971Z,1757150665.971 [Radio_Surface](INFO): Powering down 2025-09-06T09:24:26.127Z,1757150666.127 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T09:25:36.800Z,1757150736.800 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T09:25:36.800Z,1757150736.800 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T09:25:36.800Z,1757150736.800 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T09:25:36.800Z,1757150736.800 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T09:25:37.203Z,1757150737.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T09:25:37.203Z,1757150737.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T09:25:37.203Z,1757150737.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T09:25:37.203Z,1757150737.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T09:25:37.203Z,1757150737.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T09:25:37.204Z,1757150737.204 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T09:25:37.617Z,1757150737.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T09:25:37.617Z,1757150737.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T09:25:37.617Z,1757150737.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T09:25:37.617Z,1757150737.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T09:25:37.618Z,1757150737.618 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T09:25:38.017Z,1757150738.017 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T09:25:38.017Z,1757150738.017 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T09:25:38.017Z,1757150738.017 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T09:25:38.017Z,1757150738.017 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T09:25:38.018Z,1757150738.018 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T09:25:38.018Z,1757150738.018 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T09:25:38.018Z,1757150738.018 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T09:26:11.571Z,1757150771.571 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759277 2025-09-06T09:41:44.791Z,1757151704.791 [DVL_micro](ERROR): Failed to parse: :098.93 2025-09-06T09:46:30.012Z,1757151990.012 [DVL_micro](ERROR): Failed to parse: :SA,-01.53,-00.44,169.5 2025-09-06T09:48:02.118Z,1757152082.118 [DVL_micro](ERROR): Failed to parse: :SA,-00.15,-00.48,167.6 2025-09-06T09:55:37.420Z,1757152537.420 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-09-06T09:56:30.344Z,1757152590.344 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T09:56:30.344Z,1757152590.344 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T09:56:30.345Z,1757152590.345 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T09:56:30.346Z,1757152590.346 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-06T09:56:30.349Z,1757152590.349 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T10:08:50.899Z,1757153330.899 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T10:18:46.927Z,1757153926.927 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T10:21:53.589Z,1757154113.589 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T10:21:53.589Z,1757154113.589 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T10:21:53.589Z,1757154113.589 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T10:21:53.590Z,1757154113.590 [keepstation:SurfaceComms:A] Stopped 2025-09-06T10:21:53.590Z,1757154113.590 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T10:21:53.590Z,1757154113.590 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T10:21:53.992Z,1757154113.992 [keepstation:KeepStation:Dive] Stopped 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T10:21:53.993Z,1757154113.993 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T10:21:53.994Z,1757154113.994 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T10:21:53.995Z,1757154113.995 [keepstation:NeedComms] Running Loop=1 2025-09-06T10:21:53.996Z,1757154113.996 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T10:21:53.996Z,1757154113.996 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T10:21:53.996Z,1757154113.996 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T10:21:53.996Z,1757154113.996 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T10:21:53.997Z,1757154113.997 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T10:21:53.997Z,1757154113.997 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T10:21:53.997Z,1757154113.997 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T10:21:53.997Z,1757154113.997 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T10:21:53.998Z,1757154113.998 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T10:21:53.998Z,1757154113.998 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T10:21:53.000Z,1757154114.000 [keepstation:NeedComms:A](INFO): last time_fix was: 1757150512.000000 second since 1970/01/01T00:00:00Z 2025-09-06T10:21:53.000Z,1757154114.000 [keepstation:NeedComms:A] Stopped 2025-09-06T10:23:37.956Z,1757154217.956 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T10:23:38.415Z,1757154218.415 [Radio_Surface](INFO): Powering up 2025-09-06T10:23:43.867Z,1757154223.867 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T10:24:13.987Z,1757154253.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T10:24:45.115Z,1757154285.115 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T10:25:16.239Z,1757154316.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T10:25:47.371Z,1757154347.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T10:26:06.083Z,1757154366.083 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003281 2025-09-06T10:28:42.320Z,1757154522.320 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T10:28:49.189Z,1757154529.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102848.00,A,3645.88226,N,12152.95636,W,0.467,62.15,060925,,,D*4B 2025-09-06T10:28:49.192Z,1757154529.192 [NAL9602](INFO): GPS fix at 20250906T102848: (36.764704, -121.882606) 2025-09-06T10:28:49.204Z,1757154529.204 [UniversalFixResidualReporter](INFO): Fix residual: 15.0 %DT, over the last 753.2 m. Residual distance 113.1 m at bearing -106.6 degrees. Fix at (36.7647, -121.8826) with 92.2 m made good. 2025-09-06T10:28:49.207Z,1757154529.207 [keepstation:NeedComms:C] Stopped 2025-09-06T10:28:49.208Z,1757154529.208 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T10:28:56.834Z,1757154536.834 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20250906T033053/Courier0021.lzma 2025-09-06T10:29:07.876Z,1757154547.876 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0021.lzma.bak 2025-09-06T10:29:07.877Z,1757154547.877 [DataOverHttps](INFO): SBD MOMSN=25937041 2025-09-06T10:29:21.515Z,1757154561.515 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T10:29:34.188Z,1757154574.188 [DataOverHttps](INFO): Sending 534 bytes from file Logs/20250906T033053/Express0022.lzma 2025-09-06T10:29:45.228Z,1757154585.228 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0022.lzma.bak 2025-09-06T10:29:45.228Z,1757154585.228 [DataOverHttps](INFO): SBD MOMSN=25937044 2025-09-06T10:29:46.582Z,1757154586.582 [keepstation:NeedComms:D] Stopped 2025-09-06T10:29:46.583Z,1757154586.583 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T10:29:48.582Z,1757154588.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102947.00,A,3645.88334,N,12152.95452,W,0.156,53.07,060925,,,D*41 2025-09-06T10:29:48.584Z,1757154588.584 [NAL9602](INFO): GPS fix at 20250906T102947: (36.764722, -121.882575) 2025-09-06T10:29:48.634Z,1757154588.634 [keepstation:NeedComms:E] Stopped 2025-09-06T10:29:48.639Z,1757154588.639 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T10:29:48.639Z,1757154588.639 [keepstation:NeedComms] Stopped 2025-09-06T10:29:48.639Z,1757154588.639 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T10:29:48.639Z,1757154588.639 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T10:29:48.640Z,1757154588.640 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T10:29:49.056Z,1757154589.056 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T10:29:49.056Z,1757154589.056 [keepstation:SurfaceComms:B] Stopped 2025-09-06T10:29:49.056Z,1757154589.056 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T10:29:49.056Z,1757154589.056 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T10:29:49.056Z,1757154589.056 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T10:29:49.056Z,1757154589.056 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T10:29:49.471Z,1757154589.471 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T10:29:49.471Z,1757154589.471 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T10:29:49.471Z,1757154589.471 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T10:29:49.471Z,1757154589.471 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T10:29:49.471Z,1757154589.471 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T10:29:49.471Z,1757154589.471 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T10:29:49.865Z,1757154589.865 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T10:29:49.865Z,1757154589.865 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T10:29:49.865Z,1757154589.865 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T10:29:49.865Z,1757154589.865 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T10:29:49.865Z,1757154589.865 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T10:29:49.865Z,1757154589.865 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T10:29:50.231Z,1757154590.231 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T10:29:50.231Z,1757154590.231 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T10:29:50.231Z,1757154590.231 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T10:29:50.231Z,1757154590.231 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T10:29:50.232Z,1757154590.232 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T10:29:50.232Z,1757154590.232 [keepstation:SurfaceComms] Stopped 2025-09-06T10:29:50.232Z,1757154590.232 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T10:29:50.619Z,1757154590.619 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T10:29:50.619Z,1757154590.619 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T10:29:50.619Z,1757154590.619 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T10:29:50.619Z,1757154590.619 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T10:29:50.620Z,1757154590.620 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T10:29:50.621Z,1757154590.621 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T10:29:50.621Z,1757154590.621 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T10:29:50.621Z,1757154590.621 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T10:29:50.621Z,1757154590.621 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T10:30:13.234Z,1757154613.234 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-06T10:30:19.284Z,1757154619.284 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-06T10:30:19.358Z,1757154619.358 [NAL9602](ERROR): received: +CSQ:0 OK997, 0, 0, 0, 0 OK 2025-09-06T10:30:33.424Z,1757154633.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=22998, MT Status=2, MTMSN=0 2025-09-06T10:30:33.424Z,1757154633.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T10:31:00.493Z,1757154660.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=22998, MT Status=2, MTMSN=0 2025-09-06T10:31:00.494Z,1757154660.494 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T10:31:16.679Z,1757154676.679 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-06T10:31:47.803Z,1757154707.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T10:32:05.839Z,1757154725.839 [Radio_Surface](INFO): Powering down 2025-09-06T10:32:18.927Z,1757154738.927 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T10:32:19.931Z,1757154739.931 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T10:33:04.542Z,1757154784.542 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T10:33:04.542Z,1757154784.542 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T10:33:04.542Z,1757154784.542 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T10:33:04.543Z,1757154784.543 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T10:33:04.964Z,1757154784.964 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T10:33:04.964Z,1757154784.964 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T10:33:04.964Z,1757154784.964 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T10:33:04.964Z,1757154784.964 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T10:33:04.964Z,1757154784.964 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T10:33:04.964Z,1757154784.964 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T10:33:05.339Z,1757154785.339 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T10:33:05.339Z,1757154785.339 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T10:33:05.339Z,1757154785.339 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T10:33:05.339Z,1757154785.339 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T10:33:05.340Z,1757154785.340 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T10:33:05.341Z,1757154785.341 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T10:33:05.751Z,1757154785.751 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T10:33:35.245Z,1757154815.245 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.912277 2025-09-06T10:34:50.369Z,1757154890.369 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T10:35:21.070Z,1757154921.070 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T10:45:33.187Z,1757155533.187 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+0096.95,+0105.99,+9 2025-09-06T10:45:33.187Z,1757155533.187 [DVL_micro](ERROR): Failed to parse: :RD,+0096.95,+0105.99,+9 2025-09-06T10:57:12.475Z,1757156232.475 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T10:59:56.186Z,1757156396.186 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-06T11:00:00.746Z,1757156400.746 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.001650 CHAN A1 (24V): 0.018120 CHAN A2 (12V): -0.011668 CHAN A3 (5V): -0.022659 CHAN B0 (3.3V): -0.009668 CHAN B1 (3.15aV): -0.009022 CHAN B2 (3.15bV): -0.008156 CHAN B3 (GND): -0.010312 OPEN: 0.008252 Full Scale: +/- 1 mA 2025-09-06T11:04:04.560Z,1757156644.560 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T11:04:04.561Z,1757156644.561 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T11:04:04.562Z,1757156644.562 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T11:04:04.563Z,1757156644.563 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2025-09-06T11:04:04.565Z,1757156644.565 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T11:08:26.039Z,1757156906.039 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T11:08:26.453Z,1757156906.453 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm. 2025-09-06T11:29:48.754Z,1757158188.754 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T11:29:48.754Z,1757158188.754 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T11:29:48.754Z,1757158188.754 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T11:29:48.755Z,1757158188.755 [keepstation:SurfaceComms:A] Stopped 2025-09-06T11:29:48.755Z,1757158188.755 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T11:29:48.755Z,1757158188.755 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T11:29:49.145Z,1757158189.145 [keepstation:KeepStation:Dive] Stopped 2025-09-06T11:29:49.146Z,1757158189.146 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T11:29:49.146Z,1757158189.146 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T11:29:49.146Z,1757158189.146 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T11:29:49.146Z,1757158189.146 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T11:29:49.147Z,1757158189.147 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T11:29:49.147Z,1757158189.147 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T11:29:49.147Z,1757158189.147 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T11:29:49.147Z,1757158189.147 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T11:29:49.147Z,1757158189.147 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T11:29:49.148Z,1757158189.148 [keepstation:NeedComms] Running Loop=1 2025-09-06T11:29:49.149Z,1757158189.149 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T11:29:49.149Z,1757158189.149 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T11:29:49.149Z,1757158189.149 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T11:29:49.149Z,1757158189.149 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T11:29:49.149Z,1757158189.149 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T11:29:49.150Z,1757158189.150 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T11:29:49.170Z,1757158189.170 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T11:29:49.171Z,1757158189.171 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T11:29:49.171Z,1757158189.171 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T11:29:49.171Z,1757158189.171 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T11:29:49.173Z,1757158189.173 [keepstation:NeedComms:A](INFO): last time_fix was: 1757154587.000000 second since 1970/01/01T00:00:00Z 2025-09-06T11:29:49.173Z,1757158189.173 [keepstation:NeedComms:A] Stopped 2025-09-06T11:31:17.437Z,1757158277.437 [DVL_micro](ERROR): Failed to parse: :BI,-00156,+00762,-00040,+00000,A 2025-09-06T11:31:19.452Z,1757158279.452 [DVL_micro](ERROR): Failed to parse: :RD,+0123.87,+0114.62,+0096.43,+0100.55 2025-09-06T11:31:20.651Z,1757158280.651 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T11:31:21.363Z,1757158281.363 [Radio_Surface](INFO): Powering up 2025-09-06T11:31:21.497Z,1757158281.497 [DVL_micro](ERROR): Failed to parse: :SA,+16.23,-08.33,192.1 2025-09-06T11:31:26.647Z,1757158286.647 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T11:31:53.412Z,1757158313.412 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-06T11:31:53.486Z,1757158313.486 [NAL9602](ERROR): received: +CSQ:0 OK998, 2, 0, 0, 0 OK 2025-09-06T11:33:19.860Z,1757158399.860 [NAL9602](INFO): SBD MO Status=2, MOMSN=22998, MT Status=2, MTMSN=0 2025-09-06T11:33:19.860Z,1757158399.860 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T11:33:32.843Z,1757158412.843 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003248 2025-09-06T11:33:45.698Z,1757158425.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=22998, MT Status=2, MTMSN=0 2025-09-06T11:33:45.698Z,1757158425.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T11:34:06.307Z,1757158446.307 [NAL9602](INFO): SBD MO Status=1, MOMSN=22998, MT Status=0, MTMSN=0 2025-09-06T11:34:06.307Z,1757158446.307 [NAL9602](INFO): No messages in MT queue 2025-09-06T11:34:07.512Z,1757158447.512 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113406.00,A,3645.80910,N,12152.88891,W,0.505,24.56,060925,,,A*41 2025-09-06T11:34:07.515Z,1757158447.515 [NAL9602](INFO): GPS fix at 20250906T113406: (36.763485, -121.881482) 2025-09-06T11:34:07.527Z,1757158447.527 [UniversalFixResidualReporter](INFO): Fix residual: 25.8 %DT, over the last 848.1 m. Residual distance 219.0 m at bearing -64.9 degrees. Fix at (36.7635, -121.8815) with 168.6 m made good. 2025-09-06T11:34:07.530Z,1757158447.530 [keepstation:NeedComms:C] Stopped 2025-09-06T11:34:07.530Z,1757158447.530 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T11:34:15.522Z,1757158455.522 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250906T033053/Courier0024.lzma 2025-09-06T11:34:26.564Z,1757158466.564 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0024.lzma.bak 2025-09-06T11:34:26.564Z,1757158466.564 [DataOverHttps](INFO): SBD MOMSN=25937127 2025-09-06T11:34:39.442Z,1757158479.442 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T11:34:53.941Z,1757158493.941 [DataOverHttps](INFO): Sending 736 bytes from file Logs/20250906T033053/Express0025.lzma 2025-09-06T11:35:04.980Z,1757158504.980 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0025.lzma.bak 2025-09-06T11:35:04.981Z,1757158504.981 [DataOverHttps](INFO): SBD MOMSN=25937132 2025-09-06T11:35:06.146Z,1757158506.146 [keepstation:NeedComms:D] Stopped 2025-09-06T11:35:06.147Z,1757158506.147 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T11:35:08.133Z,1757158508.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113507.00,A,3645.81041,N,12152.88840,W,0.214,340.67,060925,,,A*75 2025-09-06T11:35:08.140Z,1757158508.140 [NAL9602](INFO): GPS fix at 20250906T113507: (36.763507, -121.881473) 2025-09-06T11:35:08.161Z,1757158508.161 [keepstation:NeedComms:E] Stopped 2025-09-06T11:35:08.162Z,1757158508.162 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T11:35:08.162Z,1757158508.162 [keepstation:NeedComms] Stopped 2025-09-06T11:35:08.162Z,1757158508.162 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T11:35:08.162Z,1757158508.162 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T11:35:08.162Z,1757158508.162 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T11:35:08.604Z,1757158508.604 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T11:35:08.604Z,1757158508.604 [keepstation:SurfaceComms:B] Stopped 2025-09-06T11:35:08.604Z,1757158508.604 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T11:35:08.604Z,1757158508.604 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T11:35:08.604Z,1757158508.604 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T11:35:08.604Z,1757158508.604 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T11:35:09.037Z,1757158509.037 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T11:35:09.037Z,1757158509.037 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T11:35:09.037Z,1757158509.037 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T11:35:09.037Z,1757158509.037 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T11:35:09.037Z,1757158509.037 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T11:35:09.037Z,1757158509.037 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T11:35:09.417Z,1757158509.417 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T11:35:09.417Z,1757158509.417 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T11:35:09.417Z,1757158509.417 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T11:35:09.417Z,1757158509.417 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T11:35:09.417Z,1757158509.417 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T11:35:09.418Z,1757158509.418 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T11:35:09.834Z,1757158509.834 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T11:35:09.835Z,1757158509.835 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T11:35:09.835Z,1757158509.835 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T11:35:09.835Z,1757158509.835 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T11:35:09.835Z,1757158509.835 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T11:35:09.835Z,1757158509.835 [keepstation:SurfaceComms] Stopped 2025-09-06T11:35:09.835Z,1757158509.835 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T11:35:10.196Z,1757158510.196 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T11:35:10.196Z,1757158510.196 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T11:35:10.196Z,1757158510.196 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T11:35:10.196Z,1757158510.196 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T11:35:10.196Z,1757158510.196 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T11:35:10.197Z,1757158510.197 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T11:35:10.198Z,1757158510.198 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T11:35:10.203Z,1757158510.203 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T11:35:10.204Z,1757158510.204 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T11:35:10.204Z,1757158510.204 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T11:35:10.204Z,1757158510.204 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T11:35:35.205Z,1757158535.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=22999, MT Status=2, MTMSN=0 2025-09-06T11:35:35.205Z,1757158535.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T11:36:05.086Z,1757158565.086 [NAL9602](INFO): SBD MO Status=2, MOMSN=22999, MT Status=2, MTMSN=0 2025-09-06T11:36:05.086Z,1757158565.086 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T11:36:07.120Z,1757158567.120 [DVL_micro](ERROR): Failed to parse: :SA,+01.53,-00.05,213.4 2025-09-06T11:36:36.455Z,1757158596.455 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T11:37:04.884Z,1757158624.884 [DVL_micro](ERROR): Failed to parse: :SA,+00.94,-01.58,213.7 2025-09-06T11:37:07.579Z,1757158627.579 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T11:37:17.715Z,1757158637.715 [Radio_Surface](INFO): Powering down 2025-09-06T11:37:38.711Z,1757158658.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T11:37:39.715Z,1757158659.715 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T11:38:23.273Z,1757158703.273 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T11:38:23.273Z,1757158703.273 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T11:38:23.273Z,1757158703.273 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T11:38:23.273Z,1757158703.273 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T11:38:23.687Z,1757158703.687 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T11:38:23.687Z,1757158703.687 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T11:38:23.687Z,1757158703.687 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T11:38:23.687Z,1757158703.687 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T11:38:23.687Z,1757158703.687 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T11:38:23.687Z,1757158703.687 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T11:38:24.072Z,1757158704.072 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T11:38:24.072Z,1757158704.072 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T11:38:24.072Z,1757158704.072 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T11:38:24.072Z,1757158704.072 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T11:38:24.073Z,1757158704.073 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T11:38:24.074Z,1757158704.074 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T11:38:24.488Z,1757158704.488 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T11:38:55.015Z,1757158735.015 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.727356 2025-09-06T11:39:12.546Z,1757158752.546 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00357,-00057,-00053,+0000000.00 2025-09-06T11:39:12.546Z,1757158752.546 [DVL_micro](ERROR): Failed to parse: :BI,-00357,-00057,-00053,+0000000.00 2025-09-06T11:40:10.371Z,1757158810.371 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T11:40:41.029Z,1757158841.029 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T11:47:15.358Z,1757159235.358 [DVL_micro](ERROR): Failed to parse: :RD,+0075.43,+0079.55,+0085.24,+0077.93 2025-09-06T12:05:01.916Z,1757160301.916 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T12:05:02.327Z,1757160302.327 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm (1 active estimators). 2025-09-06T12:11:42.906Z,1757160702.906 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T12:11:42.907Z,1757160702.907 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-06T12:11:42.908Z,1757160702.908 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-06T12:11:42.909Z,1757160702.909 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-06T12:11:42.911Z,1757160702.911 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T12:15:40.486Z,1757160940.486 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T12:24:18.421Z,1757161458.421 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-06T12:33:34.339Z,1757162014.339 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-06T12:34:30.056Z,1757162070.056 [DVL_micro](ERROR): Failed to parse: :WI,+00041,-00037,-00004,+00000,A 2025-09-06T12:35:08.470Z,1757162108.470 [keepstation:SurfaceComms] Running Loop=1 2025-09-06T12:35:08.470Z,1757162108.470 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-06T12:35:08.471Z,1757162108.471 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-06T12:35:08.471Z,1757162108.471 [keepstation:SurfaceComms:A] Stopped 2025-09-06T12:35:08.471Z,1757162108.471 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-06T12:35:08.471Z,1757162108.471 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-06T12:35:08.473Z,1757162108.473 [keepstation:NeedComms] Running Loop=1 2025-09-06T12:35:08.473Z,1757162108.473 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-06T12:35:08.473Z,1757162108.473 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-06T12:35:08.473Z,1757162108.473 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-06T12:35:08.474Z,1757162108.474 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-06T12:35:08.474Z,1757162108.474 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-06T12:35:08.474Z,1757162108.474 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-06T12:35:08.475Z,1757162108.475 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-06T12:35:08.475Z,1757162108.475 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-06T12:35:08.476Z,1757162108.476 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-06T12:35:08.476Z,1757162108.476 [keepstation:NeedComms:A] Running Loop=1 2025-09-06T12:35:08.477Z,1757162108.477 [keepstation:NeedComms:A](INFO): last time_fix was: 1757158507.000000 second since 1970/01/01T00:00:00Z 2025-09-06T12:35:08.477Z,1757162108.477 [keepstation:NeedComms:A] Stopped 2025-09-06T12:35:08.864Z,1757162108.864 [keepstation:KeepStation:Dive] Stopped 2025-09-06T12:35:08.864Z,1757162108.864 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-06T12:35:08.864Z,1757162108.864 [keepstation:KeepStation:Dive:A] Stopped 2025-09-06T12:35:08.864Z,1757162108.864 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-06T12:35:08.864Z,1757162108.864 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-06T12:35:08.864Z,1757162108.864 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-06T12:35:08.865Z,1757162108.865 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-06T12:35:08.865Z,1757162108.865 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-06T12:35:08.865Z,1757162108.865 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-06T12:35:08.865Z,1757162108.865 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-06T12:36:47.143Z,1757162207.143 [keepstation:NeedComms:C] Running Loop=1 2025-09-06T12:36:47.615Z,1757162207.615 [Radio_Surface](INFO): Powering up 2025-09-06T12:36:53.519Z,1757162213.519 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-06T12:37:19.905Z,1757162239.905 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-06T12:37:19.986Z,1757162239.986 [NAL9602](ERROR): received: +CSQ:0 OK999, 2, 0, 0, 0 OK 2025-09-06T12:37:23.647Z,1757162243.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:37:54.771Z,1757162274.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:37:58.689Z,1757162278.689 [NAL9602](INFO): SBD MO Status=2, MOMSN=22999, MT Status=2, MTMSN=0 2025-09-06T12:37:58.689Z,1757162278.689 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T12:38:25.895Z,1757162305.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:38:46.357Z,1757162326.357 [NAL9602](INFO): SBD MO Status=2, MOMSN=22999, MT Status=2, MTMSN=0 2025-09-06T12:38:46.357Z,1757162326.357 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T12:38:57.019Z,1757162337.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:39:15.719Z,1757162355.719 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003231 2025-09-06T12:39:26.772Z,1757162366.772 [NAL9602](INFO): SBD MO Status=2, MOMSN=22999, MT Status=2, MTMSN=0 2025-09-06T12:39:26.772Z,1757162366.772 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T12:40:07.174Z,1757162407.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=22999, MT Status=2, MTMSN=0 2025-09-06T12:40:07.175Z,1757162407.175 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T12:40:17.273Z,1757162417.273 [NAL9602](INFO): SBD MO Status=1, MOMSN=22999, MT Status=0, MTMSN=0 2025-09-06T12:40:17.273Z,1757162417.273 [NAL9602](INFO): No messages in MT queue 2025-09-06T12:40:18.473Z,1757162418.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124017.00,A,3645.84154,N,12152.64271,W,0.311,357.29,060925,,,D*72 2025-09-06T12:40:18.475Z,1757162418.475 [NAL9602](INFO): GPS fix at 20250906T124017: (36.764026, -121.877379) 2025-09-06T12:40:18.488Z,1757162418.488 [UniversalFixResidualReporter](INFO): Fix residual: 10.2 %DT, over the last 1432.4 m. Residual distance 145.7 m at bearing -96.1 degrees. Fix at (36.7640, -121.8774) with 369.3 m made good. 2025-09-06T12:40:18.491Z,1757162418.491 [keepstation:NeedComms:C] Stopped 2025-09-06T12:40:18.492Z,1757162418.492 [keepstation:NeedComms:D] Running Loop=1 2025-09-06T12:40:25.884Z,1757162425.884 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20250906T033053/Courier0027.lzma 2025-09-06T12:40:36.925Z,1757162436.925 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Courier0027.lzma.bak 2025-09-06T12:40:36.925Z,1757162436.925 [DataOverHttps](INFO): SBD MOMSN=25937225 2025-09-06T12:40:50.386Z,1757162450.386 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T12:41:03.680Z,1757162463.680 [DataOverHttps](INFO): Sending 658 bytes from file Logs/20250906T033053/Express0028.lzma 2025-09-06T12:41:14.721Z,1757162474.721 [DataOverHttps](INFO): Moved sent file to Logs/20250906T033053/Express0028.lzma.bak 2025-09-06T12:41:14.722Z,1757162474.722 [DataOverHttps](INFO): SBD MOMSN=25937230 2025-09-06T12:41:15.853Z,1757162475.853 [keepstation:NeedComms:D] Stopped 2025-09-06T12:41:15.853Z,1757162475.853 [keepstation:NeedComms:E] Running Loop=1 2025-09-06T12:41:17.868Z,1757162477.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124117.00,A,3645.84478,N,12152.64148,W,0.233,340.02,060925,,,D*7F 2025-09-06T12:41:17.871Z,1757162477.871 [NAL9602](INFO): GPS fix at 20250906T124117: (36.764080, -121.877358) 2025-09-06T12:41:17.889Z,1757162477.889 [keepstation:NeedComms:E] Stopped 2025-09-06T12:41:17.890Z,1757162477.890 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-06T12:41:17.890Z,1757162477.890 [keepstation:NeedComms] Stopped 2025-09-06T12:41:17.890Z,1757162477.890 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-06T12:41:17.890Z,1757162477.890 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-06T12:41:17.890Z,1757162477.890 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-06T12:41:18.307Z,1757162478.307 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-06T12:41:18.307Z,1757162478.307 [keepstation:SurfaceComms:B] Stopped 2025-09-06T12:41:18.307Z,1757162478.307 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-06T12:41:18.307Z,1757162478.307 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-06T12:41:18.308Z,1757162478.308 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-06T12:41:18.308Z,1757162478.308 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-06T12:41:18.717Z,1757162478.717 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-06T12:41:18.717Z,1757162478.717 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-06T12:41:18.718Z,1757162478.718 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-06T12:41:18.718Z,1757162478.718 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-06T12:41:18.718Z,1757162478.718 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-06T12:41:18.718Z,1757162478.718 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-06T12:41:19.181Z,1757162479.181 [keepstation:SurfaceComms:setStation] Stopped 2025-09-06T12:41:19.181Z,1757162479.181 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-06T12:41:19.181Z,1757162479.181 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-06T12:41:19.181Z,1757162479.181 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-06T12:41:19.181Z,1757162479.181 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-06T12:41:19.181Z,1757162479.181 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-06T12:41:19.537Z,1757162479.537 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-06T12:41:19.537Z,1757162479.537 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-06T12:41:19.537Z,1757162479.537 [keepstation:SurfaceComms:setSink] Stopped 2025-09-06T12:41:19.537Z,1757162479.537 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-06T12:41:19.538Z,1757162479.538 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-06T12:41:19.538Z,1757162479.538 [keepstation:SurfaceComms] Stopped 2025-09-06T12:41:19.538Z,1757162479.538 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-06T12:41:19.927Z,1757162479.927 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-06T12:41:19.927Z,1757162479.927 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-06T12:41:19.928Z,1757162479.928 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-06T12:41:19.928Z,1757162479.928 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-06T12:41:19.928Z,1757162479.928 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-06T12:41:19.928Z,1757162479.928 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-06T12:41:19.928Z,1757162479.928 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-06T12:41:19.929Z,1757162479.929 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-06T12:41:19.930Z,1757162479.930 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-06T12:41:19.930Z,1757162479.930 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-06T12:41:19.930Z,1757162479.930 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-06T12:42:47.663Z,1757162567.663 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:43:18.787Z,1757162598.787 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:43:26.451Z,1757162606.451 [Radio_Surface](INFO): Powering down 2025-09-06T12:43:49.913Z,1757162629.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-06T12:43:50.915Z,1757162630.915 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-06T12:44:47.140Z,1757162687.140 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-06T12:44:47.140Z,1757162687.140 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-06T12:44:47.141Z,1757162687.141 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T12:44:47.141Z,1757162687.141 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-06T12:44:47.549Z,1757162687.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-06T12:44:47.549Z,1757162687.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-06T12:44:47.549Z,1757162687.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-06T12:44:47.549Z,1757162687.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-06T12:44:47.550Z,1757162687.550 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T12:44:47.550Z,1757162687.550 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-06T12:44:47.963Z,1757162687.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-06T12:44:47.963Z,1757162687.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T12:44:47.963Z,1757162687.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-06T12:44:47.963Z,1757162687.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-06T12:44:47.964Z,1757162687.964 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-06T12:44:47.965Z,1757162687.965 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-06T12:44:48.360Z,1757162688.360 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-06T12:44:48.360Z,1757162688.360 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-06T12:44:48.360Z,1757162688.360 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-06T12:44:48.360Z,1757162688.360 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-06T12:44:48.360Z,1757162688.360 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-06T12:44:48.361Z,1757162688.361 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-06T12:44:48.361Z,1757162688.361 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-06T12:45:05.215Z,1757162705.215 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.726669 2025-09-06T12:46:20.469Z,1757162780.469 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-06T12:46:51.167Z,1757162811.167 [NAL9602](INFO): Not Powering down - fast GPS