2025-06-05T04:02:00.076Z,1749096120.076 [CommandExec](IMPORTANT): got command restart logs
2025-06-05T04:02:07.254Z,1749096127.254 [NAL9602](INFO): SBD MO Status=1, MOMSN=21038, MT Status=0, MTMSN=0
2025-06-05T04:02:07.319Z,1749096127.319 [NAL9602](INFO): Sent 332 bytes from file Logs/20250604T184824/Express0118.lzma
2025-06-05T04:02:07.319Z,1749096127.319 [NAL9602](INFO): Packets left to send: 2
2025-06-05T04:02:13.763Z,1749096133.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T04:02:22.499Z,1749096142.499 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003179
2025-06-05T04:02:24.029Z,1749096144.029 [NAL9602](INFO): SBD MO Status=1, MOMSN=21039, MT Status=0, MTMSN=0
2025-06-05T04:02:24.091Z,1749096144.091 [NAL9602](INFO): Sent 40 bytes from file Logs/20250604T184824/Courier0123.lzma
2025-06-05T04:02:24.091Z,1749096144.091 [NAL9602](INFO): Packets left to send: 0
2025-06-05T04:02:30.229Z,1749096150.229 [DataOverHttps](INFO): Sending 1264 bytes from file Logs/20250604T184824/Express0118.lzma
2025-06-05T04:02:32.233Z,1749096152.233 [DataOverHttps](INFO): Moved sent file to Logs/20250604T184824/Express0118.lzma.bak
2025-06-05T04:02:32.233Z,1749096152.233 [DataOverHttps](INFO): SBD MOMSN=25099502
2025-06-05T04:02:49.627Z,1749096169.627 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250604T184824/Express0121.lzma
2025-06-05T04:02:50.629Z,1749096170.629 [DataOverHttps](INFO): Moved sent file to Logs/20250604T184824/Express0121.lzma.bak
2025-06-05T04:02:50.629Z,1749096170.629 [DataOverHttps](INFO): SBD MOMSN=25099515
2025-06-05T04:02:54.742Z,1749096174.742 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T04:03:08.206Z,1749096188.206 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250604T184824/Express0124.lzma
2025-06-05T04:03:09.208Z,1749096189.208 [DataOverHttps](INFO): Moved sent file to Logs/20250604T184824/Express0124.lzma.bak
2025-06-05T04:03:09.209Z,1749096189.209 [DataOverHttps](INFO): SBD MOMSN=25099524
2025-06-05T04:03:10.492Z,1749096190.492 [keepstation:NeedComms:D] Stopped
2025-06-05T04:03:10.493Z,1749096190.493 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T04:03:11.130Z,1749096191.130 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-05T04:03:12.516Z,1749096192.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040311.00,A,3647.00916,N,12159.72140,W,0.933,154.97,050625,,,A*7E
2025-06-05T04:03:12.522Z,1749096192.522 [NAL9602](INFO): GPS fix at 20250605T040311: (36.783486, -121.995357)
2025-06-05T04:03:12.563Z,1749096192.563 [keepstation:NeedComms:E] Stopped
2025-06-05T04:03:12.564Z,1749096192.564 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T04:03:12.564Z,1749096192.564 [keepstation:NeedComms] Stopped
2025-06-05T04:03:12.564Z,1749096192.564 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T04:03:12.564Z,1749096192.564 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T04:03:12.564Z,1749096192.564 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T04:03:12.938Z,1749096192.938 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-05T04:03:12.938Z,1749096192.938 [keepstation:SurfaceComms:B] Stopped
2025-06-05T04:03:12.939Z,1749096192.939 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T04:03:12.939Z,1749096192.939 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-05T04:03:12.939Z,1749096192.939 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-05T04:03:12.939Z,1749096192.939 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-05T04:03:13.343Z,1749096193.343 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-05T04:03:13.343Z,1749096193.343 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-05T04:03:13.343Z,1749096193.343 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-05T04:03:13.344Z,1749096193.344 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-05T04:03:13.344Z,1749096193.344 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-05T04:03:13.344Z,1749096193.344 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-05T04:03:13.807Z,1749096193.807 [keepstation:SurfaceComms:setStation] Stopped
2025-06-05T04:03:13.807Z,1749096193.807 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-05T04:03:13.807Z,1749096193.807 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-05T04:03:13.808Z,1749096193.808 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-05T04:03:13.808Z,1749096193.808 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-05T04:03:13.808Z,1749096193.808 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-05T04:03:14.195Z,1749096194.195 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-05T04:03:14.195Z,1749096194.195 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-05T04:03:14.195Z,1749096194.195 [keepstation:SurfaceComms:setSink] Stopped
2025-06-05T04:03:14.195Z,1749096194.195 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-05T04:03:14.196Z,1749096194.196 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-05T04:03:14.196Z,1749096194.196 [keepstation:SurfaceComms] Stopped
2025-06-05T04:03:14.196Z,1749096194.196 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:StartingMission] Stopped
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:TransitToStation] Running Loop=1
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-05T04:03:14.543Z,1749096194.543 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-05T04:03:14.544Z,1749096194.544 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-05T04:03:14.544Z,1749096194.544 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-05T04:03:14.544Z,1749096194.544 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-06-05T04:03:14.544Z,1749096194.544 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-06-05T04:03:14.545Z,1749096194.545 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.798149,-122.055191
2025-06-05T04:03:14.955Z,1749096194.955 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.798149,-122.055191
2025-06-05T04:03:14.955Z,1749096194.955 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd] Running Loop=1
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T04:03:14.956Z,1749096194.956 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T04:03:14.957Z,1749096194.957 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-05T04:03:14.958Z,1749096194.958 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T04:03:14.958Z,1749096194.958 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-05T04:03:14.958Z,1749096194.958 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T04:03:14.962Z,1749096194.962 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-05T04:03:14.962Z,1749096194.962 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T04:03:14.962Z,1749096194.962 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T04:03:14.962Z,1749096194.962 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-05T04:03:14.962Z,1749096194.962 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T04:03:14.963Z,1749096194.963 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-05T04:03:14.963Z,1749096194.963 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-05T04:03:14.964Z,1749096194.964 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-05T04:03:14.965Z,1749096194.965 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T04:03:14.965Z,1749096194.965 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-05T04:03:14.965Z,1749096194.965 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T04:03:14.965Z,1749096194.965 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-05T04:03:14.965Z,1749096194.965 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-05T04:03:14.966Z,1749096194.966 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T04:03:14.970Z,1749096194.970 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T04:03:31.086Z,1749096211.086 [NAL9602](INFO): SBD MO Status=0, MOMSN=21040, MT Status=0, MTMSN=0
2025-06-05T04:03:31.086Z,1749096211.086 [NAL9602](INFO): No messages in MT queue
2025-06-05T04:04:01.805Z,1749096241.805 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T04:05:12.099Z,1749096312.099 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T04:05:43.224Z,1749096343.224 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T04:05:50.262Z,1749096350.262 [Radio_Surface](INFO): Powering down
2025-06-05T04:06:14.347Z,1749096374.347 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T04:06:15.351Z,1749096375.351 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T04:07:20.627Z,1749096440.627 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735336
2025-06-05T04:07:35.110Z,1749096455.110 [DVL_micro](ERROR): Failed to parse:
:SA,-01.42,+01.04,243.8
2025-06-05T04:07:56.533Z,1749096476.533 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-05T04:07:56.534Z,1749096476.534 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-05T04:07:56.534Z,1749096476.534 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T04:07:56.534Z,1749096476.534 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-05T04:07:56.932Z,1749096476.932 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-05T04:07:56.932Z,1749096476.932 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T04:07:56.933Z,1749096476.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-05T04:07:56.933Z,1749096476.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T04:07:56.933Z,1749096476.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-05T04:07:56.933Z,1749096476.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T04:07:56.933Z,1749096476.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-05T04:07:57.332Z,1749096477.332 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T04:07:57.332Z,1749096477.332 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T04:07:57.333Z,1749096477.333 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T04:07:57.334Z,1749096477.334 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T04:07:57.334Z,1749096477.334 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T04:07:57.335Z,1749096477.335 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-05T04:07:57.737Z,1749096477.737 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position 31.28 mm (4 active estimators).
2025-06-05T04:26:14.007Z,1749097574.007 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.7,0000.0,1489.0,000
2025-06-05T04:43:36.539Z,1749098616.539 [ElevatorServo](FAULT): Overload Error
2025-06-05T04:43:36.539Z,1749098616.539 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T04:43:36.539Z,1749098616.539 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T04:43:36.661Z,1749098616.661 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T04:43:36.922Z,1749098616.922 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T04:43:36.922Z,1749098616.922 [ElevatorServo](INFO): Powering down
2025-06-05T04:43:37.753Z,1749098617.753 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T04:43:37.871Z,1749098617.871 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T04:43:37.949Z,1749098617.949 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T04:43:37.950Z,1749098617.950 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T04:46:42.557Z,1749098802.557 [DVL_micro](ERROR): Failed to parse:
:BI,-00130,+00537,+00062,+00000,I
2025-06-05T04:46:44.584Z,1749098804.584 [DVL_micro](ERROR): Failed to parse:
:WI,-00129,+00611,+00032,+00000,A
2025-06-05T04:46:46.613Z,1749098806.613 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T04:46:48.640Z,1749098808.640 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-06-05T04:46:49.014Z,1749098809.014 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-06-05T04:46:49.014Z,1749098809.014 [DVL_micro] Communications Fault, FailCount= 1
2025-06-05T04:46:49.014Z,1749098809.014 [DVL_micro](ERROR): Communications Fault
2025-06-05T04:46:49.386Z,1749098809.386 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-05T04:46:49.518Z,1749098809.518 [DVL_micro](INFO): Powering down
2025-06-05T04:46:50.378Z,1749098810.378 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-05T04:46:50.378Z,1749098810.378 [DVL_micro] No Fault, FailCount= 1
2025-06-05T04:47:08.033Z,1749098828.033 [DVL_micro](ERROR): Failed to parse:
:SA,-20.84,-01.14,263.7
2025-06-05T04:55:41.706Z,1749099341.706 [DVL_micro](ERROR): only read 0 of 4 data items for beam range. Device response is::RD9,+9999.99,+9999.99,+9999.99
2025-06-05T04:55:41.707Z,1749099341.707 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999.99,+9999.99,+9999.99
2025-06-05T04:56:47.580Z,1749099407.580 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T04:56:47.581Z,1749099407.581 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T04:56:47.584Z,1749099407.584 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T05:03:12.819Z,1749099792.819 [keepstation:SurfaceComms] Running Loop=1
2025-06-05T05:03:12.819Z,1749099792.819 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-05T05:03:12.820Z,1749099792.820 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-05T05:03:12.820Z,1749099792.820 [keepstation:SurfaceComms:A] Stopped
2025-06-05T05:03:12.820Z,1749099792.820 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-05T05:03:12.820Z,1749099792.820 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-05T05:03:12.822Z,1749099792.822 [keepstation:NeedComms] Running Loop=1
2025-06-05T05:03:12.827Z,1749099792.827 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T05:03:12.827Z,1749099792.827 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T05:03:12.827Z,1749099792.827 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T05:03:12.827Z,1749099792.827 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T05:03:12.828Z,1749099792.828 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T05:03:12.828Z,1749099792.828 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T05:03:12.828Z,1749099792.828 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T05:03:12.829Z,1749099792.829 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T05:03:12.829Z,1749099792.829 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T05:03:12.829Z,1749099792.829 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T05:03:12.835Z,1749099792.835 [keepstation:NeedComms:A](INFO): last time_fix was:  1749096191.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T05:03:12.835Z,1749099792.835 [keepstation:NeedComms:A] Stopped
2025-06-05T05:03:13.204Z,1749099793.204 [keepstation:TransitToStation:Dive] Stopped
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-05T05:03:13.205Z,1749099793.205 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T05:03:13.206Z,1749099793.206 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-05T05:03:13.206Z,1749099793.206 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T05:03:13.206Z,1749099793.206 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-05T05:03:13.206Z,1749099793.206 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-05T05:03:13.206Z,1749099793.206 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-05T05:03:19.889Z,1749099799.889 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-06-05T05:03:56.398Z,1749099836.398 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T05:03:56.915Z,1749099836.915 [Radio_Surface](INFO): Powering up
2025-06-05T05:04:02.739Z,1749099842.739 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-05T05:04:32.859Z,1749099872.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T05:04:38.069Z,1749099878.069 [NAL9602](INFO): SBD MO Status=2, MOMSN=21041, MT Status=2, MTMSN=0
2025-06-05T05:04:38.069Z,1749099878.069 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:05:04.330Z,1749099904.330 [NAL9602](INFO): SBD MO Status=2, MOMSN=21041, MT Status=2, MTMSN=0
2025-06-05T05:05:04.330Z,1749099904.330 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:05:56.942Z,1749099956.942 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003133
2025-06-05T05:06:54.618Z,1749100014.618 [NAL9602](INFO): SBD MO Status=2, MOMSN=21041, MT Status=2, MTMSN=0
2025-06-05T05:06:54.618Z,1749100014.618 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:07:14.021Z,1749100034.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=21041, MT Status=2, MTMSN=0
2025-06-05T05:07:14.021Z,1749100034.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:07:31.377Z,1749100051.377 [NAL9602](INFO): SBD MO Status=2, MOMSN=21041, MT Status=2, MTMSN=0
2025-06-05T05:07:31.378Z,1749100051.378 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:07:48.753Z,1749100068.753 [NAL9602](INFO): SBD MO Status=0, MOMSN=21041, MT Status=0, MTMSN=0
2025-06-05T05:07:48.753Z,1749100068.753 [NAL9602](INFO): No messages in MT queue
2025-06-05T05:07:49.960Z,1749100069.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050749.00,A,3647.01012,N,12200.64915,W,0.797,148.71,050625,,,A*7F
2025-06-05T05:07:49.963Z,1749100069.963 [NAL9602](INFO): GPS fix at 20250605T050749: (36.783502, -122.010819)
2025-06-05T05:07:49.975Z,1749100069.975 [UniversalFixResidualReporter](INFO): Fix residual: 47.6 %DT, over the last 2408.9 m. Residual distance 1146.0 m at bearing -53.1 degrees. Fix at (36.7835, -122.0108) with 1377.0 m made good.
2025-06-05T05:07:49.978Z,1749100069.978 [keepstation:NeedComms:C] Stopped
2025-06-05T05:07:49.979Z,1749100069.979 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T05:08:06.171Z,1749100086.171 [DataOverHttps](INFO): Sending 372 bytes from file Logs/20250605T040200/Courier0000.lzma
2025-06-05T05:08:09.180Z,1749100089.180 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Courier0000.lzma.bak
2025-06-05T05:08:09.180Z,1749100089.180 [DataOverHttps](INFO): SBD MOMSN=25099663
2025-06-05T05:08:22.308Z,1749100102.308 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T05:08:38.505Z,1749100118.505 [DataOverHttps](INFO): Sending 1089 bytes from file Logs/20250605T040200/Express0001.lzma
2025-06-05T05:08:39.505Z,1749100119.505 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Express0001.lzma.bak
2025-06-05T05:08:39.505Z,1749100119.505 [DataOverHttps](INFO): SBD MOMSN=25099668
2025-06-05T05:08:40.942Z,1749100120.942 [keepstation:NeedComms:D] Stopped
2025-06-05T05:08:40.943Z,1749100120.943 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T05:08:42.975Z,1749100122.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050842.00,A,3646.99749,N,12200.64326,W,0.739,151.42,050625,,,A*74
2025-06-05T05:08:42.977Z,1749100122.977 [NAL9602](INFO): GPS fix at 20250605T050842: (36.783291, -122.010721)
2025-06-05T05:08:43.003Z,1749100123.003 [keepstation:NeedComms:E] Stopped
2025-06-05T05:08:43.004Z,1749100123.004 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T05:08:43.004Z,1749100123.004 [keepstation:NeedComms] Stopped
2025-06-05T05:08:43.004Z,1749100123.004 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T05:08:43.004Z,1749100123.004 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T05:08:43.004Z,1749100123.004 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T05:08:43.368Z,1749100123.368 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-05T05:08:43.368Z,1749100123.368 [keepstation:SurfaceComms:B] Stopped
2025-06-05T05:08:43.368Z,1749100123.368 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T05:08:43.368Z,1749100123.368 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-05T05:08:43.368Z,1749100123.368 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-05T05:08:43.368Z,1749100123.368 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-05T05:08:43.767Z,1749100123.767 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 31.28 mm.
2025-06-05T05:08:43.768Z,1749100123.768 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm.
2025-06-05T05:08:43.782Z,1749100123.782 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-05T05:08:43.782Z,1749100123.782 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-05T05:08:43.782Z,1749100123.782 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-05T05:08:43.783Z,1749100123.783 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-05T05:08:43.783Z,1749100123.783 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-05T05:08:43.783Z,1749100123.783 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-05T05:08:44.185Z,1749100124.185 [keepstation:SurfaceComms:setStation] Stopped
2025-06-05T05:08:44.185Z,1749100124.185 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-05T05:08:44.185Z,1749100124.185 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-05T05:08:44.185Z,1749100124.185 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-05T05:08:44.185Z,1749100124.185 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-05T05:08:44.185Z,1749100124.185 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-05T05:08:44.600Z,1749100124.600 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-05T05:08:44.600Z,1749100124.600 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-05T05:08:44.601Z,1749100124.601 [keepstation:SurfaceComms:setSink] Stopped
2025-06-05T05:08:44.601Z,1749100124.601 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-05T05:08:44.601Z,1749100124.601 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-05T05:08:44.601Z,1749100124.601 [keepstation:SurfaceComms] Stopped
2025-06-05T05:08:44.601Z,1749100124.601 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T05:08:45.025Z,1749100125.025 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-05T05:08:45.025Z,1749100125.025 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-05T05:08:45.025Z,1749100125.025 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-05T05:08:45.025Z,1749100125.025 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-05T05:08:45.026Z,1749100125.026 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T05:08:45.026Z,1749100125.026 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T05:08:45.030Z,1749100125.030 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T05:08:45.030Z,1749100125.030 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T05:08:45.030Z,1749100125.030 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T05:08:45.030Z,1749100125.030 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T05:08:45.030Z,1749100125.030 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T05:08:45.031Z,1749100125.031 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T05:08:45.031Z,1749100125.031 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T05:08:45.031Z,1749100125.031 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-05T05:08:45.031Z,1749100125.031 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T05:08:45.031Z,1749100125.031 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-05T05:08:45.032Z,1749100125.032 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-05T05:10:08.972Z,1749100208.972 [NAL9602](INFO): SBD MO Status=2, MOMSN=21042, MT Status=2, MTMSN=0
2025-06-05T05:10:08.972Z,1749100208.972 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:10:34.021Z,1749100234.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=21042, MT Status=2, MTMSN=0
2025-06-05T05:10:34.021Z,1749100234.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T05:11:02.300Z,1749100262.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=21042, MT Status=0, MTMSN=0
2025-06-05T05:11:02.300Z,1749100262.300 [NAL9602](INFO): No messages in MT queue
2025-06-05T05:11:33.020Z,1749100293.020 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T05:11:35.942Z,1749100295.942 [RudderServo](ERROR): getPosition uart error serial timeout
2025-06-05T05:11:35.942Z,1749100295.942 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-06-05T05:11:35.943Z,1749100295.943 [RudderServo] Communications Fault, FailCount= 1
2025-06-05T05:11:35.943Z,1749100295.943 [RudderServo](ERROR): Communications Fault
2025-06-05T05:11:35.963Z,1749100295.963 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-06-05T05:11:36.280Z,1749100296.280 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-06-05T05:11:36.280Z,1749100296.280 [RudderServo](INFO): Powering down
2025-06-05T05:11:37.074Z,1749100297.074 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-05T05:11:37.191Z,1749100297.191 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-05T05:11:37.195Z,1749100297.195 [CBIT](INFO): Clearing failed state for component RudderServo
2025-06-05T05:11:37.195Z,1749100297.195 [RudderServo] No Fault, FailCount= 1
2025-06-05T05:12:44.463Z,1749100364.463 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T05:13:08.103Z,1749100388.103 [Radio_Surface](INFO): Powering down
2025-06-05T05:13:15.608Z,1749100395.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T05:13:16.611Z,1749100396.611 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T05:14:49.366Z,1749100489.365 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-05T05:14:49.366Z,1749100489.366 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-05T05:14:49.367Z,1749100489.367 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T05:14:49.369Z,1749100489.369 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-05T05:14:49.781Z,1749100489.781 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-05T05:14:49.781Z,1749100489.781 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T05:14:49.781Z,1749100489.781 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-05T05:14:49.782Z,1749100489.782 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T05:14:49.782Z,1749100489.782 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-05T05:14:49.782Z,1749100489.782 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T05:14:49.782Z,1749100489.782 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-05T05:14:50.196Z,1749100490.196 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T05:14:50.197Z,1749100490.197 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-05T05:14:50.198Z,1749100490.198 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-05T05:14:50.198Z,1749100490.198 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-05T05:14:50.198Z,1749100490.198 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T05:14:50.198Z,1749100490.198 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T05:14:50.202Z,1749100490.202 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T05:14:50.202Z,1749100490.202 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T05:14:50.202Z,1749100490.202 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T05:14:50.202Z,1749100490.202 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T05:14:50.202Z,1749100490.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-05T05:14:50.203Z,1749100490.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T05:14:50.203Z,1749100490.203 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-05T05:14:50.590Z,1749100490.590 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 31.28 mm (3 active estimators).
2025-06-05T05:14:53.023Z,1749100493.023 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.771347
2025-06-05T05:19:48.850Z,1749100788.850 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-06-05T05:19:48.850Z,1749100788.850 [DVL_micro] Communications Fault, FailCount= 1
2025-06-05T05:19:48.850Z,1749100788.850 [DVL_micro](ERROR): Communications Fault
2025-06-05T05:19:48.851Z,1749100788.851 [DVL_micro](ERROR): Failed to parse:
2025-06-05T05:19:49.006Z,1749100789.006 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-05T05:19:49.283Z,1749100789.283 [DVL_micro](INFO): Powering down
2025-06-05T05:19:50.226Z,1749100790.226 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-05T05:19:50.226Z,1749100790.226 [DVL_micro] No Fault, FailCount= 1
2025-06-05T05:19:57.010Z,1749100797.010 [ElevatorServo](FAULT): Overload Error
2025-06-05T05:19:57.010Z,1749100797.010 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T05:19:57.010Z,1749100797.010 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T05:19:57.029Z,1749100797.029 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T05:19:57.282Z,1749100797.282 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T05:19:57.282Z,1749100797.282 [ElevatorServo](INFO): Powering down
2025-06-05T05:19:58.083Z,1749100798.083 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T05:19:58.203Z,1749100798.203 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T05:19:58.273Z,1749100798.273 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T05:19:58.274Z,1749100798.274 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T05:20:07.380Z,1749100807.380 [DVL_micro](ERROR): Failed to parse:
:SA,-20.60,+00.71,314.4
2025-06-05T05:20:09.394Z,1749100809.394 [DVL_micro](ERROR): Failed to parse:
:SA,-19.80,-00.18,313.9
2025-06-05T05:22:33.625Z,1749100953.625 [DVL_micro](ERROR): Failed to parse:
:SA,-14.12,+01.01,313.0
2025-06-05T05:26:30.114Z,1749101190.114 [ElevatorServo](FAULT): Overload Error
2025-06-05T05:26:30.114Z,1749101190.114 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T05:26:30.114Z,1749101190.114 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T05:26:30.133Z,1749101190.133 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T05:26:30.389Z,1749101190.389 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T05:26:30.389Z,1749101190.389 [ElevatorServo](INFO): Powering down
2025-06-05T05:26:31.215Z,1749101191.215 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T05:26:31.336Z,1749101191.336 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T05:26:31.403Z,1749101191.403 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T05:26:31.403Z,1749101191.403 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T05:29:31.016Z,1749101371.016 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+10000.00,9999.99,000.00
2025-06-05T05:29:31.016Z,1749101371.016 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+10000.00,9999.99,000.00
2025-06-05T05:31:36.419Z,1749101496.419 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-06-05T05:33:00.537Z,1749101580.537 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-05T05:33:05.233Z,1749101585.233 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003964
CHAN A1 (24V): 0.001949
CHAN A2 (12V): -0.014237
CHAN A3 (5V): -0.007766
CHAN B0 (3.3V): -0.002732
CHAN B1 (3.15aV): -0.001762
CHAN B2 (3.15bV): -0.001834
CHAN B3 (GND): 0.041872
OPEN: 0.011938
Full Scale: +/- 1 mA
2025-06-05T05:38:05.377Z,1749101885.377 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.3,,+00686,-00046,+00000,I
2025-06-05T05:38:05.378Z,1749101885.378 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.3,,+00686,-00046,+00000,I
2025-06-05T05:38:35.684Z,1749101915.684 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.3,00000,I
2025-06-05T05:38:35.684Z,1749101915.684 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.3,00000,I
2025-06-05T05:45:05.154Z,1749102305.154 [ElevatorServo](FAULT): Overload Error
2025-06-05T05:45:05.154Z,1749102305.154 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T05:45:05.155Z,1749102305.155 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T05:45:05.368Z,1749102305.368 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T05:45:05.532Z,1749102305.532 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T05:45:05.533Z,1749102305.533 [ElevatorServo](INFO): Powering down
2025-06-05T05:45:06.326Z,1749102306.326 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T05:45:06.443Z,1749102306.443 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T05:45:06.637Z,1749102306.637 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T05:45:06.638Z,1749102306.638 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T05:45:51.574Z,1749102351.574 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T05:45:51.574Z,1749102351.574 [DVL_micro](ERROR): Failed to parse:
:BI,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T05:51:11.510Z,1749102671.510 [DVL_micro](ERROR): Failed to parse:
:WI,-00065,+00651,+00110,+00000,A
2025-06-05T05:51:13.518Z,1749102673.518 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T05:51:15.538Z,1749102675.538 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-06-05T05:51:17.556Z,1749102677.556 [DVL_micro](ERROR): Failed to parse:
:SA,-20.69,-01.53,277.5
2025-06-05T05:51:18.354Z,1749102678.354 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-06-05T05:51:18.354Z,1749102678.354 [DVL_micro] Communications Fault, FailCount= 1
2025-06-05T05:51:18.354Z,1749102678.354 [DVL_micro](ERROR): Communications Fault
2025-06-05T05:51:18.553Z,1749102678.553 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-05T05:51:18.851Z,1749102678.851 [DVL_micro](INFO): Powering down
2025-06-05T05:51:19.757Z,1749102679.757 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-05T05:51:19.758Z,1749102679.758 [DVL_micro] No Fault, FailCount= 1
2025-06-05T05:54:42.202Z,1749102882.202 [ElevatorServo](FAULT): Overload Error
2025-06-05T05:54:42.202Z,1749102882.202 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T05:54:42.203Z,1749102882.203 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T05:54:42.269Z,1749102882.269 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T05:54:42.478Z,1749102882.478 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T05:54:42.479Z,1749102882.479 [ElevatorServo](INFO): Powering down
2025-06-05T05:54:43.295Z,1749102883.295 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T05:54:43.415Z,1749102883.415 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T05:54:43.569Z,1749102883.569 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T05:54:43.569Z,1749102883.569 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T05:59:48.171Z,1749103188.171 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,00.00
2025-06-05T05:59:48.171Z,1749103188.171 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,00.00
2025-06-05T06:01:39.383Z,1749103299.383 [DVL_micro](ERROR): Failed to parse:
:SA,-03.79,+02.08,267.0
2025-06-05T06:01:45.015Z,1749103305.015 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-06-05T06:04:26.236Z,1749103466.236 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T06:04:26.237Z,1749103466.237 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T06:04:26.240Z,1749103466.240 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T06:07:59.916Z,1749103679.916 [DVL_micro](ERROR): Failed to parse:

