diff options
| author | Melanie Plageman | 2025-03-12 15:33:08 +0000 |
|---|---|---|
| committer | Melanie Plageman | 2025-03-12 15:35:27 +0000 |
| commit | 18cd15e706ac1f2d6b1c49847a82774ca143352f (patch) | |
| tree | 148f8a5def0fc9ebc2d2ccffa113395b0a4c5aea /src/backend/utils | |
| parent | 9219093cab2607f34ac70612a65430a9c519157f (diff) | |
Add connection establishment duration logging
Add log_connections option 'setup_durations' which logs durations of
several key parts of connection establishment and backend setup.
For an incoming connection, starting from when the postmaster gets a
socket from accept() and ending when the forked child backend is first
ready for query, there are multiple steps that could each take longer
than expected due to external factors. This logging provides visibility
into authentication and fork duration as well as the end-to-end
connection establishment and backend initialization time.
To make this portable, the timings captured in the postmaster (socket
creation time, fork initiation time) are passed through the
BackendStartupData.
Author: Melanie Plageman <[email protected]>
Reviewed-by: Bertrand Drouvot <[email protected]>
Reviewed-by: Fujii Masao <[email protected]>
Reviewed-by: Daniel Gustafsson <[email protected]>
Reviewed-by: Jacob Champion <[email protected]>
Reviewed-by: Jelte Fennema-Nio <[email protected]>
Reviewed-by: Guillaume Lelarge <[email protected]>
Discussion: https://postgr.es/m/flat/CAAKRu_b_smAHK0ZjrnL5GRxnAVWujEXQWpLXYzGbmpcZd3nLYw%40mail.gmail.com
Diffstat (limited to 'src/backend/utils')
| -rw-r--r-- | src/backend/utils/init/postinit.c | 6 | ||||
| -rw-r--r-- | src/backend/utils/misc/postgresql.conf.sample | 2 |
2 files changed, 7 insertions, 1 deletions
diff --git a/src/backend/utils/init/postinit.c b/src/backend/utils/init/postinit.c index 6c207e17768..4b2faf1ba9d 100644 --- a/src/backend/utils/init/postinit.c +++ b/src/backend/utils/init/postinit.c @@ -235,6 +235,9 @@ PerformAuthentication(Port *port) } #endif + /* Capture authentication start time for logging */ + conn_timing.auth_start = GetCurrentTimestamp(); + /* * Set up a timeout in case a buggy or malicious client fails to respond * during authentication. Since we're inside a transaction and might do @@ -253,6 +256,9 @@ PerformAuthentication(Port *port) */ disable_timeout(STATEMENT_TIMEOUT, false); + /* Capture authentication end time for logging */ + conn_timing.auth_end = GetCurrentTimestamp(); + if (log_connections & LOG_CONNECTION_AUTHORIZATION) { StringInfoData logmsg; diff --git a/src/backend/utils/misc/postgresql.conf.sample b/src/backend/utils/misc/postgresql.conf.sample index c291c05d181..d2bd329a587 100644 --- a/src/backend/utils/misc/postgresql.conf.sample +++ b/src/backend/utils/misc/postgresql.conf.sample @@ -580,7 +580,7 @@ #log_checkpoints = on #log_connections = '' # log aspects of connection setup # options include receipt, authentication, authorization, - # and all to log all of these aspects + # setup_durations, and all to log all of these aspects #log_disconnections = off #log_duration = off # log statement duration #log_error_verbosity = default # terse, default, or verbose messages |