[03:22:07.925](0.031s) # setting up data directory # Checking port 61188 # Found port 61188 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives Connection string: port=61188 host=/tmp/P7LXNx5me4 Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [03:22:07.930](0.005s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 49874 [03:22:08.162](0.232s) ok 1 - ssl_library parameter ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50157 [03:22:09.213](1.051s) # testing password-protected keys ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-04 03:22:08.073 UTC [49874][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:08.073 UTC [49874][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:08.074 UTC [49884][startup] LOG: database system was shut down at 2024-04-04 03:19:47 UTC 2024-04-04 03:22:08.077 UTC [49874][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:08.155 UTC [49908][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.156 UTC [49908][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.156 UTC [49908][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.160 UTC [49908][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-04 03:22:08.163 UTC [49908][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.169 UTC [49916][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.170 UTC [49916][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.170 UTC [49916][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.170 UTC [49916][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-04 03:22:08.171 UTC [49916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.213 UTC [49945][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.214 UTC [49945][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.214 UTC [49945][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.214 UTC [49945][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-04 03:22:08.215 UTC [49945][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.256 UTC [49959][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.257 UTC [49959][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.257 UTC [49959][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.257 UTC [49959][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-04 03:22:08.258 UTC [49959][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.305 UTC [49977][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.305 UTC [49977][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.305 UTC [49977][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.306 UTC [49977][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-04 03:22:08.307 UTC [49977][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.351 UTC [49992][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.352 UTC [49992][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.352 UTC [49992][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.352 UTC [49992][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-04 03:22:08.389 UTC [49992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.474 UTC [50034][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.474 UTC [50034][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.474 UTC [50034][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.475 UTC [50034][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-04 03:22:08.520 UTC [50034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.045 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.599 UTC [50062][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.600 UTC [50062][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.600 UTC [50062][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.601 UTC [50062][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-04 03:22:08.630 UTC [50062][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.643 UTC [50067][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.644 UTC [50067][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.644 UTC [50067][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.645 UTC [50067][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-04 03:22:08.690 UTC [50067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.047 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.779 UTC [50101][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.780 UTC [50101][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.780 UTC [50101][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.781 UTC [50101][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-04 03:22:08.812 UTC [50101][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.890 UTC [50118][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.890 UTC [50118][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.890 UTC [50118][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.891 UTC [50118][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-04 03:22:08.948 UTC [50118][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=postgres database=postgres host=[local] 2024-04-04 03:22:09.012 UTC [49874][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.012 UTC [49874][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.013 UTC [49874][postmaster] LOG: background worker "logical replication launcher" (PID 49888) exited with exit code 1 2024-04-04 03:22:09.014 UTC [49882][checkpointer] LOG: shutting down 2024-04-04 03:22:09.014 UTC [49882][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.050 UTC [49882][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.036 s, sync=0.001 s, total=0.037 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414CC38, redo lsn=0/414CC38 2024-04-04 03:22:09.059 UTC [49874][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.131 UTC [50162][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.133 UTC [50157][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:09.215 UTC [50157][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.215 UTC [50157][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.217 UTC [50157][postmaster] LOG: background worker "logical replication launcher" (PID 50165) exited with exit code 1 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: shutting down 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CCB0, redo lsn=0/414CCB0 2024-04-04 03:22:09.219 UTC [50157][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.334 UTC [50213][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-04 03:22:09.335 UTC [50213][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [03:22:09.416](0.203s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 50237 [03:22:09.520](0.103s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-04 03:22:08.073 UTC [49874][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:08.073 UTC [49874][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:08.074 UTC [49884][startup] LOG: database system was shut down at 2024-04-04 03:19:47 UTC 2024-04-04 03:22:08.077 UTC [49874][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:08.155 UTC [49908][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.156 UTC [49908][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.156 UTC [49908][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.160 UTC [49908][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-04 03:22:08.163 UTC [49908][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.169 UTC [49916][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.170 UTC [49916][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.170 UTC [49916][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.170 UTC [49916][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-04 03:22:08.171 UTC [49916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.213 UTC [49945][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.214 UTC [49945][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.214 UTC [49945][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.214 UTC [49945][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-04 03:22:08.215 UTC [49945][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.256 UTC [49959][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.257 UTC [49959][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.257 UTC [49959][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.257 UTC [49959][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-04 03:22:08.258 UTC [49959][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.305 UTC [49977][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.305 UTC [49977][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.305 UTC [49977][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.306 UTC [49977][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-04 03:22:08.307 UTC [49977][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.351 UTC [49992][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.352 UTC [49992][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.352 UTC [49992][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.352 UTC [49992][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-04 03:22:08.389 UTC [49992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.474 UTC [50034][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.474 UTC [50034][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.474 UTC [50034][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.475 UTC [50034][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-04 03:22:08.520 UTC [50034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.045 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.599 UTC [50062][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.600 UTC [50062][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.600 UTC [50062][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.601 UTC [50062][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-04 03:22:08.630 UTC [50062][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.643 UTC [50067][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.644 UTC [50067][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.644 UTC [50067][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.645 UTC [50067][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-04 03:22:08.690 UTC [50067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.047 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.779 UTC [50101][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.780 UTC [50101][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.780 UTC [50101][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.781 UTC [50101][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-04 03:22:08.812 UTC [50101][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.890 UTC [50118][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.890 UTC [50118][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.890 UTC [50118][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.891 UTC [50118][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-04 03:22:08.948 UTC [50118][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=postgres database=postgres host=[local] 2024-04-04 03:22:09.012 UTC [49874][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.012 UTC [49874][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.013 UTC [49874][postmaster] LOG: background worker "logical replication launcher" (PID 49888) exited with exit code 1 2024-04-04 03:22:09.014 UTC [49882][checkpointer] LOG: shutting down 2024-04-04 03:22:09.014 UTC [49882][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.050 UTC [49882][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.036 s, sync=0.001 s, total=0.037 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414CC38, redo lsn=0/414CC38 2024-04-04 03:22:09.059 UTC [49874][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.131 UTC [50162][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.133 UTC [50157][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:09.215 UTC [50157][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.215 UTC [50157][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.217 UTC [50157][postmaster] LOG: background worker "logical replication launcher" (PID 50165) exited with exit code 1 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: shutting down 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CCB0, redo lsn=0/414CCB0 2024-04-04 03:22:09.219 UTC [50157][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.334 UTC [50213][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-04 03:22:09.335 UTC [50213][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.438 UTC [50237][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.438 UTC [50237][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.438 UTC [50237][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.442 UTC [50250][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.444 UTC [50237][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:09.522 UTC [50237][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.522 UTC [50237][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.523 UTC [50237][postmaster] LOG: background worker "logical replication launcher" (PID 50256) exited with exit code 1 2024-04-04 03:22:09.525 UTC [50248][checkpointer] LOG: shutting down 2024-04-04 03:22:09.525 UTC [50248][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.526 UTC [50248][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CD28, redo lsn=0/414CD28 2024-04-04 03:22:09.528 UTC [50237][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.635 UTC [50283][postmaster] FATAL: could not set SSL protocol version range 2024-04-04 03:22:09.635 UTC [50283][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-04 03:22:09.635 UTC [50283][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [03:22:09.723](0.203s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 50313 [03:22:09.828](0.105s) ok 5 - restart succeeds with correct SSL protocol bounds [03:22:09.828](0.000s) # running client tests ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50396 [03:22:10.044](0.216s) ok 6 - server doesn't accept non-SSL connections [03:22:10.045](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [03:22:10.060](0.016s) ok 8 - connect without server root cert sslmode=require [03:22:10.060](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [03:22:10.068](0.008s) ok 10 - connect without server root cert sslmode=verify-ca [03:22:10.068](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [03:22:10.076](0.008s) ok 12 - connect without server root cert sslmode=verify-full [03:22:10.076](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [03:22:10.086](0.010s) ok 14 - connect with wrong server root cert sslmode=require [03:22:10.086](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [03:22:10.096](0.010s) ok 16 - connect with wrong server root cert sslmode=verify-ca [03:22:10.096](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [03:22:10.118](0.021s) ok 18 - connect with wrong server root cert sslmode=verify-full [03:22:10.118](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [03:22:10.135](0.017s) ok 20 - connect with server CA cert, without root CA [03:22:10.135](0.000s) ok 21 - connect with server CA cert, without root CA: matches [03:22:10.149](0.014s) ok 22 - connect with correct server CA cert file sslmode=require [03:22:10.149](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [03:22:10.161](0.012s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [03:22:10.161](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [03:22:10.172](0.011s) ok 26 - connect with correct server CA cert file sslmode=verify-full [03:22:10.172](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [03:22:10.184](0.011s) ok 28 - cert root file that contains two certificates, order 1 [03:22:10.184](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [03:22:10.195](0.011s) ok 30 - cert root file that contains two certificates, order 2 [03:22:10.195](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [03:22:10.207](0.011s) ok 32 - connect with sslcertmode=disable [03:22:10.207](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [03:22:10.227](0.020s) ok 34 - connect with sslcertmode=allow [03:22:10.227](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [03:22:10.240](0.013s) ok 36 - connect with sslcertmode=require fails without a client certificate [03:22:10.240](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [03:22:10.253](0.012s) ok 38 - sslcrl option with invalid file name [03:22:10.253](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [03:22:10.263](0.010s) ok 40 - CRL belonging to a different CA [03:22:10.264](0.000s) ok 41 - CRL belonging to a different CA: matches [03:22:10.274](0.010s) ok 42 - directory CRL belonging to a different CA [03:22:10.274](0.000s) ok 43 - directory CRL belonging to a different CA: matches [03:22:10.286](0.012s) ok 44 - CRL with a non-revoked cert [03:22:10.286](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [03:22:10.299](0.012s) ok 46 - directory CRL with a non-revoked cert [03:22:10.299](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [03:22:10.310](0.012s) ok 48 - mismatch between host name and server certificate sslmode=require [03:22:10.311](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [03:22:10.322](0.012s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [03:22:10.322](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [03:22:10.333](0.010s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [03:22:10.333](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50521 [03:22:10.552](0.220s) ok 54 - IP address in the Common Name [03:22:10.553](0.000s) ok 55 - IP address in the Common Name: no stderr [03:22:10.564](0.011s) ok 56 - mismatch between host name and server certificate IP address [03:22:10.564](0.000s) ok 57 - mismatch between host name and server certificate IP address: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50548 [03:22:10.788](0.224s) ok 58 - IP address in a dNSName [03:22:10.788](0.000s) ok 59 - IP address in a dNSName: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50571 [03:22:11.009](0.221s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [03:22:11.009](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [03:22:11.023](0.014s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [03:22:11.024](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [03:22:11.038](0.014s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [03:22:11.038](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [03:22:11.049](0.011s) ok 66 - host name not matching with X.509 Subject Alternative Names [03:22:11.049](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [03:22:11.061](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [03:22:11.061](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50603 [03:22:11.281](0.220s) ok 70 - host name matching with a single X.509 Subject Alternative Name [03:22:11.282](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [03:22:11.292](0.010s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [03:22:11.292](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [03:22:11.302](0.010s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [03:22:11.302](0.000s) ok 75 - host name not matching with a single X.509 Subject Alternative Name wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50622 [03:22:11.526](0.223s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [03:22:11.526](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [03:22:11.540](0.014s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [03:22:11.540](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [03:22:11.551](0.011s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [03:22:11.551](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [03:22:11.564](0.012s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [03:22:11.564](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [03:22:11.576](0.012s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [03:22:11.576](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [03:22:11.587](0.011s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [03:22:11.587](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [03:22:11.598](0.010s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [03:22:11.598](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [03:22:11.609](0.011s) ok 90 - IPv6 host with CIDR mask does not match [03:22:11.609](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50649 [03:22:11.829](0.220s) ok 92 - certificate with both a CN and SANs 1 [03:22:11.830](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [03:22:11.842](0.012s) ok 94 - certificate with both a CN and SANs 2 [03:22:11.842](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [03:22:11.852](0.010s) ok 96 - certificate with both a CN and SANs ignores CN [03:22:11.852](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50664 [03:22:12.074](0.222s) ok 98 - certificate with both a CN and IP SANs matches CN [03:22:12.074](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [03:22:12.086](0.012s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [03:22:12.086](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [03:22:12.097](0.011s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [03:22:12.097](0.000s) ok 103 - certificate with both a CN and IP SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50678 [03:22:12.317](0.220s) ok 104 - certificate with both an IP CN and IP SANs 1 [03:22:12.317](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [03:22:12.328](0.011s) ok 106 - certificate with both an IP CN and IP SANs 2 [03:22:12.328](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [03:22:12.338](0.010s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [03:22:12.339](0.000s) ok 109 - certificate with both an IP CN and IP SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50692 [03:22:12.559](0.221s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [03:22:12.559](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [03:22:12.572](0.012s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [03:22:12.572](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [03:22:12.583](0.011s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [03:22:12.583](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50706 [03:22:12.803](0.220s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [03:22:12.803](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [03:22:12.813](0.010s) ok 118 - server certificate without CN or SANs sslmode=verify-full [03:22:12.813](0.000s) ok 119 - server certificate without CN or SANs sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50718 [03:22:13.027](0.213s) ok 120 - sslrootcert=system does not connect with private CA [03:22:13.027](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [03:22:13.033](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [03:22:13.033](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [03:22:13.048](0.015s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [03:22:13.048](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [03:22:13.057](0.009s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [03:22:13.057](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50733 [03:22:13.277](0.220s) ok 128 - connects without client-side CRL [03:22:13.277](0.000s) ok 129 - connects without client-side CRL: no stderr [03:22:13.287](0.010s) ok 130 - does not connect with client-side CRL file [03:22:13.287](0.000s) ok 131 - does not connect with client-side CRL file: matches [03:22:13.296](0.009s) ok 132 - does not connect with client-side CRL directory [03:22:13.297](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [03:22:13.308](0.011s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [03:22:13.308](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [03:22:13.308](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [03:22:13.319](0.011s) ok 137 - connection success with correct range of TLS protocol versions [03:22:13.319](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [03:22:13.325](0.006s) ok 139 - connection failure with incorrect range of TLS protocol versions [03:22:13.325](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [03:22:13.330](0.005s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [03:22:13.330](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [03:22:13.335](0.005s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [03:22:13.336](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [03:22:13.336](0.000s) # running server tests [03:22:13.345](0.010s) ok 145 - certificate authorization fails without client cert [03:22:13.346](0.000s) ok 146 - certificate authorization fails without client cert: matches [03:22:13.362](0.016s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [03:22:13.362](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [03:22:13.374](0.013s) ok 149 - certificate authorization succeeds with correct client cert in DER format [03:22:13.374](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [03:22:13.387](0.013s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [03:22:13.387](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [03:22:13.399](0.012s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [03:22:13.399](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [03:22:13.411](0.012s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [03:22:13.411](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [03:22:13.426](0.015s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [03:22:13.427](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [03:22:13.438](0.011s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [03:22:13.438](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [03:22:13.447](0.009s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [03:22:13.448](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [03:22:13.464](0.016s) ok 163 - certificate authorization succeeds with DN mapping [03:22:13.464](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [03:22:13.464](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [03:22:13.481](0.017s) ok 166 - certificate authorization succeeds with DN regex mapping [03:22:13.481](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [03:22:13.499](0.018s) ok 168 - certificate authorization succeeds with CN mapping [03:22:13.499](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [03:22:13.499](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [03:22:13.500](0.000s) not ok 171 # TODO & SKIP Need Pty support [03:22:13.500](0.000s) not ok 172 # TODO & SKIP Need Pty support [03:22:13.500](0.000s) not ok 173 # TODO & SKIP Need Pty support [03:22:13.500](0.000s) not ok 174 # TODO & SKIP Need Pty support # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [03:22:13.522](0.023s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [03:22:13.523](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [03:22:13.523](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [03:22:13.531](0.009s) ok 178 - certificate authorization fails because of file permissions [03:22:13.532](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [03:22:13.543](0.012s) ok 180 - certificate authorization fails with client cert belonging to another user [03:22:13.544](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [03:22:13.554](0.011s) ok 182 - certificate authorization fails with revoked client cert [03:22:13.555](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [03:22:13.555](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [03:22:13.571](0.017s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [03:22:13.572](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [03:22:13.572](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [03:22:13.584](0.012s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [03:22:13.584](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [03:22:13.584](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [03:22:13.597](0.013s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [03:22:13.597](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [03:22:13.598](0.000s) ok 193 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: log matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50793 [03:22:13.820](0.222s) ok 194 - intermediate client certificate is provided by client [03:22:13.820](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [03:22:13.832](0.012s) ok 196 - intermediate client certificate is missing [03:22:13.832](0.000s) ok 197 - intermediate client certificate is missing: matches [03:22:13.843](0.011s) ok 198 - logged client certificate Subjects are truncated if they're too long [03:22:13.843](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50807 [03:22:14.058](0.215s) ok 200 - intermediate client certificate is untrusted [03:22:14.059](0.000s) ok 201 - intermediate client certificate is untrusted: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50817 [03:22:14.274](0.216s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [03:22:14.275](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [03:22:14.286](0.012s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [03:22:14.286](0.000s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50829 [03:22:14.500](0.213s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [03:22:14.500](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [03:22:14.500](0.000s) # got: '2' # expected: '0' [03:22:14.500](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [03:22:14.501](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [03:22:14.501](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 61188 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [03:22:14.516](0.016s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [03:22:14.517](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50841 [03:22:14.731](0.214s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [03:22:14.746](0.016s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [03:22:14.747](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50853 [03:22:14.961](0.214s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [03:22:14.977](0.017s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [03:22:14.977](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50865 [03:22:15.192](0.215s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [03:22:15.209](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [03:22:15.209](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50877 [03:22:15.423](0.214s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [03:22:15.424](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [03:22:15.424](0.000s) # got: '2' # expected: '0' [03:22:15.424](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [03:22:15.424](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [03:22:15.424](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 61188 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [03:22:15.440](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [03:22:15.440](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50889 [03:22:15.655](0.214s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [03:22:15.670](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [03:22:15.671](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50901 [03:22:15.885](0.214s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [03:22:15.900](0.016s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [03:22:15.900](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50913 [03:22:16.114](0.214s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [03:22:16.130](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [03:22:16.131](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50925 [03:22:16.348](0.217s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [03:22:16.364](0.016s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [03:22:16.364](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-04 03:22:08.073 UTC [49874][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:08.073 UTC [49874][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:08.074 UTC [49884][startup] LOG: database system was shut down at 2024-04-04 03:19:47 UTC 2024-04-04 03:22:08.077 UTC [49874][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:08.155 UTC [49908][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.156 UTC [49908][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.156 UTC [49908][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.160 UTC [49908][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-04 03:22:08.163 UTC [49908][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.169 UTC [49916][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.170 UTC [49916][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.170 UTC [49916][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.170 UTC [49916][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-04 03:22:08.171 UTC [49916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.213 UTC [49945][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.214 UTC [49945][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.214 UTC [49945][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.214 UTC [49945][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-04 03:22:08.215 UTC [49945][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.256 UTC [49959][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.257 UTC [49959][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.257 UTC [49959][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.257 UTC [49959][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-04 03:22:08.258 UTC [49959][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.305 UTC [49977][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.305 UTC [49977][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.305 UTC [49977][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.306 UTC [49977][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-04 03:22:08.307 UTC [49977][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.351 UTC [49992][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.352 UTC [49992][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.352 UTC [49992][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.352 UTC [49992][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-04 03:22:08.389 UTC [49992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.474 UTC [50034][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.474 UTC [50034][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.474 UTC [50034][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.475 UTC [50034][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-04 03:22:08.520 UTC [50034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.045 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.599 UTC [50062][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.600 UTC [50062][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.600 UTC [50062][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.601 UTC [50062][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-04 03:22:08.630 UTC [50062][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.643 UTC [50067][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.644 UTC [50067][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.644 UTC [50067][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.645 UTC [50067][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-04 03:22:08.690 UTC [50067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.047 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.779 UTC [50101][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.780 UTC [50101][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.780 UTC [50101][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.781 UTC [50101][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-04 03:22:08.812 UTC [50101][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-04-04 03:22:08.890 UTC [50118][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-04 03:22:08.890 UTC [50118][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-04 03:22:08.890 UTC [50118][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-04 03:22:08.891 UTC [50118][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-04 03:22:08.948 UTC [50118][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=postgres database=postgres host=[local] 2024-04-04 03:22:09.012 UTC [49874][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.012 UTC [49874][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.013 UTC [49874][postmaster] LOG: background worker "logical replication launcher" (PID 49888) exited with exit code 1 2024-04-04 03:22:09.014 UTC [49882][checkpointer] LOG: shutting down 2024-04-04 03:22:09.014 UTC [49882][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.050 UTC [49882][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.036 s, sync=0.001 s, total=0.037 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414CC38, redo lsn=0/414CC38 2024-04-04 03:22:09.059 UTC [49874][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.129 UTC [50157][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.131 UTC [50162][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.133 UTC [50157][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:09.215 UTC [50157][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.215 UTC [50157][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.217 UTC [50157][postmaster] LOG: background worker "logical replication launcher" (PID 50165) exited with exit code 1 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: shutting down 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.217 UTC [50160][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CCB0, redo lsn=0/414CCB0 2024-04-04 03:22:09.219 UTC [50157][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.334 UTC [50213][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-04 03:22:09.335 UTC [50213][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.438 UTC [50237][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.438 UTC [50237][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.438 UTC [50237][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.442 UTC [50250][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.444 UTC [50237][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:09.522 UTC [50237][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.522 UTC [50237][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.523 UTC [50237][postmaster] LOG: background worker "logical replication launcher" (PID 50256) exited with exit code 1 2024-04-04 03:22:09.525 UTC [50248][checkpointer] LOG: shutting down 2024-04-04 03:22:09.525 UTC [50248][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.526 UTC [50248][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CD28, redo lsn=0/414CD28 2024-04-04 03:22:09.528 UTC [50237][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.635 UTC [50283][postmaster] FATAL: could not set SSL protocol version range 2024-04-04 03:22:09.635 UTC [50283][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-04 03:22:09.635 UTC [50283][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.746 UTC [50313][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.746 UTC [50313][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.746 UTC [50313][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.748 UTC [50322][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.750 UTC [50313][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:09.835 UTC [50313][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:09.835 UTC [50313][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:09.836 UTC [50313][postmaster] LOG: background worker "logical replication launcher" (PID 50325) exited with exit code 1 2024-04-04 03:22:09.838 UTC [50320][checkpointer] LOG: shutting down 2024-04-04 03:22:09.838 UTC [50320][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:09.839 UTC [50320][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CDA0, redo lsn=0/414CDA0 2024-04-04 03:22:09.841 UTC [50313][postmaster] LOG: database system is shut down 2024-04-04 03:22:09.952 UTC [50396][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:09.952 UTC [50396][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:09.952 UTC [50396][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:09.954 UTC [50401][startup] LOG: database system was shut down at 2024-04-04 03:22:09 UTC 2024-04-04 03:22:09.957 UTC [50396][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:10.043 UTC [50424][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37322 2024-04-04 03:22:10.043 UTC [50424][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-04-04 03:22:10.043 UTC [50424][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-04-04 03:22:10.050 UTC [50427][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37326 2024-04-04 03:22:10.054 UTC [50427][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.054 UTC [50427][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.059 UTC [50427][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-04-04 03:22:10.059 UTC [50427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=37326 2024-04-04 03:22:10.066 UTC [50430][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37328 2024-04-04 03:22:10.067 UTC [50430][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-04 03:22:10.074 UTC [50432][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37344 2024-04-04 03:22:10.075 UTC [50432][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-04 03:22:10.082 UTC [50434][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37348 2024-04-04 03:22:10.085 UTC [50434][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:10.092 UTC [50437][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37358 2024-04-04 03:22:10.097 UTC [50437][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:10.114 UTC [50442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37360 2024-04-04 03:22:10.117 UTC [50442][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:10.129 UTC [50448][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37364 2024-04-04 03:22:10.133 UTC [50448][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:10.142 UTC [50459][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37374 2024-04-04 03:22:10.146 UTC [50459][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.146 UTC [50459][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.147 UTC [50459][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-04-04 03:22:10.148 UTC [50459][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37374 2024-04-04 03:22:10.155 UTC [50461][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37378 2024-04-04 03:22:10.159 UTC [50461][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.159 UTC [50461][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.159 UTC [50461][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-04-04 03:22:10.160 UTC [50461][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37378 2024-04-04 03:22:10.167 UTC [50463][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37388 2024-04-04 03:22:10.170 UTC [50463][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.170 UTC [50463][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.171 UTC [50463][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-04-04 03:22:10.171 UTC [50463][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37388 2024-04-04 03:22:10.178 UTC [50465][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37392 2024-04-04 03:22:10.182 UTC [50465][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.182 UTC [50465][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.182 UTC [50465][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-04-04 03:22:10.183 UTC [50465][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37392 2024-04-04 03:22:10.190 UTC [50467][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37396 2024-04-04 03:22:10.193 UTC [50467][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.193 UTC [50467][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.194 UTC [50467][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-04-04 03:22:10.194 UTC [50467][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37396 2024-04-04 03:22:10.201 UTC [50469][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37410 2024-04-04 03:22:10.204 UTC [50469][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.204 UTC [50469][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.205 UTC [50469][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-04-04 03:22:10.205 UTC [50469][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37410 2024-04-04 03:22:10.214 UTC [50476][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37426 2024-04-04 03:22:10.217 UTC [50476][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.217 UTC [50476][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.218 UTC [50476][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-04-04 03:22:10.219 UTC [50476][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37426 2024-04-04 03:22:10.233 UTC [50483][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37442 2024-04-04 03:22:10.237 UTC [50483][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.237 UTC [50483][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.241 UTC [50483][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=37442 2024-04-04 03:22:10.247 UTC [50486][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37458 2024-04-04 03:22:10.250 UTC [50486][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.251 UTC [50486][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.251 UTC [50486][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-04-04 03:22:10.252 UTC [50486][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37458 2024-04-04 03:22:10.259 UTC [50489][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37460 2024-04-04 03:22:10.262 UTC [50489][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:10.270 UTC [50491][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37468 2024-04-04 03:22:10.273 UTC [50491][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:10.280 UTC [50493][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37478 2024-04-04 03:22:10.284 UTC [50493][client backend] [[unknown]][11/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.284 UTC [50493][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.285 UTC [50493][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-04-04 03:22:10.285 UTC [50493][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37478 2024-04-04 03:22:10.293 UTC [50495][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37494 2024-04-04 03:22:10.296 UTC [50495][client backend] [[unknown]][12/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.296 UTC [50495][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.297 UTC [50495][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-04-04 03:22:10.298 UTC [50495][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37494 2024-04-04 03:22:10.305 UTC [50497][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37496 2024-04-04 03:22:10.308 UTC [50497][client backend] [[unknown]][13/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.308 UTC [50497][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.309 UTC [50497][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-04-04 03:22:10.309 UTC [50497][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37496 2024-04-04 03:22:10.317 UTC [50499][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37500 2024-04-04 03:22:10.320 UTC [50499][client backend] [[unknown]][14/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.320 UTC [50499][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.321 UTC [50499][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-04-04 03:22:10.321 UTC [50499][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37500 2024-04-04 03:22:10.328 UTC [50502][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37502 2024-04-04 03:22:10.335 UTC [50396][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:10.335 UTC [50396][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:10.336 UTC [50396][postmaster] LOG: background worker "logical replication launcher" (PID 50404) exited with exit code 1 2024-04-04 03:22:10.336 UTC [50399][checkpointer] LOG: shutting down 2024-04-04 03:22:10.337 UTC [50399][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:10.337 UTC [50399][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CE18, redo lsn=0/414CE18 2024-04-04 03:22:10.339 UTC [50396][postmaster] LOG: database system is shut down 2024-04-04 03:22:10.452 UTC [50521][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:10.452 UTC [50521][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:10.452 UTC [50521][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:10.455 UTC [50528][startup] LOG: database system was shut down at 2024-04-04 03:22:10 UTC 2024-04-04 03:22:10.458 UTC [50521][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:10.542 UTC [50533][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37516 2024-04-04 03:22:10.546 UTC [50533][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.546 UTC [50533][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.551 UTC [50533][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-04 03:22:10.551 UTC [50533][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37516 2024-04-04 03:22:10.559 UTC [50537][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37522 2024-04-04 03:22:10.567 UTC [50521][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:10.567 UTC [50521][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:10.568 UTC [50521][postmaster] LOG: background worker "logical replication launcher" (PID 50531) exited with exit code 1 2024-04-04 03:22:10.569 UTC [50526][checkpointer] LOG: shutting down 2024-04-04 03:22:10.569 UTC [50526][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:10.570 UTC [50526][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CE90, redo lsn=0/414CE90 2024-04-04 03:22:10.573 UTC [50521][postmaster] LOG: database system is shut down 2024-04-04 03:22:10.686 UTC [50548][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:10.686 UTC [50548][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:10.686 UTC [50548][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:10.688 UTC [50552][startup] LOG: database system was shut down at 2024-04-04 03:22:10 UTC 2024-04-04 03:22:10.691 UTC [50548][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:10.777 UTC [50559][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37538 2024-04-04 03:22:10.781 UTC [50559][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:10.781 UTC [50559][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:10.786 UTC [50559][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-04 03:22:10.786 UTC [50559][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37538 2024-04-04 03:22:10.790 UTC [50548][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:10.790 UTC [50548][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:10.791 UTC [50548][postmaster] LOG: background worker "logical replication launcher" (PID 50555) exited with exit code 1 2024-04-04 03:22:10.792 UTC [50550][checkpointer] LOG: shutting down 2024-04-04 03:22:10.792 UTC [50550][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:10.792 UTC [50550][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CF08, redo lsn=0/414CF08 2024-04-04 03:22:10.795 UTC [50548][postmaster] LOG: database system is shut down 2024-04-04 03:22:10.908 UTC [50571][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:10.908 UTC [50571][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:10.908 UTC [50571][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:10.910 UTC [50575][startup] LOG: database system was shut down at 2024-04-04 03:22:10 UTC 2024-04-04 03:22:10.915 UTC [50571][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:10.999 UTC [50581][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37542 2024-04-04 03:22:11.002 UTC [50581][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.002 UTC [50581][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.007 UTC [50581][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:11.008 UTC [50581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37542 2024-04-04 03:22:11.017 UTC [50584][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37544 2024-04-04 03:22:11.021 UTC [50584][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.021 UTC [50584][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.022 UTC [50584][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:11.022 UTC [50584][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37544 2024-04-04 03:22:11.031 UTC [50587][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37548 2024-04-04 03:22:11.035 UTC [50587][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.035 UTC [50587][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.036 UTC [50587][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-04-04 03:22:11.037 UTC [50587][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=37548 2024-04-04 03:22:11.045 UTC [50596][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37562 2024-04-04 03:22:11.056 UTC [50598][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37574 2024-04-04 03:22:11.064 UTC [50571][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:11.064 UTC [50571][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:11.065 UTC [50571][postmaster] LOG: background worker "logical replication launcher" (PID 50579) exited with exit code 1 2024-04-04 03:22:11.067 UTC [50573][checkpointer] LOG: shutting down 2024-04-04 03:22:11.067 UTC [50573][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:11.067 UTC [50573][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CF80, redo lsn=0/414CF80 2024-04-04 03:22:11.070 UTC [50571][postmaster] LOG: database system is shut down 2024-04-04 03:22:11.181 UTC [50603][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:11.181 UTC [50603][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:11.181 UTC [50603][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:11.183 UTC [50606][startup] LOG: database system was shut down at 2024-04-04 03:22:11 UTC 2024-04-04 03:22:11.186 UTC [50603][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:11.272 UTC [50615][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37590 2024-04-04 03:22:11.275 UTC [50615][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.275 UTC [50615][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.280 UTC [50615][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:11.280 UTC [50615][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=37590 2024-04-04 03:22:11.288 UTC [50617][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37602 2024-04-04 03:22:11.298 UTC [50619][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37604 2024-04-04 03:22:11.306 UTC [50603][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:11.306 UTC [50603][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:11.307 UTC [50603][postmaster] LOG: background worker "logical replication launcher" (PID 50610) exited with exit code 1 2024-04-04 03:22:11.308 UTC [50604][checkpointer] LOG: shutting down 2024-04-04 03:22:11.308 UTC [50604][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:11.309 UTC [50604][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CFF8, redo lsn=0/414CFF8 2024-04-04 03:22:11.311 UTC [50603][postmaster] LOG: database system is shut down 2024-04-04 03:22:11.424 UTC [50622][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:11.424 UTC [50622][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:11.424 UTC [50622][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:11.427 UTC [50627][startup] LOG: database system was shut down at 2024-04-04 03:22:11 UTC 2024-04-04 03:22:11.429 UTC [50622][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:11.515 UTC [50633][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37616 2024-04-04 03:22:11.519 UTC [50633][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.519 UTC [50633][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.524 UTC [50633][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-04 03:22:11.524 UTC [50633][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37616 2024-04-04 03:22:11.533 UTC [50635][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37632 2024-04-04 03:22:11.537 UTC [50635][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.537 UTC [50635][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.538 UTC [50635][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-04-04 03:22:11.538 UTC [50635][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37632 2024-04-04 03:22:11.547 UTC [50637][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37634 2024-04-04 03:22:11.558 UTC [50639][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37648 2024-04-04 03:22:11.561 UTC [50639][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.561 UTC [50639][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.562 UTC [50639][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-04-04 03:22:11.563 UTC [50639][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37648 2024-04-04 03:22:11.570 UTC [50641][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37656 2024-04-04 03:22:11.574 UTC [50641][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.574 UTC [50641][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.575 UTC [50641][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-04-04 03:22:11.575 UTC [50641][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37656 2024-04-04 03:22:11.582 UTC [50643][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37664 2024-04-04 03:22:11.585 UTC [50643][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.585 UTC [50643][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.586 UTC [50643][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-04-04 03:22:11.586 UTC [50643][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37664 2024-04-04 03:22:11.593 UTC [50645][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37680 2024-04-04 03:22:11.605 UTC [50647][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37692 2024-04-04 03:22:11.611 UTC [50622][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:11.611 UTC [50622][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:11.613 UTC [50622][postmaster] LOG: background worker "logical replication launcher" (PID 50630) exited with exit code 1 2024-04-04 03:22:11.613 UTC [50625][checkpointer] LOG: shutting down 2024-04-04 03:22:11.613 UTC [50625][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:11.613 UTC [50625][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D070, redo lsn=0/414D070 2024-04-04 03:22:11.616 UTC [50622][postmaster] LOG: database system is shut down 2024-04-04 03:22:11.731 UTC [50649][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:11.732 UTC [50649][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:11.732 UTC [50649][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:11.734 UTC [50652][startup] LOG: database system was shut down at 2024-04-04 03:22:11 UTC 2024-04-04 03:22:11.736 UTC [50649][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:11.819 UTC [50657][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37700 2024-04-04 03:22:11.823 UTC [50657][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.823 UTC [50657][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.828 UTC [50657][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:11.828 UTC [50657][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37700 2024-04-04 03:22:11.836 UTC [50659][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37714 2024-04-04 03:22:11.840 UTC [50659][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:11.840 UTC [50659][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:11.841 UTC [50659][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:11.841 UTC [50659][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37714 2024-04-04 03:22:11.848 UTC [50661][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37716 2024-04-04 03:22:11.856 UTC [50649][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:11.856 UTC [50649][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:11.857 UTC [50649][postmaster] LOG: background worker "logical replication launcher" (PID 50655) exited with exit code 1 2024-04-04 03:22:11.857 UTC [50650][checkpointer] LOG: shutting down 2024-04-04 03:22:11.858 UTC [50650][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:11.858 UTC [50650][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D0E8, redo lsn=0/414D0E8 2024-04-04 03:22:11.860 UTC [50649][postmaster] LOG: database system is shut down 2024-04-04 03:22:11.973 UTC [50664][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:11.973 UTC [50664][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:11.973 UTC [50664][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:11.975 UTC [50667][startup] LOG: database system was shut down at 2024-04-04 03:22:11 UTC 2024-04-04 03:22:11.978 UTC [50664][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:12.064 UTC [50672][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37728 2024-04-04 03:22:12.067 UTC [50672][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.067 UTC [50672][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.072 UTC [50672][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-04-04 03:22:12.073 UTC [50672][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37728 2024-04-04 03:22:12.080 UTC [50674][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37736 2024-04-04 03:22:12.083 UTC [50674][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.083 UTC [50674][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.084 UTC [50674][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-04 03:22:12.085 UTC [50674][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37736 2024-04-04 03:22:12.091 UTC [50676][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37740 2024-04-04 03:22:12.095 UTC [50676][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.095 UTC [50676][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.095 UTC [50676][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-04-04 03:22:12.096 UTC [50676][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37740 2024-04-04 03:22:12.099 UTC [50664][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:12.099 UTC [50664][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:12.100 UTC [50664][postmaster] LOG: background worker "logical replication launcher" (PID 50670) exited with exit code 1 2024-04-04 03:22:12.100 UTC [50665][checkpointer] LOG: shutting down 2024-04-04 03:22:12.100 UTC [50665][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:12.100 UTC [50665][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D160, redo lsn=0/414D160 2024-04-04 03:22:12.103 UTC [50664][postmaster] LOG: database system is shut down 2024-04-04 03:22:12.216 UTC [50678][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:12.216 UTC [50678][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:12.216 UTC [50678][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:12.218 UTC [50681][startup] LOG: database system was shut down at 2024-04-04 03:22:12 UTC 2024-04-04 03:22:12.221 UTC [50678][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:12.307 UTC [50686][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37750 2024-04-04 03:22:12.310 UTC [50686][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.310 UTC [50686][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.315 UTC [50686][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-04-04 03:22:12.316 UTC [50686][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37750 2024-04-04 03:22:12.323 UTC [50688][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37754 2024-04-04 03:22:12.326 UTC [50688][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.326 UTC [50688][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.327 UTC [50688][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-04-04 03:22:12.327 UTC [50688][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37754 2024-04-04 03:22:12.334 UTC [50690][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37760 2024-04-04 03:22:12.340 UTC [50678][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:12.340 UTC [50678][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:12.341 UTC [50678][postmaster] LOG: background worker "logical replication launcher" (PID 50684) exited with exit code 1 2024-04-04 03:22:12.342 UTC [50679][checkpointer] LOG: shutting down 2024-04-04 03:22:12.342 UTC [50679][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:12.343 UTC [50679][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D1D8, redo lsn=0/414D1D8 2024-04-04 03:22:12.345 UTC [50678][postmaster] LOG: database system is shut down 2024-04-04 03:22:12.466 UTC [50692][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:12.466 UTC [50692][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:12.466 UTC [50692][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:12.469 UTC [50695][startup] LOG: database system was shut down at 2024-04-04 03:22:12 UTC 2024-04-04 03:22:12.472 UTC [50692][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:12.548 UTC [50700][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37768 2024-04-04 03:22:12.553 UTC [50700][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.553 UTC [50700][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.558 UTC [50700][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-04 03:22:12.558 UTC [50700][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=37768 2024-04-04 03:22:12.566 UTC [50702][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37770 2024-04-04 03:22:12.569 UTC [50702][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.569 UTC [50702][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.570 UTC [50702][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:12.571 UTC [50702][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37770 2024-04-04 03:22:12.578 UTC [50704][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37774 2024-04-04 03:22:12.581 UTC [50704][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.581 UTC [50704][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.582 UTC [50704][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-04-04 03:22:12.582 UTC [50704][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37774 2024-04-04 03:22:12.585 UTC [50692][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:12.585 UTC [50692][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:12.586 UTC [50692][postmaster] LOG: background worker "logical replication launcher" (PID 50698) exited with exit code 1 2024-04-04 03:22:12.587 UTC [50693][checkpointer] LOG: shutting down 2024-04-04 03:22:12.587 UTC [50693][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:12.587 UTC [50693][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D250, redo lsn=0/414D250 2024-04-04 03:22:12.590 UTC [50692][postmaster] LOG: database system is shut down 2024-04-04 03:22:12.703 UTC [50706][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:12.703 UTC [50706][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:12.703 UTC [50706][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:12.705 UTC [50709][startup] LOG: database system was shut down at 2024-04-04 03:22:12 UTC 2024-04-04 03:22:12.708 UTC [50706][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:12.793 UTC [50714][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37788 2024-04-04 03:22:12.797 UTC [50714][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:12.797 UTC [50714][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:12.801 UTC [50714][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-04-04 03:22:12.802 UTC [50714][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=37788 2024-04-04 03:22:12.809 UTC [50716][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37790 2024-04-04 03:22:12.815 UTC [50706][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:12.815 UTC [50706][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:12.816 UTC [50706][postmaster] LOG: background worker "logical replication launcher" (PID 50712) exited with exit code 1 2024-04-04 03:22:12.816 UTC [50707][checkpointer] LOG: shutting down 2024-04-04 03:22:12.816 UTC [50707][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:12.817 UTC [50707][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D2C8, redo lsn=0/414D2C8 2024-04-04 03:22:12.819 UTC [50706][postmaster] LOG: database system is shut down 2024-04-04 03:22:12.933 UTC [50718][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:12.933 UTC [50718][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:12.933 UTC [50718][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:12.935 UTC [50721][startup] LOG: database system was shut down at 2024-04-04 03:22:12 UTC 2024-04-04 03:22:12.938 UTC [50718][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:13.023 UTC [50726][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37802 2024-04-04 03:22:13.026 UTC [50726][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-04 03:22:13.038 UTC [50729][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37804 2024-04-04 03:22:13.041 UTC [50729][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:13.041 UTC [50729][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.046 UTC [50729][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-04-04 03:22:13.047 UTC [50729][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=37804 2024-04-04 03:22:13.053 UTC [50731][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37816 2024-04-04 03:22:13.059 UTC [50718][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:13.059 UTC [50718][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:13.060 UTC [50718][postmaster] LOG: background worker "logical replication launcher" (PID 50724) exited with exit code 1 2024-04-04 03:22:13.060 UTC [50719][checkpointer] LOG: shutting down 2024-04-04 03:22:13.060 UTC [50719][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:13.061 UTC [50719][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D340, redo lsn=0/414D340 2024-04-04 03:22:13.063 UTC [50718][postmaster] LOG: database system is shut down 2024-04-04 03:22:13.177 UTC [50733][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:13.177 UTC [50733][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:13.177 UTC [50733][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:13.179 UTC [50736][startup] LOG: database system was shut down at 2024-04-04 03:22:13 UTC 2024-04-04 03:22:13.181 UTC [50733][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:13.267 UTC [50741][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37828 2024-04-04 03:22:13.271 UTC [50741][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:13.271 UTC [50741][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.276 UTC [50741][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-04-04 03:22:13.276 UTC [50741][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37828 2024-04-04 03:22:13.283 UTC [50743][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37838 2024-04-04 03:22:13.286 UTC [50743][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-04-04 03:22:13.293 UTC [50745][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37848 2024-04-04 03:22:13.296 UTC [50745][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-04-04 03:22:13.302 UTC [50747][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37864 2024-04-04 03:22:13.305 UTC [50747][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:13.305 UTC [50747][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.306 UTC [50747][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-04 03:22:13.307 UTC [50747][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37864 2024-04-04 03:22:13.314 UTC [50749][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37870 2024-04-04 03:22:13.317 UTC [50749][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:13.317 UTC [50749][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-04-04 03:22:13.318 UTC [50749][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-04-04 03:22:13.318 UTC [50749][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37870 2024-04-04 03:22:13.341 UTC [50754][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37880 2024-04-04 03:22:13.344 UTC [50754][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-04-04 03:22:13.351 UTC [50756][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37892 2024-04-04 03:22:13.356 UTC [50756][client backend] [[unknown]][4/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.356 UTC [50756][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.360 UTC [50756][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key$$ 2024-04-04 03:22:13.361 UTC [50756][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=37892 2024-04-04 03:22:13.367 UTC [50758][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37898 2024-04-04 03:22:13.372 UTC [50758][client backend] [[unknown]][5/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.372 UTC [50758][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.373 UTC [50758][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client-der.key$$ 2024-04-04 03:22:13.373 UTC [50758][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=37898 2024-04-04 03:22:13.380 UTC [50760][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37904 2024-04-04 03:22:13.385 UTC [50760][client backend] [[unknown]][6/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.385 UTC [50760][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.386 UTC [50760][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-04-04 03:22:13.386 UTC [50760][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=37904 2024-04-04 03:22:13.392 UTC [50762][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37910 2024-04-04 03:22:13.397 UTC [50762][client backend] [[unknown]][7/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.397 UTC [50762][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.398 UTC [50762][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-04-04 03:22:13.398 UTC [50762][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=certdb host=localhost port=37910 2024-04-04 03:22:13.405 UTC [50764][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37920 2024-04-04 03:22:13.409 UTC [50764][client backend] [[unknown]][8/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.409 UTC [50764][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.410 UTC [50764][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=require sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key$$ 2024-04-04 03:22:13.410 UTC [50764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=certdb host=localhost port=37920 2024-04-04 03:22:13.419 UTC [50766][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37924 2024-04-04 03:22:13.424 UTC [50766][client backend] [[unknown]][9/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.424 UTC [50766][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.425 UTC [50766][client backend] [001_ssltests.pl][9/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=allow sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key$$ 2024-04-04 03:22:13.425 UTC [50766][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=37924 2024-04-04 03:22:13.433 UTC [50768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37932 2024-04-04 03:22:13.437 UTC [50768][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-04-04 03:22:13.445 UTC [50770][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37942 2024-04-04 03:22:13.446 UTC [50770][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-04 03:22:13.453 UTC [50772][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37958 2024-04-04 03:22:13.458 UTC [50772][client backend] [[unknown]][11/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8) 2024-04-04 03:22:13.458 UTC [50772][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.463 UTC [50772][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client-dn.key$$ 2024-04-04 03:22:13.463 UTC [50772][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn host=localhost port=37958 2024-04-04 03:22:13.470 UTC [50774][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37972 2024-04-04 03:22:13.475 UTC [50774][client backend] [[unknown]][12/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9) 2024-04-04 03:22:13.475 UTC [50774][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.479 UTC [50774][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn_re user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client-dn.key$$ 2024-04-04 03:22:13.480 UTC [50774][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=37972 2024-04-04 03:22:13.488 UTC [50776][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37980 2024-04-04 03:22:13.493 UTC [50776][client backend] [[unknown]][13/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10) 2024-04-04 03:22:13.493 UTC [50776][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.497 UTC [50776][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_cn user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client-dn.key$$ 2024-04-04 03:22:13.498 UTC [50776][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=37980 2024-04-04 03:22:13.514 UTC [50779][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37990 2024-04-04 03:22:13.519 UTC [50779][client backend] [[unknown]][14/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.519 UTC [50779][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.520 UTC [50779][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-04 03:22:13.522 UTC [50779][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=37990 2024-04-04 03:22:13.530 UTC [50781][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38000 2024-04-04 03:22:13.531 UTC [50781][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-04 03:22:13.537 UTC [50783][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38008 2024-04-04 03:22:13.542 UTC [50783][client backend] [[unknown]][15/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.542 UTC [50783][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-04 03:22:13.542 UTC [50783][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-04-04 03:22:13.542 UTC [50783][client backend] [[unknown]][15/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb all 127.0.0.1/32 cert" 2024-04-04 03:22:13.549 UTC [50785][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38022 2024-04-04 03:22:13.553 UTC [50785][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-04 03:22:13.553 UTC [50785][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-04 03:22:13.560 UTC [50787][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38034 2024-04-04 03:22:13.565 UTC [50787][client backend] [[unknown]][16/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4) 2024-04-04 03:22:13.565 UTC [50787][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.570 UTC [50787][client backend] [001_ssltests.pl][16/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key$$ 2024-04-04 03:22:13.570 UTC [50787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=38034 2024-04-04 03:22:13.578 UTC [50789][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38044 2024-04-04 03:22:13.583 UTC [50789][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-04 03:22:13.583 UTC [50789][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-04-04 03:22:13.583 UTC [50789][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-04-04 03:22:13.583 UTC [50789][client backend] [[unknown]][17/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb anotheruser 127.0.0.1/32 trust clientcert=verify-full" 2024-04-04 03:22:13.590 UTC [50791][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38056 2024-04-04 03:22:13.595 UTC [50791][client backend] [[unknown]][18/1:0] LOG: connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6) 2024-04-04 03:22:13.595 UTC [50791][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.596 UTC [50791][client backend] [001_ssltests.pl][18/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=yetanotheruser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key$$ 2024-04-04 03:22:13.596 UTC [50791][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=38056 2024-04-04 03:22:13.600 UTC [50733][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:13.600 UTC [50733][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:13.601 UTC [50733][postmaster] LOG: background worker "logical replication launcher" (PID 50739) exited with exit code 1 2024-04-04 03:22:13.601 UTC [50734][checkpointer] LOG: shutting down 2024-04-04 03:22:13.601 UTC [50734][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:13.601 UTC [50734][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D3B8, redo lsn=0/414D3B8 2024-04-04 03:22:13.604 UTC [50733][postmaster] LOG: database system is shut down 2024-04-04 03:22:13.718 UTC [50793][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:13.718 UTC [50793][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:13.718 UTC [50793][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:13.720 UTC [50796][startup] LOG: database system was shut down at 2024-04-04 03:22:13 UTC 2024-04-04 03:22:13.723 UTC [50793][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:13.808 UTC [50801][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38064 2024-04-04 03:22:13.813 UTC [50801][client backend] [[unknown]][0/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-04 03:22:13.813 UTC [50801][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:13.818 UTC [50801][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=certdb sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_dzlG/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-04-04 03:22:13.818 UTC [50801][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=38064 2024-04-04 03:22:13.826 UTC [50803][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38072 2024-04-04 03:22:13.831 UTC [50803][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-04 03:22:13.831 UTC [50803][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-04 03:22:13.838 UTC [50805][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38084 2024-04-04 03:22:13.842 UTC [50805][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-04 03:22:13.842 UTC [50805][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-04 03:22:13.845 UTC [50793][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:13.845 UTC [50793][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:13.846 UTC [50793][postmaster] LOG: background worker "logical replication launcher" (PID 50799) exited with exit code 1 2024-04-04 03:22:13.846 UTC [50794][checkpointer] LOG: shutting down 2024-04-04 03:22:13.846 UTC [50794][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:13.847 UTC [50794][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D430, redo lsn=0/414D430 2024-04-04 03:22:13.849 UTC [50793][postmaster] LOG: database system is shut down 2024-04-04 03:22:13.962 UTC [50807][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:13.962 UTC [50807][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:13.962 UTC [50807][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:13.964 UTC [50810][startup] LOG: database system was shut down at 2024-04-04 03:22:13 UTC 2024-04-04 03:22:13.967 UTC [50807][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:14.053 UTC [50815][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38098 2024-04-04 03:22:14.057 UTC [50815][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-04 03:22:14.057 UTC [50815][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-04-04 03:22:14.060 UTC [50807][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:14.060 UTC [50807][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:14.062 UTC [50807][postmaster] LOG: background worker "logical replication launcher" (PID 50813) exited with exit code 1 2024-04-04 03:22:14.062 UTC [50808][checkpointer] LOG: shutting down 2024-04-04 03:22:14.062 UTC [50808][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:14.062 UTC [50808][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D4A8, redo lsn=0/414D4A8 2024-04-04 03:22:14.064 UTC [50807][postmaster] LOG: database system is shut down 2024-04-04 03:22:14.178 UTC [50817][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:14.178 UTC [50817][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:14.178 UTC [50817][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:14.180 UTC [50820][startup] LOG: database system was shut down at 2024-04-04 03:22:14 UTC 2024-04-04 03:22:14.183 UTC [50817][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:14.269 UTC [50825][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38104 2024-04-04 03:22:14.273 UTC [50825][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-04 03:22:14.273 UTC [50825][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-04 03:22:14.281 UTC [50827][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38118 2024-04-04 03:22:14.285 UTC [50827][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-04 03:22:14.285 UTC [50827][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-04 03:22:14.288 UTC [50817][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:14.288 UTC [50817][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:14.289 UTC [50817][postmaster] LOG: background worker "logical replication launcher" (PID 50823) exited with exit code 1 2024-04-04 03:22:14.289 UTC [50818][checkpointer] LOG: shutting down 2024-04-04 03:22:14.289 UTC [50818][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:14.290 UTC [50818][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D520, redo lsn=0/414D520 2024-04-04 03:22:14.292 UTC [50817][postmaster] LOG: database system is shut down 2024-04-04 03:22:14.406 UTC [50829][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:14.406 UTC [50829][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:14.406 UTC [50829][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:14.408 UTC [50832][startup] LOG: database system was shut down at 2024-04-04 03:22:14 UTC 2024-04-04 03:22:14.410 UTC [50829][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:14.496 UTC [50837][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38128 2024-04-04 03:22:14.498 UTC [50837][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:14.498 UTC [50837][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:14.507 UTC [50839][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38136 2024-04-04 03:22:14.510 UTC [50839][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:14.510 UTC [50839][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:14.515 UTC [50839][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:14.515 UTC [50839][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38136 2024-04-04 03:22:14.519 UTC [50829][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:14.519 UTC [50829][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:14.520 UTC [50829][postmaster] LOG: background worker "logical replication launcher" (PID 50835) exited with exit code 1 2024-04-04 03:22:14.520 UTC [50830][checkpointer] LOG: shutting down 2024-04-04 03:22:14.520 UTC [50830][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:14.521 UTC [50830][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D598, redo lsn=0/414D598 2024-04-04 03:22:14.524 UTC [50829][postmaster] LOG: database system is shut down 2024-04-04 03:22:14.637 UTC [50841][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:14.637 UTC [50841][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:14.637 UTC [50841][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:14.639 UTC [50844][startup] LOG: database system was shut down at 2024-04-04 03:22:14 UTC 2024-04-04 03:22:14.642 UTC [50841][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:14.727 UTC [50849][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38150 2024-04-04 03:22:14.729 UTC [50849][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:14.729 UTC [50849][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:14.737 UTC [50851][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38154 2024-04-04 03:22:14.740 UTC [50851][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:14.740 UTC [50851][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:14.745 UTC [50851][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:14.745 UTC [50851][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=38154 2024-04-04 03:22:14.749 UTC [50841][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:14.749 UTC [50841][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:14.750 UTC [50841][postmaster] LOG: background worker "logical replication launcher" (PID 50847) exited with exit code 1 2024-04-04 03:22:14.750 UTC [50842][checkpointer] LOG: shutting down 2024-04-04 03:22:14.750 UTC [50842][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:14.750 UTC [50842][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D610, redo lsn=0/414D610 2024-04-04 03:22:14.753 UTC [50841][postmaster] LOG: database system is shut down 2024-04-04 03:22:14.867 UTC [50853][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:14.867 UTC [50853][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:14.867 UTC [50853][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:14.869 UTC [50856][startup] LOG: database system was shut down at 2024-04-04 03:22:14 UTC 2024-04-04 03:22:14.872 UTC [50853][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:14.957 UTC [50861][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38170 2024-04-04 03:22:14.958 UTC [50861][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:14.958 UTC [50861][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:14.967 UTC [50863][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38176 2024-04-04 03:22:14.971 UTC [50863][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:14.971 UTC [50863][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:14.976 UTC [50863][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:14.976 UTC [50863][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38176 2024-04-04 03:22:14.980 UTC [50853][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:14.980 UTC [50853][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:14.982 UTC [50853][postmaster] LOG: background worker "logical replication launcher" (PID 50859) exited with exit code 1 2024-04-04 03:22:14.982 UTC [50854][checkpointer] LOG: shutting down 2024-04-04 03:22:14.982 UTC [50854][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:14.982 UTC [50854][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D688, redo lsn=0/414D688 2024-04-04 03:22:14.985 UTC [50853][postmaster] LOG: database system is shut down 2024-04-04 03:22:15.097 UTC [50865][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:15.097 UTC [50865][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:15.098 UTC [50865][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:15.100 UTC [50868][startup] LOG: database system was shut down at 2024-04-04 03:22:14 UTC 2024-04-04 03:22:15.102 UTC [50865][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:15.189 UTC [50873][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38188 2024-04-04 03:22:15.190 UTC [50873][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:15.190 UTC [50873][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:15.199 UTC [50875][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38190 2024-04-04 03:22:15.202 UTC [50875][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:15.203 UTC [50875][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:15.207 UTC [50875][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:15.208 UTC [50875][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38190 2024-04-04 03:22:15.212 UTC [50865][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:15.212 UTC [50865][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:15.213 UTC [50865][postmaster] LOG: background worker "logical replication launcher" (PID 50871) exited with exit code 1 2024-04-04 03:22:15.213 UTC [50866][checkpointer] LOG: shutting down 2024-04-04 03:22:15.213 UTC [50866][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:15.214 UTC [50866][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D700, redo lsn=0/414D700 2024-04-04 03:22:15.216 UTC [50865][postmaster] LOG: database system is shut down 2024-04-04 03:22:15.329 UTC [50877][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:15.329 UTC [50877][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:15.330 UTC [50877][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:15.332 UTC [50880][startup] LOG: database system was shut down at 2024-04-04 03:22:15 UTC 2024-04-04 03:22:15.334 UTC [50877][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:15.420 UTC [50885][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38198 2024-04-04 03:22:15.421 UTC [50885][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:15.421 UTC [50885][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:15.430 UTC [50887][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38206 2024-04-04 03:22:15.434 UTC [50887][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:15.434 UTC [50887][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:15.439 UTC [50887][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:15.439 UTC [50887][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=38206 2024-04-04 03:22:15.443 UTC [50877][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:15.443 UTC [50877][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:15.445 UTC [50877][postmaster] LOG: background worker "logical replication launcher" (PID 50883) exited with exit code 1 2024-04-04 03:22:15.445 UTC [50878][checkpointer] LOG: shutting down 2024-04-04 03:22:15.445 UTC [50878][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:15.445 UTC [50878][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D778, redo lsn=0/414D778 2024-04-04 03:22:15.448 UTC [50877][postmaster] LOG: database system is shut down 2024-04-04 03:22:15.561 UTC [50889][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:15.561 UTC [50889][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:15.561 UTC [50889][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:15.563 UTC [50892][startup] LOG: database system was shut down at 2024-04-04 03:22:15 UTC 2024-04-04 03:22:15.565 UTC [50889][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:15.651 UTC [50897][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38216 2024-04-04 03:22:15.653 UTC [50897][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:15.653 UTC [50897][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:15.661 UTC [50899][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38230 2024-04-04 03:22:15.664 UTC [50899][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:15.664 UTC [50899][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:15.669 UTC [50899][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:15.669 UTC [50899][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=38230 2024-04-04 03:22:15.673 UTC [50889][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:15.673 UTC [50889][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:15.674 UTC [50889][postmaster] LOG: background worker "logical replication launcher" (PID 50895) exited with exit code 1 2024-04-04 03:22:15.674 UTC [50890][checkpointer] LOG: shutting down 2024-04-04 03:22:15.674 UTC [50890][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:15.674 UTC [50890][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D7F0, redo lsn=0/414D7F0 2024-04-04 03:22:15.677 UTC [50889][postmaster] LOG: database system is shut down 2024-04-04 03:22:15.790 UTC [50901][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:15.791 UTC [50901][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:15.791 UTC [50901][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:15.793 UTC [50904][startup] LOG: database system was shut down at 2024-04-04 03:22:15 UTC 2024-04-04 03:22:15.795 UTC [50901][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:15.881 UTC [50909][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38232 2024-04-04 03:22:15.882 UTC [50909][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:15.882 UTC [50909][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:15.891 UTC [50911][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38248 2024-04-04 03:22:15.894 UTC [50911][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:15.894 UTC [50911][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:15.899 UTC [50911][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:15.899 UTC [50911][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38248 2024-04-04 03:22:15.902 UTC [50901][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:15.902 UTC [50901][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:15.903 UTC [50901][postmaster] LOG: background worker "logical replication launcher" (PID 50907) exited with exit code 1 2024-04-04 03:22:15.904 UTC [50902][checkpointer] LOG: shutting down 2024-04-04 03:22:15.904 UTC [50902][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:15.904 UTC [50902][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D868, redo lsn=0/414D868 2024-04-04 03:22:15.906 UTC [50901][postmaster] LOG: database system is shut down 2024-04-04 03:22:16.021 UTC [50913][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:16.021 UTC [50913][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:16.021 UTC [50913][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:16.023 UTC [50916][startup] LOG: database system was shut down at 2024-04-04 03:22:15 UTC 2024-04-04 03:22:16.026 UTC [50913][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:16.111 UTC [50921][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38260 2024-04-04 03:22:16.112 UTC [50921][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:16.112 UTC [50921][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:16.120 UTC [50923][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38266 2024-04-04 03:22:16.124 UTC [50923][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:16.124 UTC [50923][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:16.129 UTC [50923][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:16.129 UTC [50923][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38266 2024-04-04 03:22:16.133 UTC [50913][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:16.133 UTC [50913][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:16.134 UTC [50913][postmaster] LOG: background worker "logical replication launcher" (PID 50919) exited with exit code 1 2024-04-04 03:22:16.134 UTC [50914][checkpointer] LOG: shutting down 2024-04-04 03:22:16.134 UTC [50914][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:16.134 UTC [50914][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D8E0, redo lsn=0/414D8E0 2024-04-04 03:22:16.137 UTC [50913][postmaster] LOG: database system is shut down 2024-04-04 03:22:16.251 UTC [50925][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-04 03:22:16.251 UTC [50925][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61188 2024-04-04 03:22:16.251 UTC [50925][postmaster] LOG: listening on Unix socket "/tmp/P7LXNx5me4/.s.PGSQL.61188" 2024-04-04 03:22:16.254 UTC [50928][startup] LOG: database system was shut down at 2024-04-04 03:22:16 UTC 2024-04-04 03:22:16.256 UTC [50925][postmaster] LOG: database system is ready to accept connections 2024-04-04 03:22:16.344 UTC [50933][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38270 2024-04-04 03:22:16.345 UTC [50933][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-04 03:22:16.345 UTC [50933][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-04 03:22:16.354 UTC [50935][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38274 2024-04-04 03:22:16.357 UTC [50935][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-04 03:22:16.357 UTC [50935][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-04 03:22:16.362 UTC [50935][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-04 03:22:16.363 UTC [50935][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38274 2024-04-04 03:22:16.366 UTC [50925][postmaster] LOG: received fast shutdown request 2024-04-04 03:22:16.366 UTC [50925][postmaster] LOG: aborting any active transactions 2024-04-04 03:22:16.368 UTC [50925][postmaster] LOG: background worker "logical replication launcher" (PID 50931) exited with exit code 1 2024-04-04 03:22:16.368 UTC [50926][checkpointer] LOG: shutting down 2024-04-04 03:22:16.368 UTC [50926][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-04 03:22:16.368 UTC [50926][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D958, redo lsn=0/414D958 2024-04-04 03:22:16.371 UTC [50925][postmaster] LOG: database system is shut down 2024-04-04 03:22:16.479 UTC [50937][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-04-04 03:22:16.479 UTC [50937][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [03:22:16.567](0.204s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"