2025-06-05T06:08:43.222Z,1749103723.222 [keepstation:SurfaceComms] Running Loop=1
2025-06-05T06:08:43.223Z,1749103723.223 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-05T06:08:43.223Z,1749103723.223 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-05T06:08:43.223Z,1749103723.223 [keepstation:SurfaceComms:A] Stopped
2025-06-05T06:08:43.223Z,1749103723.223 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-05T06:08:43.223Z,1749103723.223 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-05T06:08:43.225Z,1749103723.225 [keepstation:NeedComms] Running Loop=1
2025-06-05T06:08:43.225Z,1749103723.225 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T06:08:43.225Z,1749103723.225 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T06:08:43.225Z,1749103723.225 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T06:08:43.226Z,1749103723.226 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T06:08:43.226Z,1749103723.226 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T06:08:43.227Z,1749103723.227 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T06:08:43.227Z,1749103723.227 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T06:08:43.227Z,1749103723.227 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T06:08:43.228Z,1749103723.228 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T06:08:43.228Z,1749103723.228 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T06:08:43.230Z,1749103723.230 [keepstation:NeedComms:A](INFO): last time_fix was:  1749100122.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T06:08:43.230Z,1749103723.230 [keepstation:NeedComms:A] Stopped
2025-06-05T06:08:43.646Z,1749103723.646 [keepstation:TransitToStation:Dive] Stopped
2025-06-05T06:08:43.646Z,1749103723.646 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-05T06:08:43.646Z,1749103723.646 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-05T06:08:43.647Z,1749103723.647 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-05T06:08:43.647Z,1749103723.647 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-05T06:08:43.647Z,1749103723.647 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-05T06:08:43.648Z,1749103723.648 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-05T06:09:47.016Z,1749103787.016 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T06:09:47.459Z,1749103787.459 [Radio_Surface](INFO): Powering up
2025-06-05T06:09:53.675Z,1749103793.675 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-05T06:10:23.795Z,1749103823.795 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:10:35.066Z,1749103835.066 [NAL9602](INFO): SBD MO Status=2, MOMSN=21043, MT Status=2, MTMSN=0
2025-06-05T06:10:35.066Z,1749103835.066 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T06:10:54.927Z,1749103854.927 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:11:26.050Z,1749103886.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:11:27.188Z,1749103887.188 [NAL9602](INFO): SBD MO Status=2, MOMSN=21043, MT Status=2, MTMSN=0
2025-06-05T06:11:27.189Z,1749103887.189 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T06:11:54.652Z,1749103914.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=21043, MT Status=2, MTMSN=0
2025-06-05T06:11:54.652Z,1749103914.652 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T06:11:57.179Z,1749103917.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:12:06.603Z,1749103926.603 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003165
2025-06-05T06:12:55.685Z,1749103975.685 [NAL9602](INFO): SBD MO Status=0, MOMSN=21043, MT Status=0, MTMSN=0
2025-06-05T06:12:55.685Z,1749103975.685 [NAL9602](INFO): No messages in MT queue
2025-06-05T06:12:56.902Z,1749103976.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061256.00,A,3647.30044,N,12201.31692,W,0.661,127.27,050625,,,D*71
2025-06-05T06:12:56.904Z,1749103976.904 [NAL9602](INFO): GPS fix at 20250605T061256: (36.788341, -122.021949)
2025-06-05T06:12:56.916Z,1749103976.916 [UniversalFixResidualReporter](INFO): Fix residual: 45.2 %DT, over the last 2373.0 m. Residual distance 1072.6 m at bearing -64.4 degrees. Fix at (36.7883, -122.0219) with 1146.7 m made good.
2025-06-05T06:12:56.920Z,1749103976.920 [keepstation:NeedComms:C] Stopped
2025-06-05T06:12:56.920Z,1749103976.920 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T06:13:04.566Z,1749103984.566 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250605T040200/Courier0003.lzma
2025-06-05T06:13:05.568Z,1749103985.568 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Courier0003.lzma.bak
2025-06-05T06:13:05.568Z,1749103985.568 [DataOverHttps](INFO): SBD MOMSN=25099898
2025-06-05T06:13:22.452Z,1749104002.452 [DataOverHttps](INFO): Sending 1010 bytes from file Logs/20250605T040200/Express0004.lzma
2025-06-05T06:13:28.472Z,1749104008.472 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Express0004.lzma.bak
2025-06-05T06:13:28.472Z,1749104008.472 [DataOverHttps](INFO): SBD MOMSN=25099901
2025-06-05T06:13:29.616Z,1749104009.616 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T06:13:30.047Z,1749104010.047 [keepstation:NeedComms:D] Stopped
2025-06-05T06:13:30.047Z,1749104010.047 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T06:13:32.109Z,1749104012.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061331.00,A,3647.29600,N,12201.31252,W,0.117,142.60,050625,,,D*71
2025-06-05T06:13:32.132Z,1749104012.132 [NAL9602](INFO): GPS fix at 20250605T061331: (36.788267, -122.021875)
2025-06-05T06:13:32.202Z,1749104012.202 [keepstation:NeedComms:E] Stopped
2025-06-05T06:13:32.211Z,1749104012.211 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T06:13:32.211Z,1749104012.211 [keepstation:NeedComms] Stopped
2025-06-05T06:13:32.211Z,1749104012.211 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T06:13:32.211Z,1749104012.211 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T06:13:32.211Z,1749104012.211 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T06:13:32.501Z,1749104012.501 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-05T06:13:32.501Z,1749104012.501 [keepstation:SurfaceComms:B] Stopped
2025-06-05T06:13:32.501Z,1749104012.501 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T06:13:32.501Z,1749104012.501 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-05T06:13:32.501Z,1749104012.501 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-05T06:13:32.501Z,1749104012.501 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-05T06:13:32.993Z,1749104012.993 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-05T06:13:32.993Z,1749104012.993 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-05T06:13:32.993Z,1749104012.993 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-05T06:13:32.993Z,1749104012.993 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-05T06:13:32.993Z,1749104012.993 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-05T06:13:32.994Z,1749104012.994 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-05T06:13:33.355Z,1749104013.355 [keepstation:SurfaceComms:setStation] Stopped
2025-06-05T06:13:33.355Z,1749104013.355 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-05T06:13:33.355Z,1749104013.355 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-05T06:13:33.355Z,1749104013.355 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-05T06:13:33.356Z,1749104013.356 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-05T06:13:33.356Z,1749104013.356 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-05T06:13:33.816Z,1749104013.816 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-05T06:13:33.816Z,1749104013.816 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-05T06:13:33.816Z,1749104013.816 [keepstation:SurfaceComms:setSink] Stopped
2025-06-05T06:13:33.816Z,1749104013.816 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-05T06:13:33.816Z,1749104013.816 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-05T06:13:33.816Z,1749104013.816 [keepstation:SurfaceComms] Stopped
2025-06-05T06:13:33.817Z,1749104013.817 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T06:13:34.132Z,1749104014.132 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-05T06:13:34.132Z,1749104014.132 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-05T06:13:34.132Z,1749104014.132 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-05T06:13:34.132Z,1749104014.132 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T06:13:34.133Z,1749104014.133 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T06:13:34.142Z,1749104014.142 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-05T06:13:34.142Z,1749104014.142 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T06:13:34.142Z,1749104014.142 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-05T06:13:34.143Z,1749104014.143 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-05T06:13:40.951Z,1749104020.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=21044, MT Status=0, MTMSN=0
2025-06-05T06:13:40.951Z,1749104020.951 [NAL9602](INFO): No messages in MT queue
2025-06-05T06:14:11.635Z,1749104051.635 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T06:15:01.075Z,1749104101.075 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-05T06:15:32.214Z,1749104132.214 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:16:03.339Z,1749104163.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:16:27.538Z,1749104187.538 [Radio_Surface](INFO): Powering down
2025-06-05T06:16:34.462Z,1749104194.462 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T06:16:35.467Z,1749104195.467 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T06:17:08.619Z,1749104228.619 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.339401
2025-06-05T06:17:53.059Z,1749104273.059 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-05T06:17:53.060Z,1749104273.060 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-05T06:17:53.060Z,1749104273.060 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T06:17:53.060Z,1749104273.060 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T06:17:53.471Z,1749104273.471 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-05T06:17:53.877Z,1749104273.877 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T06:17:53.877Z,1749104273.877 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-05T06:17:53.877Z,1749104273.877 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-05T06:17:53.877Z,1749104273.877 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T06:17:53.877Z,1749104273.877 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T06:17:53.877Z,1749104273.877 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T06:17:53.878Z,1749104273.878 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T06:17:53.878Z,1749104273.878 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-05T06:17:53.878Z,1749104273.878 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T06:17:53.878Z,1749104273.878 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-05T06:17:53.879Z,1749104273.879 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-05T06:17:53.879Z,1749104273.879 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-05T06:17:53.879Z,1749104273.879 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T06:17:53.879Z,1749104273.879 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T06:17:53.880Z,1749104273.880 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-05T06:19:59.218Z,1749104399.218 [ElevatorServo](FAULT): Overload Error
2025-06-05T06:19:59.218Z,1749104399.218 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T06:19:59.218Z,1749104399.218 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T06:19:59.434Z,1749104399.434 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T06:19:59.591Z,1749104399.591 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T06:19:59.591Z,1749104399.591 [ElevatorServo](INFO): Powering down
2025-06-05T06:20:00.381Z,1749104400.381 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T06:20:00.495Z,1749104400.495 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T06:20:00.566Z,1749104400.566 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T06:20:00.566Z,1749104400.566 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T06:35:26.658Z,1749105326.658 [ElevatorServo](FAULT): Overload Error
2025-06-05T06:35:26.658Z,1749105326.658 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T06:35:26.658Z,1749105326.658 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T06:35:26.749Z,1749105326.749 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T06:35:26.931Z,1749105326.931 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T06:35:26.932Z,1749105326.932 [ElevatorServo](INFO): Powering down
2025-06-05T06:35:27.720Z,1749105327.720 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T06:35:27.836Z,1749105327.836 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T06:35:27.973Z,1749105327.973 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T06:35:27.973Z,1749105327.973 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T06:56:43.496Z,1749106603.496 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00113,+00583,-0003+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T06:56:43.496Z,1749106603.496 [DVL_micro](ERROR): Failed to parse:
:BI,-00113,+00583,-0003+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T07:12:04.938Z,1749107524.938 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T07:12:04.943Z,1749107524.943 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T07:12:04.946Z,1749107524.946 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T07:13:32.335Z,1749107612.335 [keepstation:SurfaceComms] Running Loop=1
2025-06-05T07:13:32.335Z,1749107612.335 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-05T07:13:32.336Z,1749107612.336 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-05T07:13:32.336Z,1749107612.336 [keepstation:SurfaceComms:A] Stopped
2025-06-05T07:13:32.336Z,1749107612.336 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-05T07:13:32.336Z,1749107612.336 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-05T07:13:32.338Z,1749107612.338 [keepstation:NeedComms] Running Loop=1
2025-06-05T07:13:32.338Z,1749107612.338 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T07:13:32.338Z,1749107612.338 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T07:13:32.338Z,1749107612.338 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T07:13:32.339Z,1749107612.339 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T07:13:32.339Z,1749107612.339 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T07:13:32.340Z,1749107612.340 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T07:13:32.340Z,1749107612.340 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T07:13:32.340Z,1749107612.340 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T07:13:32.341Z,1749107612.341 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T07:13:32.341Z,1749107612.341 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T07:13:32.363Z,1749107612.363 [keepstation:NeedComms:A](INFO): last time_fix was:  1749104011.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T07:13:32.363Z,1749107612.363 [keepstation:NeedComms:A] Stopped
2025-06-05T07:13:32.742Z,1749107612.742 [keepstation:TransitToStation:Dive] Stopped
2025-06-05T07:13:32.742Z,1749107612.742 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-05T07:13:32.742Z,1749107612.742 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-05T07:13:32.742Z,1749107612.742 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-05T07:13:32.743Z,1749107612.743 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-05T07:13:32.744Z,1749107612.744 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-05T07:14:24.743Z,1749107664.743 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T07:14:25.398Z,1749107665.398 [Radio_Surface](INFO): Powering up
2025-06-05T07:14:30.815Z,1749107670.815 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-05T07:15:00.966Z,1749107700.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:15:01.091Z,1749107701.091 [NAL9602](INFO): SBD MO Status=2, MOMSN=21045, MT Status=2, MTMSN=0
2025-06-05T07:15:01.091Z,1749107701.091 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T07:15:25.317Z,1749107725.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=21045, MT Status=2, MTMSN=0
2025-06-05T07:15:25.317Z,1749107725.317 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T07:15:32.135Z,1749107732.135 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:15:48.749Z,1749107748.749 [NAL9602](INFO): SBD MO Status=2, MOMSN=21045, MT Status=2, MTMSN=0
2025-06-05T07:15:48.750Z,1749107748.750 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T07:16:03.263Z,1749107763.263 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:16:34.386Z,1749107794.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:16:43.756Z,1749107803.756 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003104
2025-06-05T07:17:10.357Z,1749107830.357 [NAL9602](INFO): SBD MO Status=0, MOMSN=21045, MT Status=0, MTMSN=0
2025-06-05T07:17:10.357Z,1749107830.357 [NAL9602](INFO): No messages in MT queue
2025-06-05T07:17:11.574Z,1749107831.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071710.00,A,3647.42953,N,12201.97685,W,1.108,119.70,050625,,,D*71
2025-06-05T07:17:11.576Z,1749107831.576 [NAL9602](INFO): GPS fix at 20250605T071710: (36.790492, -122.032948)
2025-06-05T07:17:11.590Z,1749107831.590 [UniversalFixResidualReporter](INFO): Fix residual: 55.7 %DT, over the last 2452.3 m. Residual distance 1367.0 m at bearing -63.6 degrees. Fix at (36.7905, -122.0329) with 1016.6 m made good.
2025-06-05T07:17:11.594Z,1749107831.594 [keepstation:NeedComms:C] Stopped
2025-06-05T07:17:11.595Z,1749107831.595 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T07:17:12.647Z,1749107832.647 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20250605T040200/Courier0006.lzma
2025-06-05T07:17:13.552Z,1749107833.552 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Courier0006.lzma.bak
2025-06-05T07:17:13.552Z,1749107833.552 [DataOverHttps](INFO): SBD MOMSN=25100074
2025-06-05T07:17:25.715Z,1749107845.715 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+13.5,000,+00000,A
2025-06-05T07:17:25.715Z,1749107845.715 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+13.5,000,+00000,A
2025-06-05T07:17:29.960Z,1749107849.960 [DataOverHttps](INFO): Sending 696 bytes from file Logs/20250605T040200/Express0007.lzma
2025-06-05T07:17:30.960Z,1749107850.960 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Express0007.lzma.bak
2025-06-05T07:17:30.960Z,1749107850.960 [DataOverHttps](INFO): SBD MOMSN=25100077
2025-06-05T07:17:32.643Z,1749107852.643 [keepstation:NeedComms:D] Stopped
2025-06-05T07:17:32.643Z,1749107852.643 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T07:17:34.609Z,1749107854.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071733.00,A,3647.42653,N,12201.97236,W,0.641,158.44,050625,,,D*7A
2025-06-05T07:17:34.616Z,1749107854.616 [NAL9602](INFO): GPS fix at 20250605T071733: (36.790442, -122.032873)
2025-06-05T07:17:34.677Z,1749107854.677 [keepstation:NeedComms:E] Stopped
2025-06-05T07:17:34.719Z,1749107854.719 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T07:17:34.719Z,1749107854.719 [keepstation:NeedComms] Stopped
2025-06-05T07:17:34.719Z,1749107854.719 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T07:17:34.719Z,1749107854.719 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T07:17:34.719Z,1749107854.719 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T07:17:35.077Z,1749107855.077 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-05T07:17:35.077Z,1749107855.077 [keepstation:SurfaceComms:B] Stopped
2025-06-05T07:17:35.077Z,1749107855.077 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T07:17:35.077Z,1749107855.077 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-05T07:17:35.077Z,1749107855.077 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-05T07:17:35.077Z,1749107855.077 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-05T07:17:35.457Z,1749107855.457 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-05T07:17:35.457Z,1749107855.457 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-05T07:17:35.457Z,1749107855.457 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-05T07:17:35.457Z,1749107855.457 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-05T07:17:35.457Z,1749107855.457 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-05T07:17:35.457Z,1749107855.457 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-05T07:17:35.886Z,1749107855.886 [keepstation:SurfaceComms:setStation] Stopped
2025-06-05T07:17:35.886Z,1749107855.886 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-05T07:17:35.886Z,1749107855.886 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-05T07:17:35.907Z,1749107855.907 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-05T07:17:35.907Z,1749107855.907 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-05T07:17:35.907Z,1749107855.907 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms:setSink] Stopped
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms] Stopped
2025-06-05T07:17:36.248Z,1749107856.248 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T07:17:36.712Z,1749107856.712 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-05T07:17:36.713Z,1749107856.713 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-05T07:17:36.715Z,1749107856.715 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-05T07:17:36.715Z,1749107856.715 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-05T07:17:36.716Z,1749107856.716 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T07:17:36.716Z,1749107856.716 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T07:17:36.716Z,1749107856.716 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T07:17:36.716Z,1749107856.716 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T07:17:36.716Z,1749107856.716 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T07:17:36.717Z,1749107856.717 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T07:17:36.717Z,1749107856.717 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T07:17:36.717Z,1749107856.717 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T07:17:36.717Z,1749107856.717 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T07:17:36.717Z,1749107856.717 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-05T07:17:36.717Z,1749107856.717 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T07:17:36.718Z,1749107856.718 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-05T07:17:36.718Z,1749107856.718 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-05T07:18:06.541Z,1749107886.541 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T07:19:33.143Z,1749107973.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:20:04.267Z,1749108004.267 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:20:16.474Z,1749108016.474 [Radio_Surface](INFO): Powering down
2025-06-05T07:20:35.406Z,1749108035.406 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T07:20:36.411Z,1749108036.411 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T07:21:39.459Z,1749108099.459 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-05T07:21:39.459Z,1749108099.459 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-05T07:21:39.460Z,1749108099.460 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T07:21:39.460Z,1749108099.460 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T07:21:39.852Z,1749108099.852 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-05T07:21:40.250Z,1749108100.250 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T07:21:40.251Z,1749108100.251 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-05T07:21:40.252Z,1749108100.252 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T07:21:40.253Z,1749108100.253 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-05T07:21:41.671Z,1749108101.671 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743271
2025-06-05T07:26:47.882Z,1749108407.882 [ElevatorServo](FAULT): Overload Error
2025-06-05T07:26:47.882Z,1749108407.882 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T07:26:47.882Z,1749108407.882 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T07:26:48.001Z,1749108408.001 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T07:26:48.260Z,1749108408.260 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T07:26:48.260Z,1749108408.260 [ElevatorServo](INFO): Powering down
2025-06-05T07:26:49.071Z,1749108409.071 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T07:26:49.192Z,1749108409.192 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T07:26:49.261Z,1749108409.261 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T07:26:49.262Z,1749108409.262 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T07:29:34.269Z,1749108574.269 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.6,0000.0,1489.0,I
2025-06-05T07:29:34.269Z,1749108574.269 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.6,0000.0,1489.0,I
2025-06-05T07:35:52.847Z,1749108952.847 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00044,+000000.00,9999.99,000.00
2025-06-05T07:35:52.847Z,1749108952.847 [DVL_micro](ERROR): Failed to parse:
:BI,-00044,+000000.00,9999.99,000.00
2025-06-05T07:43:01.162Z,1749109381.162 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-06-05T07:50:38.125Z,1749109838.125 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000621,-00065,+00000,A
2025-06-05T07:50:38.125Z,1749109838.125 [DVL_micro](ERROR): Failed to parse:
:TS,000621,-00065,+00000,A
2025-06-05T08:04:55.524Z,1749110695.524 [ElevatorServo](FAULT): Overload Error
2025-06-05T08:04:55.524Z,1749110695.524 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T08:04:55.524Z,1749110695.524 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T08:04:55.637Z,1749110695.637 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T08:04:55.902Z,1749110695.902 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T08:04:55.902Z,1749110695.902 [ElevatorServo](INFO): Powering down
2025-06-05T08:04:56.740Z,1749110696.740 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T08:04:56.859Z,1749110696.859 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T08:04:56.937Z,1749110696.937 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T08:04:56.938Z,1749110696.938 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T08:10:28.585Z,1749111028.585 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00237,+0,9999.99,000.00
2025-06-05T08:10:28.585Z,1749111028.585 [DVL_micro](ERROR): Failed to parse:
:BI,-00237,+0,9999.99,000.00
2025-06-05T08:13:11.427Z,1749111191.427 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.798149,-122.055191
2025-06-05T08:13:11.428Z,1749111191.428 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-06-05T08:13:11.428Z,1749111191.428 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-06-05T08:13:11.428Z,1749111191.428 [keepstation:TransitToStation:C] Running Loop=1
2025-06-05T08:13:11.845Z,1749111191.845 [keepstation:TransitToStation:C] Stopped
2025-06-05T08:13:11.845Z,1749111191.845 [keepstation:TransitToStation:D] Running Loop=1
2025-06-05T08:13:11.845Z,1749111191.845 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T08:13:11.845Z,1749111191.845 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-05T08:13:11.846Z,1749111191.846 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T08:13:11.846Z,1749111191.846 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T08:13:11.846Z,1749111191.846 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-05T08:13:11.846Z,1749111191.846 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T08:13:11.846Z,1749111191.846 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-05T08:13:11.847Z,1749111191.847 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T08:13:11.847Z,1749111191.847 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T08:13:11.847Z,1749111191.847 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-05T08:13:11.848Z,1749111191.848 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-05T08:13:11.849Z,1749111191.849 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-05T08:13:12.237Z,1749111192.237 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-06-05T08:13:12.237Z,1749111192.237 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position 31.28 mm.
2025-06-05T08:13:12.243Z,1749111192.243 [keepstation:TransitToStation:D] Stopped
2025-06-05T08:13:12.243Z,1749111192.243 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-06-05T08:13:12.243Z,1749111192.243 [keepstation:TransitToStation] Stopped
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:TransitToStation:Dive] Stopped
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:OnStation] Running Loop=1
2025-06-05T08:13:12.244Z,1749111192.244 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-06-05T08:13:12.657Z,1749111192.657 [keepstation:NeedComms] Running Loop=1
2025-06-05T08:13:12.658Z,1749111192.658 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T08:13:12.658Z,1749111192.658 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T08:13:12.658Z,1749111192.658 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T08:13:12.658Z,1749111192.658 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T08:13:12.659Z,1749111192.659 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T08:13:12.659Z,1749111192.659 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T08:13:12.659Z,1749111192.659 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T08:13:12.660Z,1749111192.660 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T08:13:12.660Z,1749111192.660 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T08:13:12.660Z,1749111192.660 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T08:13:12.662Z,1749111192.662 [keepstation:NeedComms:A](INFO): last time_fix was:  1749107853.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T08:13:12.682Z,1749111192.682 [keepstation:NeedComms:A] Stopped
2025-06-05T08:14:13.856Z,1749111253.856 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T08:14:14.526Z,1749111254.526 [Radio_Surface](INFO): Powering up
2025-06-05T08:14:20.734Z,1749111260.734 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-05T08:14:50.854Z,1749111290.854 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:15:22.003Z,1749111322.003 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:15:51.948Z,1749111351.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=21046, MT Status=2, MTMSN=0
2025-06-05T08:15:51.948Z,1749111351.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T08:15:53.126Z,1749111353.126 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:16:21.516Z,1749111381.516 [NAL9602](INFO): SBD MO Status=2, MOMSN=21046, MT Status=2, MTMSN=0
2025-06-05T08:16:21.516Z,1749111381.516 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T08:16:24.263Z,1749111384.263 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:16:26.689Z,1749111386.689 [DVL_micro](ERROR): Failed to parse:
:SA,-01.56,+00.59,232.4
2025-06-05T08:16:30.744Z,1749111390.744 [NAL9602](INFO): SBD MO Status=0, MOMSN=21046, MT Status=0, MTMSN=0
2025-06-05T08:16:30.744Z,1749111390.744 [NAL9602](INFO): No messages in MT queue
2025-06-05T08:16:31.961Z,1749111391.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081631.00,A,3647.34158,N,12202.54553,W,1.963,142.51,050625,,,D*72
2025-06-05T08:16:31.968Z,1749111391.968 [NAL9602](INFO): GPS fix at 20250605T081631: (36.789026, -122.042425)
2025-06-05T08:16:32.013Z,1749111392.013 [UniversalFixResidualReporter](INFO): Fix residual: 67.3 %DT, over the last 2317.4 m. Residual distance 1560.4 m at bearing -49.3 degrees. Fix at (36.7890, -122.0424) with 865.1 m made good.
2025-06-05T08:16:32.019Z,1749111392.019 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-05T08:16:32.026Z,1749111392.026 [keepstation:NeedComms:C] Stopped
2025-06-05T08:16:32.027Z,1749111392.027 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T08:16:48.425Z,1749111408.425 [NAL9602](INFO): SBD MO Status=1, MOMSN=21047, MT Status=0, MTMSN=0
2025-06-05T08:16:48.478Z,1749111408.478 [NAL9602](INFO): Sent 85 bytes from file Logs/20250605T040200/Courier0009.lzma
2025-06-05T08:16:48.479Z,1749111408.479 [NAL9602](INFO): Packets left to send: 0
2025-06-05T08:16:55.386Z,1749111415.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:17:05.060Z,1749111425.060 [NAL9602](INFO): SBD MO Status=1, MOMSN=21048, MT Status=0, MTMSN=0
2025-06-05T08:17:05.123Z,1749111425.123 [NAL9602](INFO): Sent 332 bytes from file Logs/20250605T040200/Express0010.lzma
2025-06-05T08:17:05.123Z,1749111425.123 [NAL9602](INFO): Packets left to send: 1
2025-06-05T08:17:15.837Z,1749111435.837 [NAL9602](INFO): SBD MO Status=1, MOMSN=21049, MT Status=0, MTMSN=0
2025-06-05T08:17:15.890Z,1749111435.890 [NAL9602](INFO): Sent 275 bytes from file Logs/20250605T040200/Express0010.lzma
2025-06-05T08:17:15.891Z,1749111435.891 [NAL9602](INFO): Packets left to send: 0
2025-06-05T08:17:24.772Z,1749111444.772 [NAL9602](INFO): SBD MO Status=0, MOMSN=21050, MT Status=0, MTMSN=0
2025-06-05T08:17:24.862Z,1749111444.862 [keepstation:NeedComms:D] Stopped
2025-06-05T08:17:24.862Z,1749111444.862 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T08:17:26.511Z,1749111446.511 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:17:27.194Z,1749111447.194 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081726.00,A,3647.33461,N,12202.53723,W,0.253,115.85,050625,,,D*7D
2025-06-05T08:17:27.196Z,1749111447.196 [NAL9602](INFO): GPS fix at 20250605T081726: (36.788910, -122.042287)
2025-06-05T08:17:27.211Z,1749111447.211 [keepstation:NeedComms:E] Stopped
2025-06-05T08:17:27.212Z,1749111447.212 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T08:17:27.212Z,1749111447.212 [keepstation:NeedComms] Stopped
2025-06-05T08:17:27.212Z,1749111447.212 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T08:17:27.212Z,1749111447.212 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T08:17:27.212Z,1749111447.212 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:OnStation] Stopped
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:KeepStation] Running Loop=1
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-05T08:17:27.631Z,1749111447.631 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-05T08:17:27.632Z,1749111447.632 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-05T08:17:27.632Z,1749111447.632 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-06-05T08:17:27.632Z,1749111447.632 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-06-05T08:17:28.089Z,1749111448.089 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-05T08:17:57.635Z,1749111477.635 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:17:59.531Z,1749111479.531 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T08:18:02.158Z,1749111482.158 [ElevatorServo](FAULT): Overload Error
2025-06-05T08:18:02.158Z,1749111482.158 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T08:18:02.158Z,1749111482.158 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T08:18:02.174Z,1749111482.174 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T08:18:02.415Z,1749111482.415 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T08:18:02.415Z,1749111482.415 [ElevatorServo](INFO): Powering down
2025-06-05T08:18:03.219Z,1749111483.219 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T08:18:03.340Z,1749111483.340 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T08:18:03.419Z,1749111483.419 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T08:18:03.419Z,1749111483.419 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T08:18:28.759Z,1749111508.759 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:18:47.266Z,1749111527.266 [ElevatorServo](FAULT): Overload Error
2025-06-05T08:18:47.266Z,1749111527.266 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T08:18:47.266Z,1749111527.266 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T08:18:47.455Z,1749111527.455 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T08:18:47.653Z,1749111527.653 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T08:18:47.653Z,1749111527.653 [ElevatorServo](INFO): Powering down
2025-06-05T08:18:48.499Z,1749111528.499 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T08:18:48.619Z,1749111528.619 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T08:18:48.698Z,1749111528.698 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T08:18:48.698Z,1749111528.698 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T08:18:59.891Z,1749111539.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:19:04.622Z,1749111544.622 [ElevatorServo](FAULT): Overload Error
2025-06-05T08:19:04.623Z,1749111544.623 [ElevatorServo] Hardware Fault, FailCount= 2
2025-06-05T08:19:04.623Z,1749111544.623 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T08:19:04.753Z,1749111544.753 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T08:19:05.001Z,1749111545.001 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T08:19:05.001Z,1749111545.001 [ElevatorServo](INFO): Powering down
2025-06-05T08:19:06.005Z,1749111546.005 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T08:19:06.005Z,1749111546.005 [ElevatorServo] No Fault, FailCount= 2
2025-06-05T08:19:06.211Z,1749111546.211 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T08:19:06.212Z,1749111546.212 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T08:19:31.039Z,1749111571.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:19:42.262Z,1749111582.262 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T08:19:42.263Z,1749111582.263 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T08:19:42.266Z,1749111582.266 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T08:20:02.179Z,1749111602.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:20:33.323Z,1749111633.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T08:20:34.327Z,1749111634.327 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T08:20:34.375Z,1749111634.375 [Radio_Surface](INFO): Powering down
2025-06-05T08:53:43.845Z,1749113623.845 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-06-05T09:00:54.089Z,1749114054.089 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.7,0000.0,1489.0,000
2025-06-05T09:17:27.547Z,1749115047.547 [keepstation:SurfaceComms] Running Loop=1
2025-06-05T09:17:27.547Z,1749115047.547 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-05T09:17:27.547Z,1749115047.547 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-05T09:17:27.548Z,1749115047.548 [keepstation:SurfaceComms:A] Stopped
2025-06-05T09:17:27.548Z,1749115047.548 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-05T09:17:27.548Z,1749115047.548 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-05T09:17:27.942Z,1749115047.942 [keepstation:KeepStation:Dive] Stopped
2025-06-05T09:17:27.942Z,1749115047.942 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-05T09:17:27.942Z,1749115047.942 [keepstation:KeepStation:Dive:A] Stopped
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-05T09:17:27.943Z,1749115047.943 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-05T09:17:27.945Z,1749115047.945 [keepstation:NeedComms] Running Loop=1
2025-06-05T09:17:27.945Z,1749115047.945 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T09:17:27.945Z,1749115047.945 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T09:17:27.945Z,1749115047.945 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T09:17:27.946Z,1749115047.946 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T09:17:27.962Z,1749115047.962 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T09:17:27.963Z,1749115047.963 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T09:17:27.963Z,1749115047.963 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T09:17:27.963Z,1749115047.963 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T09:17:27.964Z,1749115047.964 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T09:17:27.964Z,1749115047.964 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T09:17:27.966Z,1749115047.966 [keepstation:NeedComms:A](INFO): last time_fix was:  1749111446.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T09:17:27.966Z,1749115047.966 [keepstation:NeedComms:A] Stopped
2025-06-05T09:18:38.357Z,1749115118.357 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T09:18:38.882Z,1749115118.882 [Radio_Surface](INFO): Powering up
2025-06-05T09:18:44.815Z,1749115124.815 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-05T09:18:58.121Z,1749115138.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=21051, MT Status=2, MTMSN=0
2025-06-05T09:18:58.122Z,1749115138.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T09:19:14.935Z,1749115154.935 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T09:19:36.920Z,1749115176.920 [NAL9602](INFO): SBD MO Status=0, MOMSN=21051, MT Status=0, MTMSN=0
2025-06-05T09:19:36.921Z,1749115176.921 [NAL9602](INFO): No messages in MT queue
2025-06-05T09:19:38.116Z,1749115178.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091937.00,A,3647.47923,N,12202.72552,W,1.127,106.45,050625,,,A*77
2025-06-05T09:19:38.118Z,1749115178.118 [NAL9602](INFO): GPS fix at 20250605T091937: (36.791320, -122.045425)
2025-06-05T09:19:38.149Z,1749115178.149 [UniversalFixResidualReporter](INFO): Fix residual: 69.9 %DT, over the last 1649.0 m. Residual distance 1153.0 m at bearing -45.9 degrees. Fix at (36.7913, -122.0454) with 387.2 m made good.
2025-06-05T09:19:38.153Z,1749115178.153 [keepstation:NeedComms:C] Stopped
2025-06-05T09:19:38.153Z,1749115178.153 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T09:19:46.058Z,1749115186.058 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T09:20:00.816Z,1749115200.816 [NAL9602](INFO): SBD MO Status=1, MOMSN=21052, MT Status=0, MTMSN=0
2025-06-05T09:20:00.866Z,1749115200.866 [NAL9602](INFO): Sent 97 bytes from file Logs/20250605T040200/Courier0012.lzma
2025-06-05T09:20:00.867Z,1749115200.867 [NAL9602](INFO): Packets left to send: 0
2025-06-05T09:20:15.838Z,1749115215.838 [NAL9602](INFO): SBD MO Status=2, MOMSN=21053, MT Status=2, MTMSN=0
2025-06-05T09:20:15.838Z,1749115215.838 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T09:20:17.187Z,1749115217.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T09:20:48.331Z,1749115248.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T09:20:57.371Z,1749115257.371 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003100
2025-06-05T09:21:05.215Z,1749115265.215 [DataOverHttps](INFO): Sending 581 bytes from file Logs/20250605T040200/Express0013.lzma
2025-06-05T09:21:06.216Z,1749115266.216 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Express0013.lzma.bak
2025-06-05T09:21:06.216Z,1749115266.216 [DataOverHttps](INFO): SBD MOMSN=25100246
2025-06-05T09:21:07.725Z,1749115267.725 [keepstation:NeedComms:D] Stopped
2025-06-05T09:21:07.725Z,1749115267.725 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T09:21:09.713Z,1749115269.713 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092109.00,A,3647.47249,N,12202.70708,W,0.058,125.65,050625,,,A*72
2025-06-05T09:21:09.716Z,1749115269.716 [NAL9602](INFO): GPS fix at 20250605T092109: (36.791208, -122.045118)
2025-06-05T09:21:09.765Z,1749115269.765 [keepstation:NeedComms:E] Stopped
2025-06-05T09:21:09.766Z,1749115269.766 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T09:21:09.766Z,1749115269.766 [keepstation:NeedComms] Stopped
2025-06-05T09:21:09.766Z,1749115269.766 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T09:21:09.766Z,1749115269.766 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T09:21:09.774Z,1749115269.774 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T09:21:10.179Z,1749115270.179 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-05T09:21:10.179Z,1749115270.179 [keepstation:SurfaceComms:B] Stopped
2025-06-05T09:21:10.179Z,1749115270.179 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T09:21:10.180Z,1749115270.180 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-05T09:21:10.180Z,1749115270.180 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-05T09:21:10.180Z,1749115270.180 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-05T09:21:10.547Z,1749115270.547 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-05T09:21:10.547Z,1749115270.547 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-05T09:21:10.547Z,1749115270.547 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-05T09:21:10.547Z,1749115270.547 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-05T09:21:10.547Z,1749115270.547 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-05T09:21:10.547Z,1749115270.547 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-05T09:21:11.018Z,1749115271.018 [keepstation:SurfaceComms:setStation] Stopped
2025-06-05T09:21:11.019Z,1749115271.019 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-05T09:21:11.019Z,1749115271.019 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-05T09:21:11.019Z,1749115271.019 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-05T09:21:11.019Z,1749115271.019 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-05T09:21:11.019Z,1749115271.019 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-05T09:21:11.370Z,1749115271.370 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-05T09:21:11.370Z,1749115271.370 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-05T09:21:11.370Z,1749115271.370 [keepstation:SurfaceComms:setSink] Stopped
2025-06-05T09:21:11.371Z,1749115271.371 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-05T09:21:11.371Z,1749115271.371 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-05T09:21:11.371Z,1749115271.371 [keepstation:SurfaceComms] Stopped
2025-06-05T09:21:11.371Z,1749115271.371 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T09:21:11.810Z,1749115271.810 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-05T09:21:11.811Z,1749115271.811 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-05T09:21:11.811Z,1749115271.811 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-05T09:21:11.811Z,1749115271.811 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-05T09:21:11.811Z,1749115271.811 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T09:21:11.811Z,1749115271.811 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T09:21:11.812Z,1749115271.812 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T09:21:11.813Z,1749115271.813 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-05T09:21:11.813Z,1749115271.813 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T09:21:11.813Z,1749115271.813 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-05T09:21:11.813Z,1749115271.813 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-05T09:21:42.437Z,1749115302.437 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T09:23:39.643Z,1749115419.643 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T09:23:58.466Z,1749115438.466 [Radio_Surface](INFO): Powering down
2025-06-05T09:24:10.783Z,1749115450.783 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T09:24:11.787Z,1749115451.787 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T09:25:06.491Z,1749115506.491 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-05T09:25:06.491Z,1749115506.491 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-05T09:25:06.491Z,1749115506.491 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T09:25:06.492Z,1749115506.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-05T09:25:06.870Z,1749115506.870 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-05T09:25:06.870Z,1749115506.870 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T09:25:06.870Z,1749115506.870 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-05T09:25:06.870Z,1749115506.870 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-05T09:25:06.870Z,1749115506.870 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T09:25:06.871Z,1749115506.871 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-05T09:25:07.280Z,1749115507.280 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-05T09:25:07.280Z,1749115507.280 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T09:25:07.280Z,1749115507.280 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-05T09:25:07.280Z,1749115507.280 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T09:25:07.280Z,1749115507.280 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T09:25:07.281Z,1749115507.281 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-06-05T09:25:07.690Z,1749115507.690 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T09:25:07.694Z,1749115507.694 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-05T09:25:07.694Z,1749115507.694 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T09:25:07.694Z,1749115507.694 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T09:25:07.694Z,1749115507.694 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-05T09:25:07.695Z,1749115507.695 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T09:25:07.695Z,1749115507.695 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-05T09:25:07.695Z,1749115507.695 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-05T09:25:47.172Z,1749115547.172 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.791931
2025-06-05T09:27:18.607Z,1749115638.607 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T09:27:18.608Z,1749115638.608 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T09:27:18.611Z,1749115638.611 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T09:27:40.970Z,1749115660.970 [ElevatorServo](FAULT): Overload Error
2025-06-05T09:27:40.970Z,1749115660.970 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T09:27:40.970Z,1749115660.970 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T09:27:41.053Z,1749115661.053 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T09:27:41.263Z,1749115661.263 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T09:27:41.263Z,1749115661.263 [ElevatorServo](INFO): Powering down
2025-06-05T09:27:42.053Z,1749115662.053 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T09:27:42.167Z,1749115662.167 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T09:27:42.245Z,1749115662.245 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T09:27:42.246Z,1749115662.246 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T09:44:35.650Z,1749116675.650 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T09:46:36.526Z,1749116796.526 [ElevatorServo](FAULT): Overload Error
2025-06-05T09:46:36.527Z,1749116796.527 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T09:46:36.527Z,1749116796.527 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T09:46:36.730Z,1749116796.730 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T09:46:36.911Z,1749116796.911 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T09:46:36.912Z,1749116796.912 [ElevatorServo](INFO): Powering down
2025-06-05T09:46:37.692Z,1749116797.692 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T09:46:37.811Z,1749116797.811 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T09:46:37.901Z,1749116797.901 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T09:46:37.902Z,1749116797.902 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T09:53:29.147Z,1749117209.147 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-06-05T09:53:29.558Z,1749117209.558 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 31.28 mm.
2025-06-05T10:01:40.008Z,1749117700.008 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,0,1489.0,000
2025-06-05T10:01:40.008Z,1749117700.008 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,0,1489.0,000
2025-06-05T10:03:09.308Z,1749117789.308 [DVL_micro](ERROR): Failed to parse:
:WI,+00109,-00200,+00001,+00000,A
2025-06-05T10:03:10.928Z,1749117790.928 [DVL_micro](ERROR): Failed to parse:
:WI,+00170,-00040,-00074,+00000,A
2025-06-05T10:03:12.932Z,1749117792.932 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-06-05T10:03:14.951Z,1749117794.951 [DVL_micro](ERROR): Failed to parse:
:SA,-01.15,+00.76,316.5
2025-06-05T10:03:15.761Z,1749117795.761 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-06-05T10:03:15.761Z,1749117795.761 [DVL_micro] Communications Fault, FailCount= 1
2025-06-05T10:03:15.761Z,1749117795.761 [DVL_micro](ERROR): Communications Fault
2025-06-05T10:03:15.786Z,1749117795.786 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-05T10:03:16.255Z,1749117796.255 [DVL_micro](INFO): Powering down
2025-06-05T10:03:16.990Z,1749117796.990 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-05T10:03:16.990Z,1749117796.990 [DVL_micro] No Fault, FailCount= 1
2025-06-05T10:03:34.744Z,1749117814.744 [DVL_micro](ERROR): Failed to parse:
:SA,-00.69,+00.95,324.9
2025-06-05T10:21:10.011Z,1749118870.011 [keepstation:SurfaceComms] Running Loop=1
2025-06-05T10:21:10.011Z,1749118870.011 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-05T10:21:10.011Z,1749118870.011 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-05T10:21:10.011Z,1749118870.011 [keepstation:SurfaceComms:A] Stopped
2025-06-05T10:21:10.011Z,1749118870.011 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-05T10:21:10.012Z,1749118870.012 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-05T10:21:10.419Z,1749118870.419 [keepstation:KeepStation:Dive] Stopped
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:KeepStation:Dive:A] Stopped
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-05T10:21:10.420Z,1749118870.420 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-05T10:21:10.421Z,1749118870.421 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-05T10:21:10.422Z,1749118870.422 [keepstation:NeedComms] Running Loop=1
2025-06-05T10:21:10.423Z,1749118870.423 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T10:21:10.423Z,1749118870.423 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T10:21:10.423Z,1749118870.423 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T10:21:10.423Z,1749118870.423 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T10:21:10.424Z,1749118870.424 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T10:21:10.424Z,1749118870.424 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T10:21:10.424Z,1749118870.424 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T10:21:10.425Z,1749118870.425 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T10:21:10.425Z,1749118870.425 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T10:21:10.425Z,1749118870.425 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T10:21:10.428Z,1749118870.428 [keepstation:NeedComms:A](INFO): last time_fix was:  1749115269.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T10:21:10.428Z,1749118870.428 [keepstation:NeedComms:A] Stopped
2025-06-05T10:22:46.295Z,1749118966.295 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T10:22:46.722Z,1749118966.722 [Radio_Surface](INFO): Powering up
2025-06-05T10:22:52.347Z,1749118972.347 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-05T10:23:00.011Z,1749118980.011 [DVL_micro](ERROR): Failed to parse:
:WI,+00073,+00377,+00456,+00000,A
2025-06-05T10:23:22.471Z,1749119002.471 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:23:53.595Z,1749119033.595 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:23:53.755Z,1749119033.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=21053, MT Status=2, MTMSN=0
2025-06-05T10:23:53.755Z,1749119033.755 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:24:14.349Z,1749119054.349 [NAL9602](INFO): SBD MO Status=2, MOMSN=21053, MT Status=2, MTMSN=0
2025-06-05T10:24:14.349Z,1749119054.349 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:24:24.726Z,1749119064.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:24:31.741Z,1749119071.741 [NAL9602](INFO): SBD MO Status=2, MOMSN=21053, MT Status=2, MTMSN=0
2025-06-05T10:24:31.741Z,1749119071.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:24:55.858Z,1749119095.858 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:25:06.101Z,1749119106.101 [NAL9602](INFO): SBD MO Status=1, MOMSN=21053, MT Status=0, MTMSN=0
2025-06-05T10:25:06.101Z,1749119106.101 [NAL9602](INFO): No messages in MT queue
2025-06-05T10:25:07.273Z,1749119107.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102506.00,A,3647.39962,N,12202.59295,W,0.564,63.60,050625,,,D*49
2025-06-05T10:25:07.275Z,1749119107.275 [NAL9602](INFO): GPS fix at 20250605T102506: (36.789994, -122.043216)
2025-06-05T10:25:07.287Z,1749119107.287 [UniversalFixResidualReporter](INFO): Fix residual: 108.5 %DT, over the last 1308.5 m. Residual distance 1419.8 m at bearing -46.4 degrees. Fix at (36.7900, -122.0432) with 216.6 m made good.
2025-06-05T10:25:07.290Z,1749119107.290 [keepstation:NeedComms:C] Stopped
2025-06-05T10:25:07.290Z,1749119107.290 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T10:25:07.299Z,1749119107.299 [CBIT](CRITICAL): Navigation error has exceeded threshold. navErrorCriticalReporting is enabled.
2025-06-05T10:25:07.716Z,1749119107.716 [CommandExec](FAULT): Scheduling is paused for 5 commands
2025-06-05T10:25:07.716Z,1749119107.716 [CBIT](INFO): Critical error at 20250605T102507
2025-06-05T10:25:07.716Z,1749119107.716 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-06-05T10:25:08.121Z,1749119108.121 [MissionManager](INFO): MissionManager is completed.
2025-06-05T10:25:08.121Z,1749119108.121 [MissionManager](INFO): Uninitializing Mission keepstation
2025-06-05T10:25:08.122Z,1749119108.122 [keepstation] Stopped
2025-06-05T10:25:08.122Z,1749119108.122 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-06-05T10:25:08.122Z,1749119108.122 [keepstation:NeedComms] Stopped
2025-06-05T10:25:08.122Z,1749119108.122 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T10:25:08.126Z,1749119108.126 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T10:25:08.126Z,1749119108.126 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T10:25:08.126Z,1749119108.126 [keepstation:NeedComms:D] Stopped
2025-06-05T10:25:08.126Z,1749119108.126 [keepstation:StandardEnvelopes] Stopped
2025-06-05T10:25:08.126Z,1749119108.126 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:BackseatDriver] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:PowerOnly] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:PowerOnly:E.Wait] Stopped
2025-06-05T10:25:08.127Z,1749119108.127 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:H.Pitch] Stopped
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:SurfaceComms] Stopped
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:SurfaceComms:B] Stopped
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:DiveCmd] Stopped
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:KeepStation] Stopped
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-06-05T10:25:08.128Z,1749119108.128 [keepstation:KeepStation:B.Wait] Stopped
2025-06-05T10:25:08.129Z,1749119108.129 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2025-06-05T10:25:08.493Z,1749119108.493 [MissionManager](IMPORTANT): Started mission Default
2025-06-05T10:25:08.493Z,1749119108.493 [Default] Running Loop=1
2025-06-05T10:25:08.493Z,1749119108.493 [Default](DEBUG): Aggregate::initialize Default
2025-06-05T10:25:08.493Z,1749119108.493 [Default:B.GoToSurface] Running Loop=1
2025-06-05T10:25:08.493Z,1749119108.493 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T10:25:08.494Z,1749119108.494 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-05T10:25:08.494Z,1749119108.494 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-05T10:25:08.495Z,1749119108.495 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-05T10:25:08.495Z,1749119108.495 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-05T10:25:08.495Z,1749119108.495 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T10:25:08.496Z,1749119108.496 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T10:25:08.496Z,1749119108.496 [Default:A.Wait] Running Loop=1
2025-06-05T10:25:08.496Z,1749119108.496 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-05T10:25:21.833Z,1749119121.833 [Default:A.Wait](INFO): Done Waiting.
2025-06-05T10:25:21.833Z,1749119121.833 [Default:A.Wait] Stopped
2025-06-05T10:25:21.833Z,1749119121.833 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-05T10:25:22.251Z,1749119122.251 [Default:CheckIn] Running Loop=1
2025-06-05T10:25:22.251Z,1749119122.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-05T10:25:22.251Z,1749119122.251 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-05T10:25:24.246Z,1749119124.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102523.00,A,3647.39925,N,12202.58894,W,0.894,123.68,050625,,,D*78
2025-06-05T10:25:24.248Z,1749119124.248 [NAL9602](INFO): GPS fix at 20250605T102523: (36.789988, -122.043149)
2025-06-05T10:25:24.275Z,1749119124.275 [Default:CheckIn:Read_GPS] Stopped
2025-06-05T10:25:24.275Z,1749119124.275 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-05T10:25:26.987Z,1749119126.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:25:49.100Z,1749119149.100 [NAL9602](INFO): SBD MO Status=1, MOMSN=21054, MT Status=0, MTMSN=0
2025-06-05T10:25:49.154Z,1749119149.154 [NAL9602](INFO): Sent 199 bytes from file Logs/20250605T040200/Courier0015.lzma
2025-06-05T10:25:49.155Z,1749119149.155 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:25:58.111Z,1749119158.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:26:01.277Z,1749119161.277 [NAL9602](INFO): SBD MO Status=2, MOMSN=21055, MT Status=2, MTMSN=0
2025-06-05T10:26:01.277Z,1749119161.277 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:26:11.435Z,1749119171.435 [NAL9602](INFO): SBD MO Status=1, MOMSN=21055, MT Status=0, MTMSN=0
2025-06-05T10:26:11.484Z,1749119171.484 [NAL9602](INFO): Sent 76 bytes from file Logs/20250605T040200/Courier0018.lzma
2025-06-05T10:26:11.484Z,1749119171.484 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:26:29.235Z,1749119189.235 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:26:36.120Z,1749119196.120 [NAL9602](INFO): SBD MO Status=1, MOMSN=21056, MT Status=0, MTMSN=0
2025-06-05T10:26:36.175Z,1749119196.175 [NAL9602](INFO): Sent 332 bytes from file Logs/20250605T040200/Express0016.lzma
2025-06-05T10:26:36.175Z,1749119196.175 [NAL9602](INFO): Packets left to send: 1
2025-06-05T10:26:49.106Z,1749119209.106 [NAL9602](INFO): SBD MO Status=1, MOMSN=21057, MT Status=0, MTMSN=0
2025-06-05T10:26:49.166Z,1749119209.166 [NAL9602](INFO): Sent 303 bytes from file Logs/20250605T040200/Express0016.lzma
2025-06-05T10:26:49.167Z,1749119209.167 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:26:57.651Z,1749119217.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=21058, MT Status=0, MTMSN=0
2025-06-05T10:26:57.702Z,1749119217.702 [NAL9602](INFO): Sent 150 bytes from file Logs/20250605T040200/Express0019.lzma
2025-06-05T10:26:57.703Z,1749119217.703 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:27:00.371Z,1749119220.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:27:03.300Z,1749119223.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=21059, MT Status=0, MTMSN=0
2025-06-05T10:27:03.397Z,1749119223.397 [Default:CheckIn:Read_Iridium] Stopped
2025-06-05T10:27:03.397Z,1749119223.397 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-05T10:27:03.398Z,1749119223.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-05T10:27:31.499Z,1749119251.499 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:27:34.011Z,1749119254.011 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T10:28:02.622Z,1749119282.622 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:28:33.751Z,1749119313.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:29:04.875Z,1749119344.875 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:29:36.011Z,1749119376.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:30:07.156Z,1749119407.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:30:38.279Z,1749119438.279 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:31:09.403Z,1749119469.403 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:31:40.534Z,1749119500.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:32:03.906Z,1749119523.906 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-05T10:32:03.906Z,1749119523.906 [Default:CheckIn:C.Wait] Stopped
2025-06-05T10:32:03.906Z,1749119523.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-05T10:32:03.906Z,1749119523.906 [Default:CheckIn:D] Running Loop=1
2025-06-05T10:32:04.326Z,1749119524.326 [Default:CheckIn:D] Stopped
2025-06-05T10:32:04.326Z,1749119524.326 [Default:CheckIn:E] Running Loop=1
2025-06-05T10:32:04.731Z,1749119524.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  6.930543 min 
2025-06-05T10:32:04.731Z,1749119524.731 [Default:CheckIn:E] Stopped
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn] Stopped
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn](INFO): Running loop #2
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn] Running Loop=2
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-05T10:32:04.732Z,1749119524.732 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-05T10:32:06.744Z,1749119526.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,103206.00,A,3647.38056,N,12202.49026,W,0.603,90.52,050625,,,D*44
2025-06-05T10:32:06.746Z,1749119526.746 [NAL9602](INFO): GPS fix at 20250605T103206: (36.789676, -122.041504)
2025-06-05T10:32:06.777Z,1749119526.777 [Default:CheckIn:Read_GPS] Stopped
2025-06-05T10:32:06.777Z,1749119526.777 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-05T10:32:11.670Z,1749119531.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:32:39.085Z,1749119559.085 [NAL9602](INFO): SBD MO Status=1, MOMSN=21060, MT Status=0, MTMSN=0
2025-06-05T10:32:39.146Z,1749119559.146 [NAL9602](INFO): Sent 76 bytes from file Logs/20250605T040200/Courier0021.lzma
2025-06-05T10:32:39.147Z,1749119559.147 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:32:42.797Z,1749119562.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:32:50.076Z,1749119570.076 [NAL9602](INFO): SBD MO Status=1, MOMSN=21061, MT Status=0, MTMSN=0
2025-06-05T10:32:50.131Z,1749119570.131 [NAL9602](INFO): Sent 207 bytes from file Logs/20250605T040200/Express0022.lzma
2025-06-05T10:32:50.131Z,1749119570.131 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:33:05.840Z,1749119585.840 [NAL9602](INFO): SBD MO Status=2, MOMSN=21062, MT Status=2, MTMSN=0
2025-06-05T10:33:05.840Z,1749119585.840 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:33:10.491Z,1749119590.491 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-05T10:33:13.919Z,1749119593.919 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:33:32.916Z,1749119612.916 [NAL9602](INFO): SBD MO Status=2, MOMSN=21062, MT Status=2, MTMSN=0
2025-06-05T10:33:32.916Z,1749119612.916 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:33:42.202Z,1749119622.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=21062, MT Status=0, MTMSN=0
2025-06-05T10:33:42.278Z,1749119622.278 [Default:CheckIn:Read_Iridium] Stopped
2025-06-05T10:33:42.279Z,1749119622.279 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-05T10:33:42.279Z,1749119622.279 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-05T10:33:45.043Z,1749119625.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:34:12.913Z,1749119652.913 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T10:34:16.167Z,1749119656.167 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:34:47.291Z,1749119687.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:34:55.756Z,1749119695.756 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T10:34:55.757Z,1749119695.757 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T10:34:55.765Z,1749119695.765 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T10:35:18.423Z,1749119718.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:35:49.563Z,1749119749.563 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:36:20.686Z,1749119780.686 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:36:51.823Z,1749119811.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:37:22.950Z,1749119842.950 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:37:54.083Z,1749119874.083 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:38:25.207Z,1749119905.207 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:38:39.570Z,1749119919.570 [DVL_micro](ERROR): Failed to parse:
:WI,-00190,+00152,-00204,+00000,A
2025-06-05T10:38:41.575Z,1749119921.575 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T10:38:42.777Z,1749119922.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-05T10:38:42.777Z,1749119922.777 [Default:CheckIn:C.Wait] Stopped
2025-06-05T10:38:42.777Z,1749119922.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-05T10:38:42.777Z,1749119922.777 [Default:CheckIn:D] Running Loop=1
2025-06-05T10:38:43.176Z,1749119923.176 [Default:CheckIn:D] Stopped
2025-06-05T10:38:43.176Z,1749119923.176 [Default:CheckIn:E] Running Loop=1
2025-06-05T10:38:43.586Z,1749119923.586 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T10:38:43.603Z,1749119923.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  13.578050 min 
2025-06-05T10:38:43.603Z,1749119923.603 [Default:CheckIn:E] Stopped
2025-06-05T10:38:43.603Z,1749119923.603 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-05T10:38:43.603Z,1749119923.603 [Default:CheckIn] Stopped
2025-06-05T10:38:43.603Z,1749119923.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-05T10:38:43.604Z,1749119923.604 [Default:CheckIn](INFO): Running loop #3
2025-06-05T10:38:43.604Z,1749119923.604 [Default:CheckIn] Running Loop=3
2025-06-05T10:38:43.604Z,1749119923.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-05T10:38:43.604Z,1749119923.604 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-05T10:38:45.612Z,1749119925.612 [DVL_micro](ERROR): Failed to parse:
:SA,-05.03,-07.43,000.4
2025-06-05T10:38:45.615Z,1749119925.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,103844.00,A,3647.36246,N,12202.39785,W,1.749,102.17,050625,,,D*79
2025-06-05T10:38:45.617Z,1749119925.617 [NAL9602](INFO): GPS fix at 20250605T103844: (36.789374, -122.039964)
2025-06-05T10:38:45.627Z,1749119925.627 [Default:CheckIn:Read_GPS] Stopped
2025-06-05T10:38:45.628Z,1749119925.628 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-05T10:38:45.991Z,1749119925.991 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-06-05T10:38:45.992Z,1749119925.992 [DVL_micro] Communications Fault, FailCount= 1
2025-06-05T10:38:45.992Z,1749119925.992 [DVL_micro](ERROR): Communications Fault
2025-06-05T10:38:46.051Z,1749119926.051 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-05T10:38:46.498Z,1749119926.498 [DVL_micro](INFO): Powering down
2025-06-05T10:38:47.292Z,1749119927.292 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-05T10:38:47.292Z,1749119927.292 [DVL_micro] No Fault, FailCount= 1
2025-06-05T10:38:56.331Z,1749119936.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:39:09.906Z,1749119949.906 [NAL9602](INFO): SBD MO Status=2, MOMSN=21063, MT Status=2, MTMSN=0
2025-06-05T10:39:09.906Z,1749119949.906 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:39:27.459Z,1749119967.459 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:39:36.621Z,1749119976.621 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=21063, MT Status=1, MTMSN=4356
2025-06-05T10:39:36.674Z,1749119976.674 [NAL9602](INFO): Sent 89 bytes from file Logs/20250605T040200/Courier0024.lzma
2025-06-05T10:39:36.675Z,1749119976.675 [NAL9602](INFO): Packets left to send: 0
2025-06-05T10:39:37.195Z,1749119977.195 [NAL9602](INFO): Received command: configSet CBIT.runNavErrorCritical 0 bool persist;resume;schedule resume
2025-06-05T10:39:37.603Z,1749119977.603 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist
2025-06-05T10:39:37.609Z,1749119977.609 [CommandExec](IMPORTANT): got command resume
2025-06-05T10:39:37.610Z,1749119977.610 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-06-05T10:39:37.610Z,1749119977.610 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-06-05T10:39:37.614Z,1749119977.614 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-06-05T10:39:38.989Z,1749119978.989 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="SinkOffSurface">
        <False />
    </DefineArg>
    <DefineArg Name="SinkDepth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SinkDepthTimeout">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <DefineOutput Name="DiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="DoingComms">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="SurfaceSink">
        <Units:count />
        <Value>1</Value>
    </DefineOutput>
    <DefineOutput Name="TransitToStation">
        <Units:count />
        <Value>2</Value>
    </DefineOutput>
    <DefineOutput Name="StationKeep">
        <Units:count />
        <Value>3</Value>
    </DefineOutput>
    <DefineOutput Name="OnStation">
        <False />
    </DefineOutput>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="SurfaceComms">
        <When>
            <Called />
            <Or>
                <Elapsed>
                    <Universal:time_fix />
                </Elapsed>
                <Gt>
                    <Arg Name="NeedCommsTime" />
                </Gt>
            </Or>
        </When>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="DoingComms" />
        </Assign>
        <Call RefId="NeedComms" PriorityHere="false" />
        <Aggregate Id="setTransit">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Arg Name="OnStation" />
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="TransitToStation" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setStation">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Not>
                        <Arg Name="OnStation" />
                    </Not>
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="StationKeep" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setSink">
            <Sequence />
            <Break>
                <Not>
                    <Arg Name="SinkOffSurface" />
                </Not>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="SurfaceSink" />
            </Assign>
        </Aggregate>
    </Aggregate>
    <Call RefId="SurfaceComms" Id="StartingMission" PriorityHere="false" />
    <Aggregate Id="DiveCmd">
        <When>
            <Called />
        </When>
        <Aggregate Id="SurfaceSink">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="SurfaceSink" />
                </Eq>
            </While>
            <Aggregate Id="sink">
                <Sequence />
                <Guidance:Buoyancy Id="BuoyancyHold">
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Units:cubic_centimeter />
                        <Value>NaN</Value>
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Units:meter_per_second />
                        <Value>0</Value>
                    </Setting>
                </Guidance:SetSpeed>
                <Syslog Severity="Info">
                    Sinking to 
                    <Arg Name="SinkDepth" />
                    <Units:meter />
                </Syslog>
                <Guidance:Pitch>
                    <Sequence />
                    <Timeout Duration="SinkDepthTimeout">
                        <Syslog Severity="Important">
                            Timed out while trying to reach target sink depth at current depth of 
                            <Universal:depth />
                            <Units:meter />
                        </Syslog>
                    </Timeout>
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="SinkDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Aggregate Id="sinkToTransit">
                    <Sequence />
                    <Break>
                        <Arg Name="OnStation" />
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="TransitToStation" />
                    </Assign>
                </Aggregate>
                <Aggregate Id="sinkToStation">
                    <Sequence />
                    <Break>
                        <Not>
                            <Arg Name="OnStation" />
                        </Not>
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="StationKeep" />
                    </Assign>
                </Aggregate>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="TransitToStation">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="TransitToStation" />
                </Eq>
            </While>
            <Aggregate Id="dive">
                <Sequence />
                <Guidance:Buoyancy>
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Control:VerticalControl.buoyancyNeutral />
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:Pitch>
                    <Parallel />
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="ApproachDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Arg Name="Speed" />
                    </Setting>
                </Guidance:SetSpeed>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="StationKeep">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="StationKeep" />
                </Eq>
            </While>
            <Assign>
                <Parallel />
                <Control:VerticalControl.depthDeadband />
                <Arg Name="DepthDeadband" />
            </Assign>
            <Guidance:Pitch>
                <Parallel />
                <Setting>
                    <Guidance:Pitch.depth />
                    <Arg Name="Depth" />
                </Setting>
            </Guidance:Pitch>
            <Guidance:KeepStation>
                <Parallel />
                <Setting>
                    <Guidance:KeepStation.latitude />
                    <Arg Name="Latitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.longitude />
                    <Arg Name="Longitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.radius />
                    <Arg Name="Radius" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.speed />
                    <Arg Name="Speed" />
                </Setting>
            </Guidance:KeepStation>
        </Aggregate>
    </Aggregate>
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="StationKeep" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="OnStation" />
            <True />
        </Assign>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" PriorityHere="false" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-06-05T10:39:38.997Z,1749119978.997 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-06-05T10:39:38.999Z,1749119978.999 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-06-05T10:39:39.002Z,1749119979.002 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-06-05T10:39:39.004Z,1749119979.004 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-06-05T10:39:39.006Z,1749119979.006 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-06-05T10:39:39.009Z,1749119979.009 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-06-05T10:39:39.011Z,1749119979.011 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-06-05T10:39:39.013Z,1749119979.013 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-06-05T10:39:39.016Z,1749119979.016 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-06-05T10:39:39.071Z,1749119979.071 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-06-05T10:39:39.074Z,1749119979.074 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-06-05T10:39:39.081Z,1749119979.081 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-06-05T10:39:39.092Z,1749119979.092 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-06-05T10:39:39.102Z,1749119979.102 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-06-05T10:39:39.105Z,1749119979.105 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-06-05T10:39:39.109Z,1749119979.109 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-06-05T10:39:39.144Z,1749119979.144 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-06-05T10:39:39.159Z,1749119979.159 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-06-05T10:39:39.163Z,1749119979.163 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-06-05T10:39:39.165Z,1749119979.165 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-06-05T10:39:39.166Z,1749119979.166 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-06-05T10:39:39.170Z,1749119979.170 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-06-05T10:39:39.172Z,1749119979.172 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-06-05T10:39:39.479Z,1749119979.479 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-06-05T10:39:39.488Z,1749119979.488 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-06-05T10:39:39.490Z,1749119979.490 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-06-05T10:39:39.492Z,1749119979.492 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-06-05T10:39:39.523Z,1749119979.523 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-06-05T10:39:39.525Z,1749119979.525 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-06-05T10:39:39.527Z,1749119979.527 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-06-05T10:39:39.529Z,1749119979.529 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-06-05T10:39:39.536Z,1749119979.536 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-06-05T10:39:39.555Z,1749119979.555 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-05T10:39:39.603Z,1749119979.603 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-06-05T10:39:39.603Z,1749119979.603 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-06-05T10:39:39.604Z,1749119979.604 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-06-05T10:39:39.675Z,1749119979.675 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>1.5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-06-05T10:39:39.679Z,1749119979.679 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-06-05T10:39:39.681Z,1749119979.681 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-06-05T10:39:39.683Z,1749119979.683 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-06-05T10:39:39.685Z,1749119979.685 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-06-05T10:39:39.687Z,1749119979.687 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-06-05T10:39:39.692Z,1749119979.692 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-06-05T10:39:39.699Z,1749119979.699 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-06-05T10:39:39.738Z,1749119979.738 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-06-05T10:39:39.738Z,1749119979.738 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-06-05T10:39:39.739Z,1749119979.739 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-06-05T10:39:39.766Z,1749119979.766 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-06-05T10:39:39.769Z,1749119979.769 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-06-05T10:39:39.771Z,1749119979.771 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-06-05T10:39:39.773Z,1749119979.773 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-06-05T10:39:39.774Z,1749119979.774 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-06-05T10:39:39.775Z,1749119979.775 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-06-05T10:39:39.996Z,1749119979.996 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-06-05T10:39:40.002Z,1749119980.002 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-06-05T10:39:40.005Z,1749119980.005 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-06-05T10:39:40.008Z,1749119980.008 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-06-05T10:39:40.011Z,1749119980.011 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-06-05T10:39:40.040Z,1749119980.040 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-06-05T10:39:40.058Z,1749119980.058 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-06-05T10:39:40.060Z,1749119980.060 [keepstation:H.Pitch](DEBUG): Construct.
