From fbcad31a5d187cb044b46e1b00b24b69adf6e5e5 Mon Sep 17 00:00:00 2001 From: Fujii Masao Date: Tue, 7 Apr 2026 10:11:22 +0900 Subject: [PATCH v1] Adjust log level of logical decoding messages by context Commit 21b018e7eab lowered some logical decoding messages from LOG to DEBUG1. However, per discussion on pgsql-hackers, messages from background activity (walsender or slotsync worker) should remain at LOG, as they are less frequent and more likely to indicate issues that DBAs should notice. For foreground SQL functions (e.g., pg_logical_slot_peek_binary_changes()), keep these messages at DEBUG1 to avoid excessive log noise. They can still be enabled by lowering client_min_messages or log_min_messages for the session. This commit updates logical decoding to log these messages at LOG for background activity and at DEBUG1 for foreground execution. --- src/backend/replication/logical/logical.c | 2 +- src/backend/replication/logical/snapbuild.c | 6 +++--- src/include/replication/logical.h | 18 ++++++++++++++++++ .../t/038_save_logical_slots_shutdown.pl | 1 - 4 files changed, 22 insertions(+), 5 deletions(-) diff --git a/src/backend/replication/logical/logical.c b/src/backend/replication/logical/logical.c index f20d0c542f3..5c92fba2f53 100644 --- a/src/backend/replication/logical/logical.c +++ b/src/backend/replication/logical/logical.c @@ -598,7 +598,7 @@ CreateDecodingContext(XLogRecPtr start_lsn, ctx->reorder->output_rewrites = ctx->options.receive_rewrites; - ereport(DEBUG1, + ereport(LogicalDecodingLogLevel(), (errmsg("starting logical decoding for slot \"%s\"", NameStr(slot->data.name)), errdetail("Streaming transactions committing after %X/%08X, reading WAL from %X/%08X.", diff --git a/src/backend/replication/logical/snapbuild.c b/src/backend/replication/logical/snapbuild.c index b4269a3b102..192b1560369 100644 --- a/src/backend/replication/logical/snapbuild.c +++ b/src/backend/replication/logical/snapbuild.c @@ -1312,7 +1312,7 @@ SnapBuildFindSnapshot(SnapBuild *builder, XLogRecPtr lsn, xl_running_xacts *runn builder->state = SNAPBUILD_CONSISTENT; builder->next_phase_at = InvalidTransactionId; - ereport(DEBUG1, + ereport(LogicalDecodingLogLevel(), errmsg("logical decoding found consistent point at %X/%08X", LSN_FORMAT_ARGS(lsn)), errdetail("There are no running transactions.")); @@ -1409,7 +1409,7 @@ SnapBuildFindSnapshot(SnapBuild *builder, XLogRecPtr lsn, xl_running_xacts *runn builder->state = SNAPBUILD_CONSISTENT; builder->next_phase_at = InvalidTransactionId; - ereport(DEBUG1, + ereport(LogicalDecodingLogLevel(), errmsg("logical decoding found consistent point at %X/%08X", LSN_FORMAT_ARGS(lsn)), errdetail("There are no old transactions anymore.")); @@ -1915,7 +1915,7 @@ SnapBuildRestore(SnapBuild *builder, XLogRecPtr lsn) Assert(builder->state == SNAPBUILD_CONSISTENT); - ereport(DEBUG1, + ereport(LogicalDecodingLogLevel(), errmsg("logical decoding found consistent point at %X/%08X", LSN_FORMAT_ARGS(lsn)), errdetail("Logical decoding will begin using saved snapshot.")); diff --git a/src/include/replication/logical.h b/src/include/replication/logical.h index bc9d4ece672..abf64bdc9d4 100644 --- a/src/include/replication/logical.h +++ b/src/include/replication/logical.h @@ -153,4 +153,22 @@ extern XLogRecPtr LogicalReplicationSlotCheckPendingWal(XLogRecPtr end_of_wal, extern XLogRecPtr LogicalSlotAdvanceAndCheckSnapState(XLogRecPtr moveto, bool *found_consistent_snapshot); + +/* + * This macro determines the log level for messages about starting logical + * decoding and finding a consistent point. + * + * When logical decoding is triggered by a foreground SQL function (e.g., + * pg_logical_slot_peek_binary_changes()), these messages are logged at DEBUG1 + * to avoid excessive log noise. This is acceptable since such issues are + * typically less critical, and the messages can still be enabled by lowering + * client_min_messages or log_min_messages for the session. + * + * When the messages originate from background activity (walsender or + * slotsync worker), they are logged at LOG, as these events are less frequent + * and more likely to indicate issues that DBAs should notice by default. + */ +#define LogicalDecodingLogLevel() \ + ((AmWalSenderProcess() || AmLogicalSlotSyncWorkerProcess()) ? LOG : DEBUG1) + #endif diff --git a/src/test/recovery/t/038_save_logical_slots_shutdown.pl b/src/test/recovery/t/038_save_logical_slots_shutdown.pl index 05aa78b4bc7..c0392d50460 100644 --- a/src/test/recovery/t/038_save_logical_slots_shutdown.pl +++ b/src/test/recovery/t/038_save_logical_slots_shutdown.pl @@ -48,7 +48,6 @@ $node_publisher->append_conf( 'postgresql.conf', q{ checkpoint_timeout = 1h autovacuum = off -log_min_messages = 'debug1' }); $node_publisher->start; -- 2.51.2