2025-04-20T04:29:36.003Z,1745123376.003 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist
2025-04-20T04:29:36.006Z,1745123376.006 [CommandExec](IMPORTANT): got command restart logs
2025-04-20T04:29:36.626Z,1745123376.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,042934.00,A,3646.89938,N,12151.00580,W,0.564,41.34,200425,,,A*4F
2025-04-20T04:29:36.633Z,1745123376.633 [NAL9602](INFO): GPS fix at 20250420T042934: (36.781656, -121.850097)
2025-04-20T04:29:36.657Z,1745123376.657 [keepstation:NeedComms:C] Stopped
2025-04-20T04:29:36.657Z,1745123376.657 [keepstation:NeedComms:D] Running Loop=1
2025-04-20T04:29:37.055Z,1745123377.055 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-20T04:29:47.192Z,1745123387.192 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250419T153430/Courier0085.lzma
2025-04-20T04:29:48.195Z,1745123388.195 [DataOverHttps](INFO): Moved sent file to Logs/20250419T153430/Courier0085.lzma.bak
2025-04-20T04:29:48.195Z,1745123388.195 [DataOverHttps](INFO): SBD MOMSN=24693753
2025-04-20T04:30:05.021Z,1745123405.021 [DataOverHttps](INFO): Sending 419 bytes from file Logs/20250419T153430/Express0080.lzma
2025-04-20T04:30:06.023Z,1745123406.023 [DataOverHttps](INFO): Moved sent file to Logs/20250419T153430/Express0080.lzma.bak
2025-04-20T04:30:06.023Z,1745123406.023 [DataOverHttps](INFO): SBD MOMSN=24693755
2025-04-20T04:30:08.538Z,1745123408.538 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T04:30:22.213Z,1745123422.213 [DataOverHttps](INFO): Sending 421 bytes from file Logs/20250419T153430/Express0083.lzma
2025-04-20T04:30:23.215Z,1745123423.215 [DataOverHttps](INFO): Moved sent file to Logs/20250419T153430/Express0083.lzma.bak
2025-04-20T04:30:23.215Z,1745123423.215 [DataOverHttps](INFO): SBD MOMSN=24693765
2025-04-20T04:30:40.869Z,1745123440.869 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20250419T153430/Express0087.lzma
2025-04-20T04:30:41.871Z,1745123441.871 [DataOverHttps](INFO): Moved sent file to Logs/20250419T153430/Express0087.lzma.bak
2025-04-20T04:30:41.871Z,1745123441.871 [DataOverHttps](INFO): SBD MOMSN=24693774
2025-04-20T04:30:43.703Z,1745123443.703 [keepstation:NeedComms:D] Stopped
2025-04-20T04:30:43.703Z,1745123443.703 [keepstation:NeedComms:E] Running Loop=1
2025-04-20T04:30:44.097Z,1745123444.097 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-20T04:30:45.725Z,1745123445.725 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,043043.00,A,3646.90617,N,12150.99465,W,0.875,33.24,200425,,,D*4A
2025-04-20T04:30:45.727Z,1745123445.727 [NAL9602](INFO): GPS fix at 20250420T043043: (36.781770, -121.849911)
2025-04-20T04:30:45.772Z,1745123445.772 [keepstation:NeedComms:E] Stopped
2025-04-20T04:30:45.777Z,1745123445.777 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-20T04:30:45.777Z,1745123445.777 [keepstation:NeedComms] Stopped
2025-04-20T04:30:45.777Z,1745123445.777 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-20T04:30:45.777Z,1745123445.777 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-20T04:30:45.777Z,1745123445.777 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-20T04:30:46.173Z,1745123446.173 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-20T04:30:46.173Z,1745123446.173 [keepstation:SurfaceComms:B] Stopped
2025-04-20T04:30:46.173Z,1745123446.173 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-20T04:30:46.173Z,1745123446.173 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-20T04:30:46.174Z,1745123446.174 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-20T04:30:46.174Z,1745123446.174 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-20T04:30:46.548Z,1745123446.548 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-20T04:30:46.548Z,1745123446.548 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-20T04:30:46.548Z,1745123446.548 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-20T04:30:46.548Z,1745123446.548 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-20T04:30:46.553Z,1745123446.553 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-20T04:30:46.553Z,1745123446.553 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-20T04:30:46.965Z,1745123446.965 [keepstation:SurfaceComms:setStation] Stopped
2025-04-20T04:30:46.966Z,1745123446.966 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-20T04:30:46.966Z,1745123446.966 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-20T04:30:46.966Z,1745123446.966 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-20T04:30:46.966Z,1745123446.966 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-20T04:30:46.966Z,1745123446.966 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-20T04:30:47.378Z,1745123447.378 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-20T04:30:47.378Z,1745123447.378 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-20T04:30:47.378Z,1745123447.378 [keepstation:SurfaceComms:setSink] Stopped
2025-04-20T04:30:47.378Z,1745123447.378 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-20T04:30:47.378Z,1745123447.378 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-20T04:30:47.379Z,1745123447.379 [keepstation:SurfaceComms] Stopped
2025-04-20T04:30:47.379Z,1745123447.379 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-20T04:30:47.753Z,1745123447.753 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-04-20T04:30:47.753Z,1745123447.753 [keepstation:StartingMission] Stopped
2025-04-20T04:30:47.753Z,1745123447.753 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation] Running Loop=1
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-04-20T04:30:47.754Z,1745123447.754 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-04-20T04:30:48.174Z,1745123448.174 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-20T04:30:48.174Z,1745123448.174 [keepstation:DiveCmd] Running Loop=1
2025-04-20T04:30:48.174Z,1745123448.174 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-04-20T04:30:48.174Z,1745123448.174 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-20T04:30:48.175Z,1745123448.175 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-20T04:30:48.176Z,1745123448.176 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-20T04:30:48.180Z,1745123448.180 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T04:30:48.181Z,1745123448.181 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-20T04:30:48.182Z,1745123448.182 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-20T04:30:48.182Z,1745123448.182 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-20T04:30:48.182Z,1745123448.182 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-20T04:30:48.182Z,1745123448.182 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-20T04:30:48.182Z,1745123448.182 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-20T04:30:48.182Z,1745123448.182 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-20T04:30:48.183Z,1745123448.183 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-20T04:30:48.184Z,1745123448.184 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-20T04:30:48.184Z,1745123448.184 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-20T04:30:48.184Z,1745123448.184 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-20T04:30:48.184Z,1745123448.184 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-20T04:30:48.184Z,1745123448.184 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-20T04:30:48.213Z,1745123448.213 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-20T04:31:05.512Z,1745123465.512 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20167, MT Status=1, MTMSN=4297
2025-04-20T04:31:05.512Z,1745123465.512 [NAL9602](INFO): Data available in MT queue
2025-04-20T04:31:06.205Z,1745123466.205 [NAL9602](INFO): Received command: sched 20250420T13 "load Science/profile_station.tl;set profile_station.MissionTimeout 15 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree" 51t81 1 3
2025-04-20T04:31:06.252Z,1745123466.252 [CommandExec](IMPORTANT): got command schedule 20250420T130000 "load Science/profile_station.tl;set profile_station.MissionTimeout 15 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree" 51t81 1 3.000000
2025-04-20T04:31:06.252Z,1745123466.252 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=51t81
2025-04-20T04:31:06.254Z,1745123466.254 [CommandExec](IMPORTANT): Scheduled #13 (#1 of 3 with id='51t81'): "load Science/profile_station.tl;set profile_station.MissionTimeout 15 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree", AT TIME: 2025-04-20T13:00:00Z
2025-04-20T04:31:36.606Z,1745123496.606 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T04:32:15.617Z,1745123535.617 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-04-20T04:32:46.741Z,1745123566.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:33:08.518Z,1745123588.518 [Radio_Surface](INFO): Powering down
2025-04-20T04:33:17.218Z,1745123597.218 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-20T04:33:17.218Z,1745123597.218 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-20T04:33:17.219Z,1745123597.219 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T04:33:17.219Z,1745123597.219 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-20T04:33:17.626Z,1745123597.626 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-20T04:33:17.626Z,1745123597.626 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T04:33:17.627Z,1745123597.627 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-20T04:33:17.627Z,1745123597.627 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T04:33:17.627Z,1745123597.627 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-20T04:33:17.627Z,1745123597.627 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T04:33:17.627Z,1745123597.627 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-20T04:33:17.869Z,1745123597.869 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-20T04:33:18.041Z,1745123598.041 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-20T04:33:18.042Z,1745123598.042 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-20T04:33:18.042Z,1745123598.042 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-20T04:33:18.042Z,1745123598.042 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-20T04:33:18.042Z,1745123598.042 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-20T04:33:18.042Z,1745123598.042 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T04:33:18.043Z,1745123598.043 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-20T04:33:18.044Z,1745123598.044 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-20T04:33:18.044Z,1745123598.044 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-20T04:33:18.044Z,1745123598.044 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T04:33:18.044Z,1745123598.044 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-20T04:33:18.873Z,1745123598.873 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-20T04:33:23.681Z,1745123603.681 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-04-20T04:33:23.681Z,1745123603.681 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-04-20T04:33:23.681Z,1745123603.681 [keepstation:TransitToStation:C] Running Loop=1
2025-04-20T04:33:24.090Z,1745123604.090 [keepstation:TransitToStation:C] Stopped
2025-04-20T04:33:24.090Z,1745123604.090 [keepstation:TransitToStation:D] Running Loop=1
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T04:33:24.091Z,1745123604.091 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-20T04:33:24.092Z,1745123604.092 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T04:33:24.092Z,1745123604.092 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T04:33:24.093Z,1745123604.093 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T04:33:24.093Z,1745123604.093 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T04:33:24.093Z,1745123604.093 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-20T04:33:24.093Z,1745123604.093 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-20T04:33:24.094Z,1745123604.094 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-20T04:33:24.488Z,1745123604.488 [keepstation:TransitToStation:D] Stopped
2025-04-20T04:33:24.488Z,1745123604.488 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-04-20T04:33:24.488Z,1745123604.488 [keepstation:TransitToStation] Stopped
2025-04-20T04:33:24.488Z,1745123604.488 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-04-20T04:33:24.488Z,1745123604.488 [keepstation:TransitToStation:Dive] Stopped
2025-04-20T04:33:24.488Z,1745123604.488 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-04-20T04:33:24.505Z,1745123604.505 [keepstation:TransitToStation:Dive:A] Stopped
2025-04-20T04:33:24.505Z,1745123604.505 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-04-20T04:33:24.505Z,1745123604.505 [keepstation:OnStation] Running Loop=1
2025-04-20T04:33:24.505Z,1745123604.505 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-04-20T04:33:24.889Z,1745123604.889 [keepstation:NeedComms] Running Loop=1
2025-04-20T04:33:24.890Z,1745123604.890 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-20T04:33:24.890Z,1745123604.890 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-20T04:33:24.890Z,1745123604.890 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-20T04:33:24.890Z,1745123604.890 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-20T04:33:24.891Z,1745123604.891 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-20T04:33:24.891Z,1745123604.891 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-20T04:33:24.891Z,1745123604.891 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-20T04:33:24.892Z,1745123604.892 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-20T04:33:24.892Z,1745123604.892 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-20T04:33:24.892Z,1745123604.892 [keepstation:NeedComms:A] Running Loop=1
2025-04-20T04:33:24.894Z,1745123604.894 [keepstation:NeedComms:A](INFO): last time_fix was:  1745123443.000000 second since 1970/01/01T00:00:00Z 
2025-04-20T04:33:24.894Z,1745123604.894 [keepstation:NeedComms:A] Stopped
2025-04-20T04:34:08.126Z,1745123648.126 [keepstation:NeedComms:C] Running Loop=1
2025-04-20T04:34:08.713Z,1745123648.713 [Radio_Surface](INFO): Powering up
2025-04-20T04:34:14.097Z,1745123654.097 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-20T04:34:22.129Z,1745123662.129 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.391388
2025-04-20T04:34:43.669Z,1745123683.669 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20168, MT Status=1, MTMSN=4298
2025-04-20T04:34:43.669Z,1745123683.669 [NAL9602](INFO): Data available in MT queue
2025-04-20T04:34:44.397Z,1745123684.397 [NAL9602](INFO): Received command: sched 20250420T13 "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 40 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed 1 m/s;set profile_station.SinkOffSurface 1 bool" 51t81 2 3
2025-04-20T04:34:44.503Z,1745123684.503 [CommandExec](IMPORTANT): got command schedule 20250420T130000 "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 40 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed 1 m/s;set profile_station.SinkOffSurface 1 bool" 51t81 2 3.000000
2025-04-20T04:34:44.503Z,1745123684.503 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=51t81
2025-04-20T04:34:44.504Z,1745123684.504 [CommandExec](IMPORTANT): Scheduled #14 (#2 of 3 with id='51t81'): "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 40 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed 1 m/s;set profile_station.SinkOffSurface 1 bool", AT TIME: 2025-04-20T13:00:00Z
2025-04-20T04:34:45.292Z,1745123685.292 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,043443.00,A,3646.94625,N,12150.97683,W,0.350,17.72,200425,,,A*43
2025-04-20T04:34:45.294Z,1745123685.294 [NAL9602](INFO): GPS fix at 20250420T043443: (36.782438, -121.849614)
2025-04-20T04:34:45.308Z,1745123685.308 [keepstation:NeedComms:C] Stopped
2025-04-20T04:34:45.308Z,1745123685.308 [keepstation:NeedComms:D] Running Loop=1
2025-04-20T04:35:06.806Z,1745123706.806 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20169, MT Status=1, MTMSN=4299
2025-04-20T04:35:06.857Z,1745123706.857 [NAL9602](INFO): Sent 271 bytes from file Logs/20250420T042936/Courier0003.lzma
2025-04-20T04:35:06.857Z,1745123706.857 [NAL9602](INFO): Packets left to send: 0
2025-04-20T04:35:07.449Z,1745123707.449 [NAL9602](INFO): Received command: sched 20250420T13 "set profile_station.SinkDepth 5 m;set profile_station.MaxDepth 55 m;set profile_station:NeedComms.CommsTimeout 10 min;run" 51t81 3 3
2025-04-20T04:35:07.534Z,1745123707.534 [CommandExec](IMPORTANT): got command schedule 20250420T130000 "set profile_station.SinkDepth 5 m;set profile_station.MaxDepth 55 m;set profile_station:NeedComms.CommsTimeout 10 min;run" 51t81 3 3.000000
2025-04-20T04:35:07.535Z,1745123707.535 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=51t81
2025-04-20T04:35:07.536Z,1745123707.536 [CommandExec](IMPORTANT): Scheduled #15 (#3 of 3 with id='51t81'): "set profile_station.SinkDepth 5 m;set profile_station.MaxDepth 55 m;set profile_station:NeedComms.CommsTimeout 10 min;run", AT TIME: 2025-04-20T13:00:00Z
2025-04-20T04:35:14.341Z,1745123714.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:35:19.716Z,1745123719.716 [NAL9602](INFO): SBD MO Status=1, MOMSN=20170, MT Status=0, MTMSN=0
2025-04-20T04:35:19.767Z,1745123719.767 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T042936/Express0004.lzma
2025-04-20T04:35:19.767Z,1745123719.767 [NAL9602](INFO): Packets left to send: 3
2025-04-20T04:35:34.179Z,1745123734.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=20171, MT Status=0, MTMSN=0
2025-04-20T04:35:34.237Z,1745123734.237 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T042936/Express0004.lzma
2025-04-20T04:35:34.237Z,1745123734.237 [NAL9602](INFO): Packets left to send: 2
2025-04-20T04:35:43.800Z,1745123743.800 [NAL9602](INFO): SBD MO Status=1, MOMSN=20172, MT Status=0, MTMSN=0
2025-04-20T04:35:43.857Z,1745123743.857 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T042936/Express0004.lzma
2025-04-20T04:35:43.857Z,1745123743.857 [NAL9602](INFO): Packets left to send: 1
2025-04-20T04:35:45.481Z,1745123745.481 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:35:51.405Z,1745123751.405 [NAL9602](INFO): SBD MO Status=1, MOMSN=20173, MT Status=0, MTMSN=0
2025-04-20T04:35:51.461Z,1745123751.461 [NAL9602](INFO): Sent 9 bytes from file Logs/20250420T042936/Express0004.lzma
2025-04-20T04:35:51.461Z,1745123751.461 [NAL9602](INFO): Packets left to send: 0
2025-04-20T04:36:00.451Z,1745123760.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=20174, MT Status=0, MTMSN=0
2025-04-20T04:36:11.678Z,1745123771.678 [NAL9602](INFO): SBD MO Status=1, MOMSN=20175, MT Status=0, MTMSN=0
2025-04-20T04:36:11.729Z,1745123771.729 [NAL9602](INFO): Sent 40 bytes from file Logs/20250420T042936/Courier0006.lzma
2025-04-20T04:36:11.729Z,1745123771.729 [NAL9602](INFO): Packets left to send: 0
2025-04-20T04:36:16.605Z,1745123776.605 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:36:25.217Z,1745123785.217 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003256
2025-04-20T04:36:29.836Z,1745123789.836 [NAL9602](INFO): SBD MO Status=1, MOMSN=20176, MT Status=0, MTMSN=0
2025-04-20T04:36:29.887Z,1745123789.887 [NAL9602](INFO): Sent 331 bytes from file Logs/20250420T042936/Express0007.lzma
2025-04-20T04:36:29.887Z,1745123789.887 [NAL9602](INFO): Packets left to send: 0
2025-04-20T04:36:32.692Z,1745123792.692 [keepstation:NeedComms:D] Stopped
2025-04-20T04:36:32.692Z,1745123792.692 [keepstation:NeedComms:E] Running Loop=1
2025-04-20T04:36:34.751Z,1745123794.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,043632.00,A,3646.95773,N,12150.96632,W,0.389,48.91,200425,,,A*4C
2025-04-20T04:36:34.755Z,1745123794.755 [NAL9602](INFO): GPS fix at 20250420T043632: (36.782629, -121.849439)
2025-04-20T04:36:34.814Z,1745123794.814 [keepstation:NeedComms:E] Stopped
2025-04-20T04:36:34.815Z,1745123794.815 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-20T04:36:34.815Z,1745123794.815 [keepstation:NeedComms] Stopped
2025-04-20T04:36:34.815Z,1745123794.815 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-20T04:36:34.815Z,1745123794.815 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-20T04:36:34.815Z,1745123794.815 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:OnStation] Stopped
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:KeepStation] Running Loop=1
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-20T04:36:35.134Z,1745123795.134 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-20T04:36:35.135Z,1745123795.135 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-20T04:36:35.135Z,1745123795.135 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-04-20T04:36:35.135Z,1745123795.135 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-04-20T04:36:35.549Z,1745123795.549 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-20T04:37:06.630Z,1745123826.630 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T04:38:04.961Z,1745123884.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:38:36.085Z,1745123916.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:38:41.865Z,1745123921.865 [Radio_Surface](INFO): Powering down
2025-04-20T04:39:07.225Z,1745123947.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T04:39:08.229Z,1745123948.229 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-20T04:40:13.510Z,1745124013.510 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.764175
2025-04-20T05:13:39.922Z,1745126019.922 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T05:13:39.923Z,1745126019.923 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-20T05:13:39.942Z,1745126019.942 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T06:21:14.496Z,1745130074.496 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T06:21:14.497Z,1745130074.497 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-20T06:21:14.500Z,1745130074.500 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T06:36:34.807Z,1745130994.807 [keepstation:SurfaceComms] Running Loop=1
2025-04-20T06:36:34.807Z,1745130994.807 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-20T06:36:34.807Z,1745130994.807 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-20T06:36:34.807Z,1745130994.807 [keepstation:SurfaceComms:A] Stopped
2025-04-20T06:36:34.807Z,1745130994.807 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-20T06:36:34.808Z,1745130994.808 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-20T06:36:34.809Z,1745130994.809 [keepstation:NeedComms] Running Loop=1
2025-04-20T06:36:34.809Z,1745130994.809 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-20T06:36:34.810Z,1745130994.810 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-20T06:36:34.810Z,1745130994.810 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-20T06:36:34.810Z,1745130994.810 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-20T06:36:34.810Z,1745130994.810 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-20T06:36:34.811Z,1745130994.811 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-20T06:36:34.811Z,1745130994.811 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-20T06:36:34.811Z,1745130994.811 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-20T06:36:34.812Z,1745130994.812 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-20T06:36:34.812Z,1745130994.812 [keepstation:NeedComms:A] Running Loop=1
2025-04-20T06:36:34.814Z,1745130994.814 [keepstation:NeedComms:A](INFO): last time_fix was:  1745123792.000000 second since 1970/01/01T00:00:00Z 
2025-04-20T06:36:34.814Z,1745130994.814 [keepstation:NeedComms:A] Stopped
2025-04-20T06:36:35.222Z,1745130995.222 [keepstation:KeepStation:Dive] Stopped
2025-04-20T06:36:35.222Z,1745130995.222 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-20T06:36:35.222Z,1745130995.222 [keepstation:KeepStation:Dive:A] Stopped
2025-04-20T06:36:35.222Z,1745130995.222 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-20T06:36:35.223Z,1745130995.223 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-20T06:36:35.223Z,1745130995.223 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-20T06:36:35.223Z,1745130995.223 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-20T06:36:35.223Z,1745130995.223 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-20T06:36:35.223Z,1745130995.223 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-20T06:36:35.223Z,1745130995.223 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-20T06:37:58.853Z,1745131078.853 [keepstation:NeedComms:C] Running Loop=1
2025-04-20T06:37:59.537Z,1745131079.537 [Radio_Surface](INFO): Powering up
2025-04-20T06:38:04.833Z,1745131084.833 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-20T06:38:34.954Z,1745131114.954 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:38:36.807Z,1745131116.807 [NAL9602](INFO): SBD MO Status=0, MOMSN=20177, MT Status=0, MTMSN=0
2025-04-20T06:38:36.808Z,1745131116.808 [NAL9602](INFO): No messages in MT queue
2025-04-20T06:38:38.014Z,1745131118.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063836.00,A,3647.34218,N,12150.28167,W,1.263,48.53,200425,,,A*4E
2025-04-20T06:38:38.017Z,1745131118.017 [NAL9602](INFO): GPS fix at 20250420T063836: (36.789036, -121.838028)
2025-04-20T06:38:38.030Z,1745131118.030 [keepstation:NeedComms:C] Stopped
2025-04-20T06:38:38.030Z,1745131118.030 [keepstation:NeedComms:D] Running Loop=1
2025-04-20T06:38:56.289Z,1745131136.289 [NAL9602](INFO): SBD MO Status=1, MOMSN=20178, MT Status=0, MTMSN=0
2025-04-20T06:38:56.338Z,1745131136.338 [NAL9602](INFO): Sent 205 bytes from file Logs/20250420T042936/Courier0009.lzma
2025-04-20T06:38:56.338Z,1745131136.338 [NAL9602](INFO): Packets left to send: 0
2025-04-20T06:39:06.077Z,1745131146.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:39:09.967Z,1745131149.967 [NAL9602](INFO): SBD MO Status=1, MOMSN=20179, MT Status=0, MTMSN=0
2025-04-20T06:39:10.017Z,1745131150.017 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T042936/Express0010.lzma
2025-04-20T06:39:10.017Z,1745131150.017 [NAL9602](INFO): Packets left to send: 1
2025-04-20T06:39:21.034Z,1745131161.034 [NAL9602](INFO): SBD MO Status=1, MOMSN=20180, MT Status=0, MTMSN=0
2025-04-20T06:39:21.086Z,1745131161.086 [NAL9602](INFO): Sent 229 bytes from file Logs/20250420T042936/Express0010.lzma
2025-04-20T06:39:21.086Z,1745131161.086 [NAL9602](INFO): Packets left to send: 0
2025-04-20T06:39:29.672Z,1745131169.672 [NAL9602](INFO): SBD MO Status=0, MOMSN=20181, MT Status=0, MTMSN=0
2025-04-20T06:39:29.763Z,1745131169.763 [keepstation:NeedComms:D] Stopped
2025-04-20T06:39:29.764Z,1745131169.764 [keepstation:NeedComms:E] Running Loop=1
2025-04-20T06:39:32.090Z,1745131172.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063930.00,A,3647.34420,N,12150.27126,W,0.505,93.23,200425,,,D*4C
2025-04-20T06:39:32.093Z,1745131172.093 [NAL9602](INFO): GPS fix at 20250420T063930: (36.789070, -121.837854)
2025-04-20T06:39:32.105Z,1745131172.105 [keepstation:NeedComms:E] Stopped
2025-04-20T06:39:32.106Z,1745131172.106 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-20T06:39:32.106Z,1745131172.106 [keepstation:NeedComms] Stopped
2025-04-20T06:39:32.106Z,1745131172.106 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-20T06:39:32.106Z,1745131172.106 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-20T06:39:32.107Z,1745131172.107 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-20T06:39:32.526Z,1745131172.526 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-20T06:39:32.526Z,1745131172.526 [keepstation:SurfaceComms:B] Stopped
2025-04-20T06:39:32.526Z,1745131172.526 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-20T06:39:32.526Z,1745131172.526 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-20T06:39:32.526Z,1745131172.526 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-20T06:39:32.526Z,1745131172.526 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-20T06:39:32.915Z,1745131172.915 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-20T06:39:32.915Z,1745131172.915 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-20T06:39:32.915Z,1745131172.915 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-20T06:39:32.915Z,1745131172.915 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-20T06:39:32.915Z,1745131172.915 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-20T06:39:32.915Z,1745131172.915 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-20T06:39:33.334Z,1745131173.334 [keepstation:SurfaceComms:setStation] Stopped
2025-04-20T06:39:33.334Z,1745131173.334 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-20T06:39:33.334Z,1745131173.334 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-20T06:39:33.334Z,1745131173.334 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-20T06:39:33.334Z,1745131173.334 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-20T06:39:33.334Z,1745131173.334 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-20T06:39:33.716Z,1745131173.716 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-20T06:39:33.716Z,1745131173.716 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-20T06:39:33.716Z,1745131173.716 [keepstation:SurfaceComms:setSink] Stopped
2025-04-20T06:39:33.716Z,1745131173.716 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-20T06:39:33.716Z,1745131173.716 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-20T06:39:33.716Z,1745131173.716 [keepstation:SurfaceComms] Stopped
2025-04-20T06:39:33.717Z,1745131173.717 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-20T06:39:34.133Z,1745131174.133 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-20T06:39:34.134Z,1745131174.134 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-20T06:39:34.134Z,1745131174.134 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-20T06:39:34.134Z,1745131174.134 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-20T06:39:34.134Z,1745131174.134 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-20T06:39:34.134Z,1745131174.134 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-20T06:39:34.135Z,1745131174.135 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-20T06:39:34.136Z,1745131174.136 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-20T06:39:34.136Z,1745131174.136 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-20T06:39:34.136Z,1745131174.136 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-20T06:39:34.136Z,1745131174.136 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-20T06:39:37.201Z,1745131177.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:40:04.818Z,1745131204.818 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T06:40:08.325Z,1745131208.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:40:17.062Z,1745131217.062 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004087
2025-04-20T06:41:10.273Z,1745131270.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:41:41.397Z,1745131301.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:41:46.989Z,1745131306.989 [Radio_Surface](INFO): Powering down
2025-04-20T06:41:53.113Z,1745131313.113 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-20T06:41:53.113Z,1745131313.113 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-20T06:41:53.114Z,1745131313.114 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T06:41:53.114Z,1745131313.114 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-20T06:41:53.514Z,1745131313.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-20T06:41:53.514Z,1745131313.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T06:41:53.514Z,1745131313.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-20T06:41:53.514Z,1745131313.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-20T06:41:53.514Z,1745131313.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T06:41:53.514Z,1745131313.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-20T06:41:53.922Z,1745131313.922 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-20T06:41:53.922Z,1745131313.922 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T06:41:53.922Z,1745131313.922 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-20T06:41:53.922Z,1745131313.922 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-20T06:41:53.923Z,1745131313.923 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-20T06:41:54.333Z,1745131314.333 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T06:41:54.333Z,1745131314.333 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-20T06:41:54.334Z,1745131314.334 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T06:41:54.334Z,1745131314.334 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T06:41:54.334Z,1745131314.334 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-20T06:41:54.334Z,1745131314.334 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T06:41:54.334Z,1745131314.334 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-20T06:42:12.522Z,1745131332.522 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T06:42:13.525Z,1745131333.525 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-20T06:43:17.797Z,1745131397.797 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739166
2025-04-20T06:45:16.711Z,1745131516.711 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T06:55:17.061Z,1745132117.061 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T07:05:17.415Z,1745132717.415 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T07:15:17.761Z,1745133317.761 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T07:25:18.166Z,1745133918.166 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T07:28:52.641Z,1745134132.641 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T07:28:52.642Z,1745134132.642 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-20T07:28:52.644Z,1745134132.644 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T07:35:18.446Z,1745134518.446 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T07:45:18.794Z,1745135118.794 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T07:55:19.139Z,1745135719.139 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T08:05:19.483Z,1745136319.483 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T08:15:19.827Z,1745136919.827 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T08:25:20.169Z,1745137520.169 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T08:35:20.585Z,1745138120.585 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T08:36:27.187Z,1745138187.187 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T08:36:27.188Z,1745138187.188 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-04-20T08:36:27.190Z,1745138187.190 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T08:39:32.240Z,1745138372.240 [keepstation:SurfaceComms] Running Loop=1
2025-04-20T08:39:32.240Z,1745138372.240 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-20T08:39:32.240Z,1745138372.240 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-20T08:39:32.241Z,1745138372.241 [keepstation:SurfaceComms:A] Stopped
2025-04-20T08:39:32.241Z,1745138372.241 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-20T08:39:32.241Z,1745138372.241 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-20T08:39:32.243Z,1745138372.243 [keepstation:NeedComms] Running Loop=1
2025-04-20T08:39:32.243Z,1745138372.243 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-20T08:39:32.243Z,1745138372.243 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-20T08:39:32.243Z,1745138372.243 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-20T08:39:32.243Z,1745138372.243 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-20T08:39:32.244Z,1745138372.244 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-20T08:39:32.244Z,1745138372.244 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-20T08:39:32.244Z,1745138372.244 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-20T08:39:32.245Z,1745138372.245 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-20T08:39:32.245Z,1745138372.245 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-20T08:39:32.245Z,1745138372.245 [keepstation:NeedComms:A] Running Loop=1
2025-04-20T08:39:32.247Z,1745138372.247 [keepstation:NeedComms:A](INFO): last time_fix was:  1745131170.000000 second since 1970/01/01T00:00:00Z 
2025-04-20T08:39:32.247Z,1745138372.247 [keepstation:NeedComms:A] Stopped
2025-04-20T08:39:32.628Z,1745138372.628 [keepstation:KeepStation:Dive] Stopped
2025-04-20T08:39:32.628Z,1745138372.628 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-20T08:39:32.628Z,1745138372.628 [keepstation:KeepStation:Dive:A] Stopped
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-20T08:39:32.629Z,1745138372.629 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-20T08:40:40.096Z,1745138440.096 [keepstation:NeedComms:C] Running Loop=1
2025-04-20T08:40:40.841Z,1745138440.841 [Radio_Surface](INFO): Powering up
2025-04-20T08:40:46.889Z,1745138446.889 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-20T08:41:22.910Z,1745138482.910 [NAL9602](INFO): SBD MO Status=2, MOMSN=20182, MT Status=2, MTMSN=0
2025-04-20T08:41:22.910Z,1745138482.910 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-20T08:41:59.674Z,1745138519.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=20182, MT Status=2, MTMSN=0
2025-04-20T08:41:59.674Z,1745138519.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-20T08:42:20.280Z,1745138540.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=20182, MT Status=0, MTMSN=0
2025-04-20T08:42:20.280Z,1745138540.280 [NAL9602](INFO): No messages in MT queue
2025-04-20T08:42:21.487Z,1745138541.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084219.00,A,3647.72141,N,12150.36526,W,0.797,72.38,200425,,,D*4D
2025-04-20T08:42:21.490Z,1745138541.490 [NAL9602](INFO): GPS fix at 20250420T084219: (36.795357, -121.839421)
2025-04-20T08:42:21.503Z,1745138541.503 [keepstation:NeedComms:C] Stopped
2025-04-20T08:42:21.503Z,1745138541.503 [keepstation:NeedComms:D] Running Loop=1
2025-04-20T08:42:42.589Z,1745138562.589 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003282
2025-04-20T08:42:50.747Z,1745138570.747 [NAL9602](INFO): SBD MO Status=1, MOMSN=20183, MT Status=0, MTMSN=0
2025-04-20T08:42:50.805Z,1745138570.805 [NAL9602](INFO): Sent 89 bytes from file Logs/20250420T042936/Courier0012.lzma
2025-04-20T08:42:50.805Z,1745138570.805 [NAL9602](INFO): Packets left to send: 0
2025-04-20T08:42:51.691Z,1745138571.691 [DataOverHttps](INFO): Sending 436 bytes from file Logs/20250420T042936/Express0013.lzma
2025-04-20T08:42:52.090Z,1745138572.090 [DataOverHttps](INFO): Moved sent file to Logs/20250420T042936/Express0013.lzma.bak
2025-04-20T08:42:52.091Z,1745138572.091 [DataOverHttps](INFO): SBD MOMSN=24694292
2025-04-20T08:42:54.004Z,1745138574.004 [keepstation:NeedComms:D] Stopped
2025-04-20T08:42:54.004Z,1745138574.004 [keepstation:NeedComms:E] Running Loop=1
2025-04-20T08:42:55.999Z,1745138575.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084254.00,A,3647.72230,N,12150.36294,W,0.350,349.30,200425,,,D*73
2025-04-20T08:42:56.002Z,1745138576.002 [NAL9602](INFO): GPS fix at 20250420T084254: (36.795372, -121.839382)
2025-04-20T08:42:56.023Z,1745138576.023 [keepstation:NeedComms:E] Stopped
2025-04-20T08:42:56.024Z,1745138576.024 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-20T08:42:56.028Z,1745138576.028 [keepstation:NeedComms] Stopped
2025-04-20T08:42:56.029Z,1745138576.029 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-20T08:42:56.029Z,1745138576.029 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-20T08:42:56.029Z,1745138576.029 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-20T08:42:56.443Z,1745138576.443 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-20T08:42:56.443Z,1745138576.443 [keepstation:SurfaceComms:B] Stopped
2025-04-20T08:42:56.443Z,1745138576.443 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-20T08:42:56.443Z,1745138576.443 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-20T08:42:56.443Z,1745138576.443 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-20T08:42:56.444Z,1745138576.444 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-20T08:42:56.842Z,1745138576.842 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-20T08:42:56.843Z,1745138576.843 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-20T08:42:56.843Z,1745138576.843 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-20T08:42:56.843Z,1745138576.843 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-20T08:42:56.843Z,1745138576.843 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-20T08:42:56.843Z,1745138576.843 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-20T08:42:57.227Z,1745138577.227 [keepstation:SurfaceComms:setStation] Stopped
2025-04-20T08:42:57.227Z,1745138577.227 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-20T08:42:57.227Z,1745138577.227 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-20T08:42:57.227Z,1745138577.227 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-20T08:42:57.227Z,1745138577.227 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-20T08:42:57.227Z,1745138577.227 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-20T08:42:57.636Z,1745138577.636 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-20T08:42:57.641Z,1745138577.641 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-20T08:42:57.641Z,1745138577.641 [keepstation:SurfaceComms:setSink] Stopped
2025-04-20T08:42:57.642Z,1745138577.642 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-20T08:42:57.643Z,1745138577.643 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-20T08:42:57.643Z,1745138577.643 [keepstation:SurfaceComms] Stopped
2025-04-20T08:42:57.643Z,1745138577.643 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-20T08:42:58.055Z,1745138578.055 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-20T08:42:58.055Z,1745138578.055 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-20T08:42:58.055Z,1745138578.055 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-20T08:42:58.055Z,1745138578.055 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-20T08:42:58.056Z,1745138578.056 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-20T08:42:58.056Z,1745138578.056 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-20T08:42:58.056Z,1745138578.056 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-20T08:42:58.056Z,1745138578.056 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T08:42:58.056Z,1745138578.056 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-20T08:42:58.056Z,1745138578.056 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T08:42:58.061Z,1745138578.061 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-20T08:42:58.061Z,1745138578.061 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-20T08:42:58.061Z,1745138578.061 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-20T08:42:58.061Z,1745138578.061 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-20T08:42:58.061Z,1745138578.061 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-20T08:42:58.062Z,1745138578.062 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-20T08:42:58.062Z,1745138578.062 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-20T08:43:28.763Z,1745138608.763 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T08:44:25.062Z,1745138665.062 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T08:44:56.193Z,1745138696.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T08:45:05.873Z,1745138705.873 [Radio_Surface](INFO): Powering down
2025-04-20T08:45:12.000Z,1745138713.000 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-20T08:45:12.000Z,1745138713.000 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-20T08:45:12.000Z,1745138713.000 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T08:45:13.000Z,1745138713.000 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-20T08:45:13.410Z,1745138713.410 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-20T08:45:13.410Z,1745138713.410 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T08:45:13.411Z,1745138713.411 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-20T08:45:13.411Z,1745138713.411 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-20T08:45:13.411Z,1745138713.411 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T08:45:13.411Z,1745138713.411 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-20T08:45:13.820Z,1745138713.820 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-20T08:45:13.821Z,1745138713.821 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T08:45:13.821Z,1745138713.821 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-20T08:45:13.821Z,1745138713.821 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T08:45:13.821Z,1745138713.821 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T08:45:13.821Z,1745138713.821 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-20T08:45:13.822Z,1745138713.822 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T08:45:13.822Z,1745138713.822 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-20T08:45:13.822Z,1745138713.822 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T08:45:13.822Z,1745138713.822 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-20T08:45:13.822Z,1745138713.822 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-20T08:45:13.822Z,1745138713.822 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T08:45:14.219Z,1745138714.219 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-20T08:45:20.667Z,1745138720.667 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T08:45:27.321Z,1745138727.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T08:45:28.325Z,1745138728.325 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-20T08:46:32.585Z,1745138792.585 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735397
2025-04-20T08:55:21.015Z,1745139321.015 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T09:05:21.357Z,1745139921.357 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T09:15:21.699Z,1745140521.699 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T09:25:22.045Z,1745141122.045 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T09:34:43.255Z,1745141683.255 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-20T09:34:47.684Z,1745141687.684 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007543
CHAN A1 (24V): 0.023437
CHAN A2 (12V): -0.005491
CHAN A3 (5V): -0.002923
CHAN B0 (3.3V): -0.001220
CHAN B1 (3.15aV): -0.001215
CHAN B2 (3.15bV): -0.000978
CHAN B3 (GND): -0.000248
OPEN: 0.006837
Full Scale: +/- 1 mA
2025-04-20T09:35:22.410Z,1745141722.410 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T09:44:02.760Z,1745142242.760 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T09:44:02.761Z,1745142242.761 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-20T09:44:02.764Z,1745142242.764 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T09:45:22.749Z,1745142322.749 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T09:55:23.096Z,1745142923.096 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T10:05:23.449Z,1745143523.449 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T10:15:23.793Z,1745144123.793 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T10:25:24.152Z,1745144724.152 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T10:35:24.495Z,1745145324.495 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T10:42:56.180Z,1745145776.180 [keepstation:SurfaceComms] Running Loop=1
2025-04-20T10:42:56.180Z,1745145776.180 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-20T10:42:56.181Z,1745145776.181 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-20T10:42:56.181Z,1745145776.181 [keepstation:SurfaceComms:A] Stopped
2025-04-20T10:42:56.181Z,1745145776.181 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-20T10:42:56.181Z,1745145776.181 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-20T10:42:56.183Z,1745145776.183 [keepstation:NeedComms] Running Loop=1
2025-04-20T10:42:56.183Z,1745145776.183 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-20T10:42:56.183Z,1745145776.183 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-20T10:42:56.183Z,1745145776.183 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-20T10:42:56.183Z,1745145776.183 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-20T10:42:56.184Z,1745145776.184 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-20T10:42:56.184Z,1745145776.184 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-20T10:42:56.185Z,1745145776.185 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-20T10:42:56.185Z,1745145776.185 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-20T10:42:56.185Z,1745145776.185 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-20T10:42:56.186Z,1745145776.186 [keepstation:NeedComms:A] Running Loop=1
2025-04-20T10:42:56.187Z,1745145776.187 [keepstation:NeedComms:A](INFO): last time_fix was:  1745138574.000000 second since 1970/01/01T00:00:00Z 
2025-04-20T10:42:56.187Z,1745145776.187 [keepstation:NeedComms:A] Stopped
2025-04-20T10:42:56.575Z,1745145776.575 [keepstation:KeepStation:Dive] Stopped
2025-04-20T10:42:56.575Z,1745145776.575 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-20T10:42:56.575Z,1745145776.575 [keepstation:KeepStation:Dive:A] Stopped
2025-04-20T10:42:56.576Z,1745145776.576 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-20T10:42:56.576Z,1745145776.576 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-20T10:42:56.576Z,1745145776.576 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-20T10:42:56.576Z,1745145776.576 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-20T10:42:56.576Z,1745145776.576 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-20T10:42:56.585Z,1745145776.585 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-20T10:42:56.585Z,1745145776.585 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-20T10:44:05.268Z,1745145845.268 [keepstation:NeedComms:C] Running Loop=1
2025-04-20T10:44:05.785Z,1745145845.785 [Radio_Surface](INFO): Powering up
2025-04-20T10:44:11.799Z,1745145851.799 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-20T10:44:17.771Z,1745145857.771 [NAL9602](INFO): SBD MO Status=0, MOMSN=20184, MT Status=0, MTMSN=0
2025-04-20T10:44:17.771Z,1745145857.771 [NAL9602](INFO): No messages in MT queue
2025-04-20T10:44:34.343Z,1745145874.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,104432.00,A,3648.31609,N,12150.70933,W,0.408,0.00,200425,,,A*7C
2025-04-20T10:44:34.346Z,1745145874.346 [NAL9602](INFO): GPS fix at 20250420T104432: (36.805268, -121.845156)
2025-04-20T10:44:34.359Z,1745145874.359 [keepstation:NeedComms:C] Stopped
2025-04-20T10:44:34.360Z,1745145874.360 [keepstation:NeedComms:D] Running Loop=1
2025-04-20T10:44:41.925Z,1745145881.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:44:50.618Z,1745145890.618 [NAL9602](INFO): SBD MO Status=1, MOMSN=20185, MT Status=0, MTMSN=0
2025-04-20T10:44:50.669Z,1745145890.669 [NAL9602](INFO): Sent 88 bytes from file Logs/20250420T042936/Courier0015.lzma
2025-04-20T10:44:50.669Z,1745145890.669 [NAL9602](INFO): Packets left to send: 0
2025-04-20T10:45:01.834Z,1745145901.834 [NAL9602](INFO): SBD MO Status=1, MOMSN=20186, MT Status=0, MTMSN=0
2025-04-20T10:45:01.893Z,1745145901.893 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T042936/Express0016.lzma
2025-04-20T10:45:01.893Z,1745145901.893 [NAL9602](INFO): Packets left to send: 1
2025-04-20T10:45:12.339Z,1745145912.339 [NAL9602](INFO): SBD MO Status=1, MOMSN=20187, MT Status=0, MTMSN=0
2025-04-20T10:45:12.389Z,1745145912.389 [NAL9602](INFO): Sent 195 bytes from file Logs/20250420T042936/Express0016.lzma
2025-04-20T10:45:12.389Z,1745145912.389 [NAL9602](INFO): Packets left to send: 0
2025-04-20T10:45:13.050Z,1745145913.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:45:24.694Z,1745145924.694 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T10:45:30.276Z,1745145930.276 [NAL9602](INFO): SBD MO Status=0, MOMSN=20188, MT Status=0, MTMSN=0
2025-04-20T10:45:30.379Z,1745145930.379 [keepstation:NeedComms:D] Stopped
2025-04-20T10:45:30.381Z,1745145930.381 [keepstation:NeedComms:E] Running Loop=1
2025-04-20T10:45:32.699Z,1745145932.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,104530.00,A,3648.31912,N,12150.71562,W,0.447,0.00,200425,,,D*7D
2025-04-20T10:45:32.705Z,1745145932.705 [NAL9602](INFO): GPS fix at 20250420T104530: (36.805319, -121.845260)
2025-04-20T10:45:32.739Z,1745145932.739 [keepstation:NeedComms:E] Stopped
2025-04-20T10:45:32.740Z,1745145932.740 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-20T10:45:32.740Z,1745145932.740 [keepstation:NeedComms] Stopped
2025-04-20T10:45:32.740Z,1745145932.740 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-20T10:45:32.741Z,1745145932.741 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-20T10:45:32.741Z,1745145932.741 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-20T10:45:33.149Z,1745145933.149 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-20T10:45:33.149Z,1745145933.149 [keepstation:SurfaceComms:B] Stopped
2025-04-20T10:45:33.149Z,1745145933.149 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-20T10:45:33.149Z,1745145933.149 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-20T10:45:33.149Z,1745145933.149 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-20T10:45:33.149Z,1745145933.149 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-20T10:45:33.515Z,1745145933.515 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-20T10:45:33.515Z,1745145933.515 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-20T10:45:33.515Z,1745145933.515 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-20T10:45:33.516Z,1745145933.516 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-20T10:45:33.516Z,1745145933.516 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-20T10:45:33.516Z,1745145933.516 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-20T10:45:33.941Z,1745145933.941 [keepstation:SurfaceComms:setStation] Stopped
2025-04-20T10:45:33.941Z,1745145933.941 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-20T10:45:33.941Z,1745145933.941 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-20T10:45:33.941Z,1745145933.941 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-20T10:45:33.941Z,1745145933.941 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-20T10:45:33.942Z,1745145933.942 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-20T10:45:34.321Z,1745145934.321 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-20T10:45:34.321Z,1745145934.321 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-20T10:45:34.322Z,1745145934.322 [keepstation:SurfaceComms:setSink] Stopped
2025-04-20T10:45:34.322Z,1745145934.322 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-20T10:45:34.322Z,1745145934.322 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-20T10:45:34.322Z,1745145934.322 [keepstation:SurfaceComms] Stopped
2025-04-20T10:45:34.322Z,1745145934.322 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-20T10:45:34.852Z,1745145934.852 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-20T10:45:34.865Z,1745145934.865 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-20T10:45:34.865Z,1745145934.865 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-20T10:45:34.865Z,1745145934.865 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-20T10:45:34.865Z,1745145934.865 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-20T10:45:34.866Z,1745145934.866 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-20T10:45:34.867Z,1745145934.867 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-20T10:45:34.867Z,1745145934.867 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-20T10:45:34.867Z,1745145934.867 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-20T10:45:34.867Z,1745145934.867 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-20T10:45:44.185Z,1745145944.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:46:04.618Z,1745145964.618 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T10:46:15.317Z,1745145975.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:46:46.441Z,1745146006.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:47:17.565Z,1745146037.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:47:36.686Z,1745146056.686 [Radio_Surface](INFO): Powering down
2025-04-20T10:47:42.403Z,1745146062.403 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-20T10:47:42.403Z,1745146062.403 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-20T10:47:42.403Z,1745146062.403 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T10:47:42.404Z,1745146062.404 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-20T10:47:42.811Z,1745146062.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-20T10:47:42.811Z,1745146062.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T10:47:42.811Z,1745146062.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-20T10:47:42.811Z,1745146062.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-20T10:47:42.811Z,1745146062.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T10:47:42.812Z,1745146062.812 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-20T10:47:43.206Z,1745146063.206 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-20T10:47:43.206Z,1745146063.206 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T10:47:43.206Z,1745146063.206 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-20T10:47:43.206Z,1745146063.206 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T10:47:43.206Z,1745146063.206 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T10:47:43.206Z,1745146063.206 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-20T10:47:43.207Z,1745146063.207 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T10:47:43.207Z,1745146063.207 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-20T10:47:43.207Z,1745146063.207 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T10:47:43.207Z,1745146063.207 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-20T10:47:43.207Z,1745146063.207 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-20T10:47:43.207Z,1745146063.207 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-20T10:47:43.611Z,1745146063.611 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T10:47:43.611Z,1745146063.611 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-20T10:47:43.611Z,1745146063.611 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T10:47:43.612Z,1745146063.612 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T10:47:43.612Z,1745146063.612 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-20T10:47:43.612Z,1745146063.612 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T10:47:43.612Z,1745146063.612 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-20T10:47:48.689Z,1745146068.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T10:47:49.693Z,1745146069.693 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-20T10:51:41.555Z,1745146301.555 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T10:51:41.556Z,1745146301.556 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-20T10:51:41.559Z,1745146301.559 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T10:55:24.963Z,1745146524.963 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:05:25.311Z,1745147125.311 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:15:25.662Z,1745147725.662 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:25:26.003Z,1745148326.003 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:35:26.351Z,1745148926.351 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:45:26.697Z,1745149526.697 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:55:27.043Z,1745150127.043 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T11:55:44.817Z,1745150144.817 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-04-20T11:59:19.746Z,1745150359.746 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-20T11:59:19.747Z,1745150359.747 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-20T11:59:19.750Z,1745150359.750 [BPC1](INFO): Received data from all battery sticks.
2025-04-20T12:05:27.380Z,1745150727.380 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T12:15:27.732Z,1745151327.732 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T12:25:28.076Z,1745151928.076 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-20T12:37:12.353Z,1745152632.353 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2025-04-20T12:37:12.353Z,1745152632.353 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2025-04-20T12:37:12.353Z,1745152632.353 [BuoyancyServo] Communications Fault, FailCount= 1
2025-04-20T12:37:12.353Z,1745152632.353 [BuoyancyServo](ERROR): Communications Fault
2025-04-20T12:37:12.362Z,1745152632.362 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2025-04-20T12:37:14.303Z,1745152634.303 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-20T12:37:14.303Z,1745152634.303 [BuoyancyServo](INFO): Powering down
2025-04-20T12:37:15.096Z,1745152635.096 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2025-04-20T12:37:15.096Z,1745152635.096 [BuoyancyServo] No Fault, FailCount= 1
2025-04-20T12:37:15.502Z,1745152635.502 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-20T12:37:15.507Z,1745152635.507 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-20T12:45:32.819Z,1745153132.819 [keepstation:SurfaceComms] Running Loop=1
2025-04-20T12:45:32.819Z,1745153132.819 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-20T12:45:32.819Z,1745153132.819 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-20T12:45:32.820Z,1745153132.820 [keepstation:SurfaceComms:A] Stopped
2025-04-20T12:45:32.820Z,1745153132.820 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-20T12:45:32.820Z,1745153132.820 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-20T12:45:32.822Z,1745153132.822 [keepstation:NeedComms] Running Loop=1
2025-04-20T12:45:32.822Z,1745153132.822 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-20T12:45:32.822Z,1745153132.822 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-20T12:45:32.822Z,1745153132.822 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-20T12:45:32.822Z,1745153132.822 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-20T12:45:32.823Z,1745153132.823 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-20T12:45:32.823Z,1745153132.823 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-20T12:45:32.823Z,1745153132.823 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-20T12:45:32.824Z,1745153132.824 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-20T12:45:32.824Z,1745153132.824 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-20T12:45:32.824Z,1745153132.824 [keepstation:NeedComms:A] Running Loop=1
2025-04-20T12:45:32.826Z,1745153132.826 [keepstation:NeedComms:A](INFO): last time_fix was:  1745145930.000000 second since 1970/01/01T00:00:00Z 
2025-04-20T12:45:32.826Z,1745153132.826 [keepstation:NeedComms:A] Stopped
2025-04-20T12:45:33.225Z,1745153133.225 [keepstation:KeepStation:Dive] Stopped
2025-04-20T12:45:33.225Z,1745153133.225 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-20T12:45:33.225Z,1745153133.225 [keepstation:KeepStation:Dive:A] Stopped
2025-04-20T12:45:33.225Z,1745153133.225 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-20T12:45:33.225Z,1745153133.225 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-20T12:45:33.226Z,1745153133.226 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-20T12:45:33.226Z,1745153133.226 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-20T12:45:33.226Z,1745153133.226 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-20T12:45:33.226Z,1745153133.226 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-20T12:45:33.226Z,1745153133.226 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-20T12:48:00.686Z,1745153280.686 [keepstation:NeedComms:C] Running Loop=1
2025-04-20T12:48:01.417Z,1745153281.417 [Radio_Surface](INFO): Powering up
2025-04-20T12:48:07.549Z,1745153287.549 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-20T12:48:37.701Z,1745153317.701 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T12:49:08.837Z,1745153348.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T12:49:39.965Z,1745153379.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T12:49:46.919Z,1745153386.919 [NAL9602](INFO): SBD MO Status=0, MOMSN=20189, MT Status=0, MTMSN=0
2025-04-20T12:49:46.919Z,1745153386.919 [NAL9602](INFO): No messages in MT queue
2025-04-20T12:49:48.131Z,1745153388.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124946.00,A,3649.16203,N,12150.75488,W,0.447,304.83,200425,,,A*75
2025-04-20T12:49:48.133Z,1745153388.133 [NAL9602](INFO): GPS fix at 20250420T124946: (36.819367, -121.845915)
2025-04-20T12:49:48.146Z,1745153388.146 [keepstation:NeedComms:C] Stopped
2025-04-20T12:49:48.146Z,1745153388.146 [keepstation:NeedComms:D] Running Loop=1
2025-04-20T12:50:11.905Z,1745153411.905 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003283
2025-04-20T12:50:19.433Z,1745153419.433 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250420T042936/Courier0018.lzma
2025-04-20T12:50:19.851Z,1745153419.852 [NAL9602](INFO): SBD MO Status=2, MOMSN=20190, MT Status=2, MTMSN=0
2025-04-20T12:50:19.852Z,1745153419.852 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-20T12:50:20.434Z,1745153420.434 [DataOverHttps](INFO): Moved sent file to Logs/20250420T042936/Courier0018.lzma.bak
2025-04-20T12:50:20.435Z,1745153420.435 [DataOverHttps](INFO): SBD MOMSN=24694765
2025-04-20T12:50:39.302Z,1745153439.302 [DataOverHttps](INFO): Sending 733 bytes from file Logs/20250420T042936/Express0019.lzma
2025-04-20T12:50:40.302Z,1745153440.302 [DataOverHttps](INFO): Moved sent file to Logs/20250420T042936/Express0019.lzma.bak
2025-04-20T12:50:40.303Z,1745153440.303 [DataOverHttps](INFO): SBD MOMSN=24694768
2025-04-20T12:50:42.074Z,1745153442.074 [keepstation:NeedComms:D] Stopped
2025-04-20T12:50:42.075Z,1745153442.075 [keepstation:NeedComms:E] Running Loop=1
2025-04-20T12:50:44.075Z,1745153444.075 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125042.00,A,3649.16612,N,12150.75923,W,0.330,310.65,200425,,,A*7B
2025-04-20T12:50:44.078Z,1745153444.078 [NAL9602](INFO): GPS fix at 20250420T125042: (36.819435, -121.845987)
2025-04-20T12:50:44.115Z,1745153444.115 [keepstation:NeedComms:E] Stopped
2025-04-20T12:50:44.116Z,1745153444.116 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-20T12:50:44.116Z,1745153444.116 [keepstation:NeedComms] Stopped
2025-04-20T12:50:44.116Z,1745153444.116 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-20T12:50:44.116Z,1745153444.116 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-20T12:50:44.116Z,1745153444.116 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-20T12:50:44.521Z,1745153444.521 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-20T12:50:44.521Z,1745153444.521 [keepstation:SurfaceComms:B] Stopped
2025-04-20T12:50:44.521Z,1745153444.521 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-20T12:50:44.522Z,1745153444.522 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-20T12:50:44.522Z,1745153444.522 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-20T12:50:44.522Z,1745153444.522 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-20T12:50:44.902Z,1745153444.902 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-20T12:50:44.902Z,1745153444.902 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-20T12:50:44.902Z,1745153444.902 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-20T12:50:44.902Z,1745153444.902 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-20T12:50:44.902Z,1745153444.902 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-20T12:50:44.902Z,1745153444.902 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-20T12:50:45.318Z,1745153445.318 [keepstation:SurfaceComms:setStation] Stopped
2025-04-20T12:50:45.318Z,1745153445.318 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-20T12:50:45.318Z,1745153445.318 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-20T12:50:45.318Z,1745153445.318 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-20T12:50:45.318Z,1745153445.318 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-20T12:50:45.318Z,1745153445.318 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-20T12:50:45.722Z,1745153445.722 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-20T12:50:45.722Z,1745153445.722 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-20T12:50:45.722Z,1745153445.722 [keepstation:SurfaceComms:setSink] Stopped
2025-04-20T12:50:45.723Z,1745153445.723 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-20T12:50:45.723Z,1745153445.723 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-20T12:50:45.723Z,1745153445.723 [keepstation:SurfaceComms] Stopped
2025-04-20T12:50:45.723Z,1745153445.723 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-20T12:50:46.134Z,1745153446.134 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-20T12:50:46.134Z,1745153446.134 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-20T12:50:46.134Z,1745153446.134 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-20T12:50:46.134Z,1745153446.134 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-20T12:50:46.135Z,1745153446.135 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-20T12:50:46.136Z,1745153446.136 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-20T12:50:46.136Z,1745153446.136 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-20T12:50:46.136Z,1745153446.136 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-20T12:50:46.141Z,1745153446.141 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-20T12:50:46.141Z,1745153446.141 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-20T12:51:16.798Z,1745153476.798 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-20T12:52:12.949Z,1745153532.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T12:52:44.081Z,1745153564.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T12:52:51.125Z,1745153571.125 [Radio_Surface](INFO): Powering down
2025-04-20T12:52:58.618Z,1745153578.618 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-20T12:52:58.618Z,1745153578.618 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-20T12:52:58.618Z,1745153578.618 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T12:52:58.618Z,1745153578.618 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-20T12:52:59.026Z,1745153579.026 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-20T12:52:59.027Z,1745153579.027 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-20T12:52:59.027Z,1745153579.027 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-20T12:52:59.027Z,1745153579.027 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-20T12:52:59.027Z,1745153579.027 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T12:52:59.027Z,1745153579.027 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-20T12:52:59.446Z,1745153579.446 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-20T12:52:59.446Z,1745153579.446 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T12:52:59.446Z,1745153579.446 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-20T12:52:59.446Z,1745153579.446 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-20T12:52:59.447Z,1745153579.447 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-20T12:52:59.831Z,1745153579.831 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-20T12:52:59.831Z,1745153579.831 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-20T12:52:59.831Z,1745153579.831 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-20T12:52:59.831Z,1745153579.831 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-20T12:52:59.831Z,1745153579.831 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-20T12:52:59.832Z,1745153579.832 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-20T12:52:59.832Z,1745153579.832 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-20T12:53:15.209Z,1745153595.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-20T12:53:16.213Z,1745153596.213 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-20T12:54:21.485Z,1745153661.485 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755234