2025-06-05T10:39:40.087Z,1749119980.087 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-06-05T10:39:40.090Z,1749119980.090 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-06-05T10:39:40.093Z,1749119980.093 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-06-05T10:39:40.106Z,1749119980.106 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-06-05T10:39:40.109Z,1749119980.109 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-06-05T10:39:40.113Z,1749119980.113 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-06-05T10:39:40.119Z,1749119980.119 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-06-05T10:39:40.135Z,1749119980.135 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-06-05T10:39:40.144Z,1749119980.144 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-06-05T10:39:40.155Z,1749119980.155 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-06-05T10:39:40.172Z,1749119980.172 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission keepstation {
  """
  Vehicle transits to desired waypoint (or stays put if none is commanded)
  and stays within the specified radius.
  """

  arguments {
    MissionTimeout = 4 hour
      """
      Maximum duration of mission
      """

    NeedCommsTime = 45 minute
      """
      How often to surface for communications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to hold. If NaN, hold the latitude at start of
      mission.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to hold. If NaN, hold the longitude at start of
      mission.
      """

    Depth = 30 meter
      """
      Depth held during drift mode
      """

    ApproachDepth = 15 meter
      """
      Depth for initial approach to station.
      """

    DepthDeadband = 4 meter
      """
      How much vertical drift from the specified depth is allowed in drift
      mode
      """

    Speed = 0.75 meter_per_second
      """
      Vehicle transit speed.
      """

    Radius = 200 meter
      """
      Radius of circle around waypoint to hold.
      """

    SinkOffSurface = false
      """
      If true, use buoyancy only to decend to SinkDepth before driving to first target depth
      """

    SinkDepth = 10 meter
      """
      Initial depth to decend using only buoyancy before driving
      """

    SinkDepthTimeout = 10 minute
      """
      Max wait time for the vehicle to reach the target depth
      """

    MaxDepth = 45 meter
      """
      Maximum allowable depth during the mission.
      """

    MinOffshore = 2 kilometer
      """
      Minimum distance from the shoreline to maintain
      """
  }

  output {
    DiveMode = 0 count
      """
      Mission variable (don't change). The mission sets this variable to
      switch between dive modes. Initialized to DoingComms.
      """

    DoingComms = 0 count
      """
      Mission variable (don't change). The mission will run in this mode
      when doing surface comms.
      """

    SurfaceSink = 1 count
      """
      Mission variable (don't change). The mission will run in this mode
      when sinking from the surface using only buoyancy.
      """

    TransitToStation = 2 count
      """
      Mission variable (don't change). The mission will run in this mode
      when performing the transit to station
      """

    StationKeep = 3 count
      """
      Mission variable (don't change). The mission will run in this mode
      when keeping station
      """

    OnStation = false
      """
      Mission variable (don't change). Determines last mode (Transit or StationKeep)
      to resume after needcomms
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate SurfaceComms {
    run when (
      called
      or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
    )

    assign in sequence DiveMode = DoingComms

    call priorityHere=false refId="NeedComms"

    aggregate setTransit {
      run in sequence

      break if (
        SinkOffSurface
        or OnStation
      )

      assign in sequence DiveMode = TransitToStation
    }

    aggregate setStation {
      run in sequence

      break if (
        SinkOffSurface
        or not OnStation
      )

      assign in sequence DiveMode = StationKeep
    }

    aggregate setSink {
      run in sequence

      break if ( not SinkOffSurface )

      assign in sequence DiveMode = SurfaceSink
    }
  }

  call id="StartingMission" priorityHere=false refId="SurfaceComms"

  aggregate DiveCmd {
    run when ( called )

    aggregate SurfaceSink {
      run while ( DiveMode == SurfaceSink )

      aggregate sink {
        run in sequence

        behavior Guidance:Buoyancy id="BuoyancyHold" {
          run in parallel

          set position = NaN cc
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = 0 m/s
        }

        syslog info "Sinking to " + SinkDepth~meter

        behavior Guidance:Pitch {
          run in sequence

          timeout duration=SinkDepthTimeout {
            syslog important "Timed out while trying to reach target sink depth at current depth of "
                 + Universal:depth~meter
          }

          set depth = SinkDepth
        }

        # Once we've sunk, either resume transit or station keeping depths and speeds
        aggregate sinkToTransit {
          run in sequence

          break if ( OnStation )

          assign in sequence DiveMode = TransitToStation
        }

        aggregate sinkToStation {
          run in sequence

          break if ( not OnStation )

          assign in sequence DiveMode = StationKeep
        }
      }
    }

    aggregate TransitToStation {
      run while ( DiveMode == TransitToStation )

      aggregate dive {
        run in sequence

        behavior Guidance:Buoyancy {
          run in parallel

          set position = Control:VerticalControl.buoyancyNeutral
        }

        behavior Guidance:Pitch {
          run in parallel

          set depth = ApproachDepth
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = Speed
        }
      }
    }

    aggregate StationKeep {
      run while ( DiveMode == StationKeep )

      assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

      behavior Guidance:Pitch {
        run in parallel

        set depth = Depth
      }

      behavior Guidance:KeepStation {
        run in parallel

        set latitude = Latitude
        set longitude = Longitude
        set radius = Radius
        set speed = Speed
      }
    }
  }

  aggregate TransitToStation {
    """
    Need a separate aggregate for transit if we want to specify a
    different depth for the approach.
    """

    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call priorityHere=false refId="DiveCmd"
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
    }

    assign in sequence DiveMode = StationKeep
    assign in sequence OnStation = true
  }

  call id="OnStation" priorityHere=false refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call priorityHere=false refId="DiveCmd"
    }

    behavior Guidance:Wait {
      """
      Due to the way the KeepStation behavior is currently
      implemented, we must run it in parallel and use a Wait to keep
      from completing the mission before the timeout expires. However,
      this new version of the mission uses a top-level timeout so that
      the entire mission will not run for longer then MissionTimeout.
      """

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-06-05T10:39:40.191Z,1749119980.191 [MissionManager](IMPORTANT): Resuming from info in Logs/20250604T184824/Transport_keepstation_tl.state
2025-06-05T10:39:40.208Z,1749119980.208 [MissionManager](INFO): Default->Default:CheckIn/0
2025-06-05T10:39:40.209Z,1749119980.209 [MissionManager](INFO): Default:A.Wait->null/0
2025-06-05T10:39:40.209Z,1749119980.209 [MissionManager](INFO): Default:B.GoToSurface->null/0
2025-06-05T10:39:40.210Z,1749119980.210 [MissionManager](INFO): Default:CheckIn->Default:CheckIn:Read_Iridium/2
2025-06-05T10:39:40.210Z,1749119980.210 [MissionManager](INFO): Default:CheckIn:Read_GPS->null/0
2025-06-05T10:39:40.211Z,1749119980.211 [MissionManager](INFO): Default:CheckIn:Read_Iridium->null/0
2025-06-05T10:39:40.211Z,1749119980.211 [MissionManager](INFO): Default:CheckIn:C.Wait->null/0
2025-06-05T10:39:40.211Z,1749119980.211 [MissionManager](INFO): Default:CheckIn:D->null/0
2025-06-05T10:39:40.212Z,1749119980.212 [MissionManager](INFO): Default:CheckIn:E->null/0
2025-06-05T10:39:40.212Z,1749119980.212 [MissionManager](INFO): keepstation->keepstation:KeepStation/0
2025-06-05T10:39:40.213Z,1749119980.213 [MissionManager](INFO): keepstation:NeedComms->keepstation:NeedComms:D/0
2025-06-05T10:39:40.213Z,1749119980.213 [MissionManager](INFO): keepstation:NeedComms:A->null/0
2025-06-05T10:39:40.213Z,1749119980.213 [MissionManager](INFO): keepstation:NeedComms:B.GoToSurface->null/0
2025-06-05T10:39:40.214Z,1749119980.214 [MissionManager](INFO): keepstation:NeedComms:C->null/0
2025-06-05T10:39:40.214Z,1749119980.214 [MissionManager](INFO): keepstation:NeedComms:D->null/0
2025-06-05T10:39:40.215Z,1749119980.215 [MissionManager](INFO): keepstation:NeedComms:E->null/0
2025-06-05T10:39:40.215Z,1749119980.215 [MissionManager](INFO): keepstation:B->null/0
2025-06-05T10:39:40.215Z,1749119980.215 [MissionManager](INFO): keepstation:D->null/0
2025-06-05T10:39:40.216Z,1749119980.216 [MissionManager](INFO): keepstation:E->null/0
2025-06-05T10:39:40.216Z,1749119980.216 [MissionManager](INFO): keepstation:BackseatDriver->keepstation:BackseatDriver:A.BackseatDriver/0
2025-06-05T10:39:40.217Z,1749119980.217 [MissionManager](INFO): keepstation:BackseatDriver:A.BackseatDriver->null/0
2025-06-05T10:39:40.217Z,1749119980.217 [MissionManager](INFO): keepstation:PowerOnly->keepstation:PowerOnly:E.Wait/0
2025-06-05T10:39:40.217Z,1749119980.217 [MissionManager](INFO): keepstation:PowerOnly:E.Wait->null/0
2025-06-05T10:39:40.218Z,1749119980.218 [MissionManager](INFO): keepstation:SurfaceComms->keepstation:SurfaceComms:B/0
2025-06-05T10:39:40.218Z,1749119980.218 [MissionManager](INFO): keepstation:SurfaceComms:A->null/0
2025-06-05T10:39:40.219Z,1749119980.219 [MissionManager](INFO): keepstation:SurfaceComms:B->null/0
2025-06-05T10:39:40.219Z,1749119980.219 [MissionManager](INFO): keepstation:SurfaceComms:setTransit->keepstation:SurfaceComms:setTransit:A/0
2025-06-05T10:39:40.219Z,1749119980.219 [MissionManager](INFO): keepstation:SurfaceComms:setTransit:A->null/0
2025-06-05T10:39:40.220Z,1749119980.220 [MissionManager](INFO): keepstation:SurfaceComms:setStation->keepstation:SurfaceComms:setStation:A/0
2025-06-05T10:39:40.220Z,1749119980.220 [MissionManager](INFO): keepstation:SurfaceComms:setStation:A->null/0
2025-06-05T10:39:40.221Z,1749119980.221 [MissionManager](INFO): keepstation:SurfaceComms:setSink->null/0
2025-06-05T10:39:40.221Z,1749119980.221 [MissionManager](INFO): keepstation:SurfaceComms:setSink:A->null/0
2025-06-05T10:39:40.221Z,1749119980.221 [MissionManager](INFO): keepstation:StartingMission->null/0
2025-06-05T10:39:40.222Z,1749119980.222 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink->null/0
2025-06-05T10:39:40.222Z,1749119980.222 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink->null/0
2025-06-05T10:39:40.223Z,1749119980.223 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink:C->null/0
2025-06-05T10:39:40.223Z,1749119980.223 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink:D.Pitch->null/0
2025-06-05T10:39:40.223Z,1749119980.223 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit->keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A/0
2025-06-05T10:39:40.224Z,1749119980.224 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A->null/0
2025-06-05T10:39:40.224Z,1749119980.224 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink:sinkToStation->null/0
2025-06-05T10:39:40.224Z,1749119980.224 [MissionManager](INFO): keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A->null/0
2025-06-05T10:39:40.225Z,1749119980.225 [MissionManager](INFO): keepstation:DiveCmd:TransitToStation->keepstation:DiveCmd:TransitToStation:dive/0
2025-06-05T10:39:40.225Z,1749119980.225 [MissionManager](INFO): keepstation:DiveCmd:TransitToStation:dive->null/0
2025-06-05T10:39:40.226Z,1749119980.226 [MissionManager](INFO): keepstation:TransitToStation->null/0
2025-06-05T10:39:40.298Z,1749119980.298 [MissionManager](INFO): keepstation:TransitToStation:Dive->keepstation:TransitToStation:Dive:A/0
2025-06-05T10:39:40.299Z,1749119980.299 [MissionManager](INFO): keepstation:TransitToStation:Dive:A->null/0
2025-06-05T10:39:40.299Z,1749119980.299 [MissionManager](INFO): keepstation:TransitToStation:Wpt1.Waypoint->null/0
2025-06-05T10:39:40.299Z,1749119980.299 [MissionManager](INFO): keepstation:TransitToStation:C->null/0
2025-06-05T10:39:40.300Z,1749119980.300 [MissionManager](INFO): keepstation:TransitToStation:D->null/0
2025-06-05T10:39:40.300Z,1749119980.300 [MissionManager](INFO): keepstation:OnStation->null/0
2025-06-05T10:39:40.301Z,1749119980.301 [MissionManager](INFO): keepstation:KeepStation->keepstation:KeepStation:B.Wait/0
2025-06-05T10:39:40.301Z,1749119980.301 [MissionManager](INFO): keepstation:KeepStation:Dive->keepstation:KeepStation:Dive:A/0
2025-06-05T10:39:40.301Z,1749119980.301 [MissionManager](INFO): keepstation:KeepStation:Dive:A->null/0
2025-06-05T10:39:40.302Z,1749119980.302 [MissionManager](INFO): keepstation:KeepStation:B.Wait->null/0
2025-06-05T10:39:40.310Z,1749119980.310 [CommandExec](IMPORTANT): Resuming mission and schedule
2025-06-05T10:39:40.310Z,1749119980.310 [CommandExec](IMPORTANT): Scheduling is resumed
2025-06-05T10:39:40.313Z,1749119980.313 [CommandExec](IMPORTANT): Scheduled #16: "restart logs", AT TIME: 2025-06-05T12:17:00Z
2025-06-05T10:39:40.329Z,1749119980.329 [Default] Stopped
2025-06-05T10:39:40.329Z,1749119980.329 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-05T10:39:40.330Z,1749119980.330 [Default:B.GoToSurface] Stopped
2025-06-05T10:39:40.330Z,1749119980.330 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T10:39:40.330Z,1749119980.330 [Default:CheckIn] Stopped
2025-06-05T10:39:40.330Z,1749119980.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-05T10:39:40.331Z,1749119980.331 [CommandExec](IMPORTANT): Scheduled #17 (#1 of 4 with id='29hs1'): "load Science/sci2.tl;set sci2.MissionTimeout 17 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 36.79815 degree;set sci2.Lon1 -122.05519 degree;set sci2.Lat2 36.82262 degree;set sci2.Lon2 -121.96198 degree", AT TIME: 2025-06-05T12:17:00Z
2025-06-05T10:39:40.332Z,1749119980.332 [CommandExec](IMPORTANT): Scheduled #18 (#2 of 4 with id='29hs1'): "set sci2.Lat3 36.8468 degree;set sci2.Lon3 -121.97365 degree;set sci2.Lat4 36.85834 degree;set sci2.Lon4 -121.9479 degree;set sci2.Lat5 36.87083 degree;set sci2.Lon5 -121.9582 degree;set sci2.Lat6 36.86575 degree", AT TIME: 2025-06-05T12:17:00Z
2025-06-05T10:39:40.333Z,1749119980.333 [CommandExec](IMPORTANT): Scheduled #19 (#3 of 4 with id='29hs1'): "set sci2.Lon6 -121.96661 degree;set sci2.Lat7 36.84501 degree;set sci2.Lon7 -121.95168 degree;set sci2.Speed 1.2 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.SinkOffSurface 1 bool", AT TIME: 2025-06-05T12:17:00Z
2025-06-05T10:39:40.337Z,1749119980.337 [Default:CheckIn:Read_Iridium] Stopped
2025-06-05T10:39:40.337Z,1749119980.337 [MissionManager](IMPORTANT): Started mission keepstation
2025-06-05T10:39:40.337Z,1749119980.337 [keepstation] Running Loop=1
2025-06-05T10:39:40.337Z,1749119980.337 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-06-05T10:39:40.337Z,1749119980.337 [keepstation:StandardEnvelopes] Running Loop=1
2025-06-05T10:39:40.337Z,1749119980.337 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-06-05T10:39:40.338Z,1749119980.338 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-06-05T10:39:40.338Z,1749119980.338 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-06-05T10:39:40.338Z,1749119980.338 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-06-05T10:39:40.339Z,1749119980.339 [CommandExec](IMPORTANT): Scheduled #20 (#4 of 4 with id='29hs1'): "set sci2.SinkDepth 3 m;set sci2.MaxDepth 55 m;run", AT TIME: 2025-06-05T12:17:00Z
2025-06-05T10:39:40.339Z,1749119980.339 [CommandExec](IMPORTANT): got command schedule resume
2025-06-05T10:39:40.351Z,1749119980.351 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:BackseatDriver] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:PowerOnly] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:PowerOnly:A] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:PowerOnly:B] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:PowerOnly:C] Running Loop=1
2025-06-05T10:39:40.352Z,1749119980.352 [keepstation:PowerOnly:D] Running Loop=1
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:H.Pitch] Running Loop=1
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:H.Pitch](DEBUG): Initialize.
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:KeepStation] Running Loop=1
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-05T10:39:40.353Z,1749119980.353 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-05T10:39:40.354Z,1749119980.354 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-06-05T10:39:40.354Z,1749119980.354 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-06-05T10:39:40.367Z,1749119980.367 [keepstation:KeepStation:Dive] Stopped
2025-06-05T10:39:40.367Z,1749119980.367 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-05T10:39:40.367Z,1749119980.367 [keepstation:KeepStation:Dive:A] Stopped
2025-06-05T10:39:40.367Z,1749119980.367 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-05T10:39:40.367Z,1749119980.367 [keepstation:DiveCmd] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-05T10:39:40.368Z,1749119980.368 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T10:39:40.369Z,1749119980.369 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-05T10:39:40.370Z,1749119980.370 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T10:39:40.370Z,1749119980.370 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T10:39:40.370Z,1749119980.370 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-05T10:39:40.374Z,1749119980.374 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T10:39:40.374Z,1749119980.374 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-05T10:39:40.375Z,1749119980.375 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T10:39:40.376Z,1749119980.376 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:H.Pitch] Running Loop=1
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:PowerOnly] Running Loop=1
2025-06-05T10:39:40.377Z,1749119980.377 [keepstation:PowerOnly:D] Stopped
2025-06-05T10:39:40.378Z,1749119980.378 [keepstation:PowerOnly:C] Stopped
2025-06-05T10:39:40.378Z,1749119980.378 [keepstation:PowerOnly:B] Stopped
2025-06-05T10:39:40.378Z,1749119980.378 [keepstation:PowerOnly:A] Stopped
2025-06-05T10:39:40.378Z,1749119980.378 [keepstation:BackseatDriver] Running Loop=1
2025-06-05T10:39:40.383Z,1749119980.383 [keepstation:StandardEnvelopes] Running Loop=1
2025-06-05T10:39:40.383Z,1749119980.383 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-06-05T10:39:40.385Z,1749119980.385 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-06-05T10:39:40.386Z,1749119980.386 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-06-05T10:39:58.582Z,1749119998.582 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:40:01.730Z,1749120001.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=21064, MT Status=2, MTMSN=0
2025-06-05T10:40:01.730Z,1749120001.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T10:40:29.707Z,1749120029.707 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:40:32.433Z,1749120032.433 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T10:41:00.831Z,1749120060.831 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:41:31.963Z,1749120091.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:42:03.090Z,1749120123.090 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:42:34.215Z,1749120154.215 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:43:05.338Z,1749120185.338 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:43:36.463Z,1749120216.463 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:44:07.591Z,1749120247.591 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:44:38.715Z,1749120278.715 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:45:09.843Z,1749120309.843 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:45:40.978Z,1749120340.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:46:12.103Z,1749120372.103 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:46:43.227Z,1749120403.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:47:14.350Z,1749120434.350 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:47:45.475Z,1749120465.475 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:48:16.614Z,1749120496.614 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:48:47.738Z,1749120527.738 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:49:18.875Z,1749120558.875 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:49:50.007Z,1749120590.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:50:21.135Z,1749120621.135 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:50:52.266Z,1749120652.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:51:23.391Z,1749120683.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:51:54.515Z,1749120714.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:52:25.638Z,1749120745.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:52:56.763Z,1749120776.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:53:27.888Z,1749120807.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:53:59.010Z,1749120839.010 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:54:30.135Z,1749120870.135 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:55:01.259Z,1749120901.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:55:32.399Z,1749120932.399 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:56:03.524Z,1749120963.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:56:34.646Z,1749120994.646 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:57:05.779Z,1749121025.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:57:36.906Z,1749121056.906 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:58:08.038Z,1749121088.038 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:58:39.162Z,1749121119.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:59:10.291Z,1749121150.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T10:59:41.415Z,1749121181.415 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:00:12.551Z,1749121212.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:00:43.674Z,1749121243.674 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:01:14.798Z,1749121274.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:01:45.923Z,1749121305.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:02:17.051Z,1749121337.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:02:25.618Z,1749121345.618 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003096
2025-06-05T11:30:53.694Z,1749123053.694 [DVL_micro](ERROR): Failed to parse:
:WI,+00184,-00149,+00089,+00000,A
2025-06-05T11:33:00.590Z,1749123180.590 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-05T11:33:05.092Z,1749123185.092 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006962
CHAN A1 (24V): 0.003267
CHAN A2 (12V): -0.006203
CHAN A3 (5V): -0.012846
CHAN B0 (3.3V): -0.002834
CHAN B1 (3.15aV): -0.002474
CHAN B2 (3.15bV): -0.000888
CHAN B3 (GND): -0.001624
OPEN: 0.012309
Full Scale: +/- 1 mA
2025-06-05T11:38:45.642Z,1749123525.642 [keepstation:SurfaceComms] Running Loop=1
2025-06-05T11:38:45.642Z,1749123525.642 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-05T11:38:45.643Z,1749123525.643 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-05T11:38:45.643Z,1749123525.643 [keepstation:SurfaceComms:A] Stopped
2025-06-05T11:38:45.643Z,1749123525.643 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-05T11:38:45.643Z,1749123525.643 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-05T11:38:46.058Z,1749123526.058 [keepstation:NeedComms] Running Loop=1
2025-06-05T11:38:46.058Z,1749123526.058 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-05T11:38:46.059Z,1749123526.059 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-05T11:38:46.059Z,1749123526.059 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-05T11:38:46.059Z,1749123526.059 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-05T11:38:46.059Z,1749123526.059 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-05T11:38:46.060Z,1749123526.060 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-05T11:38:46.060Z,1749123526.060 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-05T11:38:46.060Z,1749123526.060 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-05T11:38:46.061Z,1749123526.061 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-05T11:38:46.061Z,1749123526.061 [keepstation:NeedComms:A] Running Loop=1
2025-06-05T11:38:46.063Z,1749123526.063 [keepstation:NeedComms:A](INFO): last time_fix was:  1749119924.000000 second since 1970/01/01T00:00:00Z 
2025-06-05T11:38:46.063Z,1749123526.063 [keepstation:NeedComms:A] Stopped
2025-06-05T11:38:46.458Z,1749123526.458 [keepstation:NeedComms:C] Running Loop=1
2025-06-05T11:38:46.873Z,1749123526.873 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-05T11:38:48.465Z,1749123528.465 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113847.00,A,3647.32373,N,12201.36679,W,1.905,90.95,050625,,,A*45
2025-06-05T11:38:48.467Z,1749123528.467 [NAL9602](INFO): GPS fix at 20250605T113847: (36.788729, -122.022780)
2025-06-05T11:38:48.496Z,1749123528.496 [keepstation:NeedComms:C] Stopped
2025-06-05T11:38:48.497Z,1749123528.497 [keepstation:NeedComms:D] Running Loop=1
2025-06-05T11:38:48.905Z,1749123528.905 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-05T11:38:57.966Z,1749123537.966 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250605T040200/Courier0027.lzma
2025-06-05T11:38:58.968Z,1749123538.968 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Courier0027.lzma.bak
2025-06-05T11:38:58.968Z,1749123538.968 [DataOverHttps](INFO): SBD MOMSN=25100440
2025-06-05T11:39:05.868Z,1749123545.868 [NAL9602](INFO): SBD MO Status=2, MOMSN=21064, MT Status=2, MTMSN=0
2025-06-05T11:39:05.869Z,1749123545.869 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-05T11:39:15.914Z,1749123555.914 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250605T040200/Express0025.lzma
2025-06-05T11:39:16.916Z,1749123556.916 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Express0025.lzma.bak
2025-06-05T11:39:16.917Z,1749123556.917 [DataOverHttps](INFO): SBD MOMSN=25100443
2025-06-05T11:39:17.171Z,1749123557.171 [NAL9602](INFO): SBD MO Status=1, MOMSN=21064, MT Status=0, MTMSN=0
2025-06-05T11:39:17.172Z,1749123557.172 [NAL9602](INFO): No messages in MT queue
2025-06-05T11:39:34.236Z,1749123574.236 [DataOverHttps](INFO): Sending 905 bytes from file Logs/20250605T040200/Express0028.lzma
2025-06-05T11:39:35.236Z,1749123575.236 [DataOverHttps](INFO): Moved sent file to Logs/20250605T040200/Express0028.lzma.bak
2025-06-05T11:39:35.236Z,1749123575.236 [DataOverHttps](INFO): SBD MOMSN=25100446
2025-06-05T11:39:36.572Z,1749123576.572 [keepstation:NeedComms:D] Stopped
2025-06-05T11:39:36.573Z,1749123576.573 [keepstation:NeedComms:E] Running Loop=1
2025-06-05T11:39:37.034Z,1749123577.034 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-05T11:39:38.572Z,1749123578.572 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113937.00,A,3647.32391,N,12201.35202,W,1.225,83.63,050625,,,A*46
2025-06-05T11:39:38.585Z,1749123578.585 [NAL9602](INFO): GPS fix at 20250605T113937: (36.788732, -122.022534)
2025-06-05T11:39:38.598Z,1749123578.598 [keepstation:NeedComms:E] Stopped
2025-06-05T11:39:38.599Z,1749123578.599 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-05T11:39:38.599Z,1749123578.599 [keepstation:NeedComms] Stopped
2025-06-05T11:39:38.600Z,1749123578.600 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-05T11:39:38.600Z,1749123578.600 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-05T11:39:38.600Z,1749123578.600 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-05T11:39:39.015Z,1749123579.015 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-05T11:39:39.015Z,1749123579.015 [keepstation:SurfaceComms:B] Stopped
2025-06-05T11:39:39.015Z,1749123579.015 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-05T11:39:39.015Z,1749123579.015 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-05T11:39:39.015Z,1749123579.015 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-05T11:39:39.015Z,1749123579.015 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-05T11:39:39.427Z,1749123579.427 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-05T11:39:39.427Z,1749123579.427 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-05T11:39:39.427Z,1749123579.427 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-05T11:39:39.428Z,1749123579.428 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-05T11:39:39.428Z,1749123579.428 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-05T11:39:39.428Z,1749123579.428 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-05T11:39:39.864Z,1749123579.864 [keepstation:SurfaceComms:setStation] Stopped
2025-06-05T11:39:39.864Z,1749123579.864 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-05T11:39:39.864Z,1749123579.864 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-05T11:39:39.864Z,1749123579.864 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-05T11:39:39.864Z,1749123579.864 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-05T11:39:39.864Z,1749123579.864 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-05T11:39:40.224Z,1749123580.224 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-05T11:39:40.224Z,1749123580.224 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-05T11:39:40.224Z,1749123580.224 [keepstation:SurfaceComms:setSink] Stopped
2025-06-05T11:39:40.224Z,1749123580.224 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-05T11:39:40.225Z,1749123580.225 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-05T11:39:40.225Z,1749123580.225 [keepstation:SurfaceComms] Stopped
2025-06-05T11:39:40.225Z,1749123580.225 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-05T11:39:40.628Z,1749123580.628 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-05T11:39:40.628Z,1749123580.628 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-05T11:39:40.628Z,1749123580.628 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-05T11:39:40.628Z,1749123580.628 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-05T11:39:40.628Z,1749123580.628 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-05T11:39:40.629Z,1749123580.629 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-05T11:39:40.634Z,1749123580.634 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-05T11:39:40.634Z,1749123580.634 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-05T11:39:40.634Z,1749123580.634 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-05T11:39:40.635Z,1749123580.635 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-05T11:39:40.635Z,1749123580.635 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-05T11:39:40.635Z,1749123580.635 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-05T11:40:10.647Z,1749123610.647 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-05T11:41:49.088Z,1749123709.088 [DVL_micro](ERROR): Failed to parse:
:R99.99
2025-06-05T11:42:31.095Z,1749123751.095 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-05T11:42:31.096Z,1749123751.096 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-05T11:42:31.100Z,1749123751.100 [BPC1](INFO): Received data from all battery sticks.
2025-06-05T11:43:11.423Z,1749123791.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:43:36.038Z,1749123816.038 [Radio_Surface](INFO): Powering down
2025-06-05T11:43:42.555Z,1749123822.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-05T11:43:43.559Z,1749123823.559 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-05T11:44:44.017Z,1749123884.017 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-05T11:44:44.017Z,1749123884.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-05T11:44:44.018Z,1749123884.018 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T11:44:44.018Z,1749123884.018 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-05T11:44:44.435Z,1749123884.435 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-05T11:44:44.435Z,1749123884.435 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-05T11:44:44.435Z,1749123884.435 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-05T11:44:44.435Z,1749123884.435 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-05T11:44:44.435Z,1749123884.435 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T11:44:44.435Z,1749123884.435 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-05T11:44:44.829Z,1749123884.829 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-05T11:44:44.829Z,1749123884.829 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T11:44:44.829Z,1749123884.829 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-05T11:44:44.829Z,1749123884.829 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-05T11:44:44.830Z,1749123884.830 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-05T11:44:44.831Z,1749123884.831 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-06-05T11:44:45.231Z,1749123885.231 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-05T11:44:45.232Z,1749123885.232 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-05T11:44:45.233Z,1749123885.233 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-05T11:44:45.234Z,1749123885.234 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-05T11:44:45.250Z,1749123885.250 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-05T11:44:45.628Z,1749123885.628 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 31.28 mm (1 active estimators).
2025-06-05T11:45:18.943Z,1749123918.943 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747192
2025-06-05T11:46:15.858Z,1749123975.858 [ElevatorServo](FAULT): Overload Error
2025-06-05T11:46:15.858Z,1749123975.858 [ElevatorServo] Hardware Fault, FailCount= 1
2025-06-05T11:46:15.858Z,1749123975.858 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T11:46:16.038Z,1749123976.038 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T11:46:16.257Z,1749123976.257 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T11:46:16.257Z,1749123976.257 [ElevatorServo](INFO): Powering down
2025-06-05T11:46:17.043Z,1749123977.043 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T11:46:17.164Z,1749123977.164 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T11:46:17.182Z,1749123977.182 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T11:46:17.183Z,1749123977.183 [ElevatorServo] No Fault, FailCount= 1
2025-06-05T11:46:22.327Z,1749123982.327 [ElevatorServo](FAULT): Overload Error
2025-06-05T11:46:22.327Z,1749123982.327 [ElevatorServo] Hardware Fault, FailCount= 2
2025-06-05T11:46:22.327Z,1749123982.327 [ElevatorServo](ERROR): Hardware Fault
2025-06-05T11:46:22.514Z,1749123982.514 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-06-05T11:46:22.674Z,1749123982.674 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-05T11:46:22.674Z,1749123982.674 [ElevatorServo](INFO): Powering down
2025-06-05T11:46:23.489Z,1749123983.489 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-05T11:46:23.607Z,1749123983.607 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-05T11:46:23.817Z,1749123983.817 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-06-05T11:46:23.818Z,1749123983.818 [ElevatorServo] No Fault, FailCount= 2
2025-06-05T11:50:06.727Z,1749124206.727 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+9999.99,+9999.99,+9999..99
2025-06-05T11:50:06.727Z,1749124206.727 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999..99
2025-06-05T12:03:22.365Z,1749125002.365 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.2,0000.0,1489.0+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-06-05T12:03:22.365Z,1749125002.365 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.2,0000.0,1489.0+00000000.00,+00000000.00,+00000000.00,9999.99,000.00