From fb31654277f1ba7500d34910f3cc16618edb0079 Mon Sep 17 00:00:00 2001 From: steve-chavez Date: Thu, 9 Apr 2026 12:04:44 -0500 Subject: [PATCH] fix: remove red herring warp logs on default log-level The logs added on e95e815483c542f430cf8aa8df15419143f400fa are red herrings under normal operation. This moves them to `log-level=debug` and removes "error" from the message prefix. Fixes https://github.com/PostgREST/postgrest/issues/4799 --- CHANGELOG.md | 4 ++++ src/PostgREST/App.hs | 2 +- src/PostgREST/Logger.hs | 3 +++ src/PostgREST/Observation.hs | 6 +++--- test/io/test_big_schema.py | 16 ++++++++++++---- 5 files changed, 23 insertions(+), 8 deletions(-) diff --git a/CHANGELOG.md b/CHANGELOG.md index ffae4da0e..0f9482bdc 100644 --- a/CHANGELOG.md +++ b/CHANGELOG.md @@ -8,6 +8,10 @@ All notable changes to this project will be documented in this file. From versio - Log host, port and pg version of listener database connection by @mkleczek in #4617 #4618 +### Fixed + +- Remove red herring warp logs on default log-level, only emit them on `log-level=debug` by @steve-chavez in #4799 + ## [14.8] - 2026-04-03 ### Added diff --git a/src/PostgREST/App.hs b/src/PostgREST/App.hs index ef71e07fb..4f7305797 100644 --- a/src/PostgREST/App.hs +++ b/src/PostgREST/App.hs @@ -98,7 +98,7 @@ run appState = do onWarpException :: Maybe Wai.Request -> SomeException -> IO () onWarpException _ ex = when (shouldDisplayException ex) $ - observer $ WarpErrorObs $ show ex + observer $ WarpServerObs $ show ex -- Similar to wai defaultShouldDisplayException in -- https://github.com/yesodweb/wai//blob/8c3882c60f6abe043889fc20c7efd3fa9747fa4a/warp/Network/Wai/Handler/Warp/Settings.hs#L251-L258 diff --git a/src/PostgREST/Logger.hs b/src/PostgREST/Logger.hs index 8f6150985..3b2d933fd 100644 --- a/src/PostgREST/Logger.hs +++ b/src/PostgREST/Logger.hs @@ -116,6 +116,9 @@ observationLogger loggerState logLevel obs = case obs of o@(JwtCacheLookup _) -> when (logLevel >= LogDebug) $ do logWithZTime loggerState $ observationMessage o + o@(WarpServerObs _) -> + when (logLevel >= LogDebug) $ do + logWithZTime loggerState $ observationMessage o o -> logWithZTime loggerState $ observationMessage o diff --git a/src/PostgREST/Observation.hs b/src/PostgREST/Observation.hs index dc354c26d..f8745922a 100644 --- a/src/PostgREST/Observation.hs +++ b/src/PostgREST/Observation.hs @@ -67,7 +67,7 @@ data Observation | JwtCacheLookup Bool | JwtCacheEviction | TerminationUnixSignalObs Text - | WarpErrorObs Text + | WarpServerObs Text deriving (Generic) data ObsFatalError = ServerAuthError | ServerPgrstBug | ServerError42P05 | ServerError08P01 @@ -167,8 +167,8 @@ observationMessage = \case "Evicted entry from JWT cache" TerminationUnixSignalObs signal -> "Received termination unix signal " <> signal - WarpErrorObs txt -> - "Warp server error: " <> txt + WarpServerObs txt -> + "Warp server: " <> txt where showMillis :: Double -> Text showMillis x = toS $ showFFloat (Just 1) x "" diff --git a/test/io/test_big_schema.py b/test/io/test_big_schema.py index e764bd755..23dea9454 100644 --- a/test/io/test_big_schema.py +++ b/test/io/test_big_schema.py @@ -55,13 +55,15 @@ def test_openapi_in_big_schema(defaultenv): assert response.status_code == 200 -def test_stackoverflow_is_logged(defaultenv): - "Stack overflow errors should be logged with the Warp error message" +@pytest.mark.parametrize("level", ["crit", "error", "warn", "info", "debug"]) +def test_stackoverflow_is_logged(level, defaultenv): + "Stack overflow should be logged with the Warp message only on log-level=debug" env = { **defaultenv, "PGRST_DB_SCHEMAS": "apflora", "PGRST_DB_ANON_ROLE": "postgrest_test_anonymous", + "PGRST_LOG_LEVEL": level, } with run(env=env, wait_max_seconds=30, no_startup_stdout=False) as postgrest: @@ -69,9 +71,15 @@ def test_stackoverflow_is_logged(defaultenv): postgrest.session.get("/") output = postgrest.read_stdout(nlines=10) - output.extend(postgrest.read_stdout(nlines=10)) + for _ in range(3): + output.extend(postgrest.read_stdout(nlines=10)) - assert any("Warp server error: stack overflow" in line for line in output) + found = any("Warp server: stack overflow" in line for line in output) + + if level == "debug": + assert found + else: + assert not found # See: https://github.com/PostgREST/postgrest/issues/3329