[05:11:48.511](0.047s) # setting up data directory # Checking port 52760 # Found port 52760 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=52760 host=/tmp/wLGCmxk5R7 Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [05:11:48.522](0.012s) # 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 50531 [05:11:48.793](0.271s) 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 50898 [05:11:49.884](1.091s) # 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-06 05:11:48.694 UTC [50531][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:48.695 UTC [50531][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:48.699 UTC [50545][startup] LOG: database system was shut down at 2024-04-06 05:09:21 UTC 2024-04-06 05:11:48.701 UTC [50531][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:48.785 UTC [50600][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.787 UTC [50600][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-06 05:11:48.787 UTC [50600][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.792 UTC [50600][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-06 05:11:48.794 UTC [50600][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.815 UTC [50611][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.816 UTC [50611][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-06 05:11:48.816 UTC [50611][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.816 UTC [50611][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-06 05:11:48.817 UTC [50611][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.845 UTC [50630][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.845 UTC [50630][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-06 05:11:48.845 UTC [50630][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.846 UTC [50630][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-06 05:11:48.847 UTC [50630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.891 UTC [50649][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.892 UTC [50649][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-06 05:11:48.892 UTC [50649][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.892 UTC [50649][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-06 05:11:48.893 UTC [50649][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.936 UTC [50673][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.937 UTC [50673][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-06 05:11:48.937 UTC [50673][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.937 UTC [50673][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-06 05:11:48.938 UTC [50673][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.995 UTC [50698][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.995 UTC [50698][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-06 05:11:48.995 UTC [50698][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.996 UTC [50698][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-06 05:11:49.029 UTC [50698][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.130 UTC [50743][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.130 UTC [50743][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-06 05:11:49.130 UTC [50743][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.135 UTC [50743][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-06 05:11:49.178 UTC [50743][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.267 UTC [50785][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.267 UTC [50785][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-06 05:11:49.267 UTC [50785][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.268 UTC [50785][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-06 05:11:49.297 UTC [50785][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.389 UTC [50806][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.390 UTC [50806][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-06 05:11:49.390 UTC [50806][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.391 UTC [50806][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-06 05:11:49.429 UTC [50806][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.513 UTC [50826][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.514 UTC [50826][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-06 05:11:49.514 UTC [50826][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.514 UTC [50826][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-06 05:11:49.541 UTC [50826][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.556 UTC [50843][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.557 UTC [50843][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-06 05:11:49.557 UTC [50843][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.558 UTC [50843][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-06 05:11:49.594 UTC [50843][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.681 UTC [50531][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:49.681 UTC [50531][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:49.683 UTC [50531][postmaster] LOG: background worker "logical replication launcher" (PID 50552) exited with exit code 1 2024-04-06 05:11:49.683 UTC [50543][checkpointer] LOG: shutting down 2024-04-06 05:11:49.683 UTC [50543][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:49.719 UTC [50543][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.036 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-06 05:11:49.728 UTC [50531][postmaster] LOG: database system is shut down 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:49.817 UTC [50907][startup] LOG: database system was shut down at 2024-04-06 05:11:49 UTC 2024-04-06 05:11:49.820 UTC [50898][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:49.888 UTC [50898][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:49.888 UTC [50898][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:49.889 UTC [50898][postmaster] LOG: background worker "logical replication launcher" (PID 50916) exited with exit code 1 2024-04-06 05:11:49.890 UTC [50905][checkpointer] LOG: shutting down 2024-04-06 05:11:49.890 UTC [50905][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:49.891 UTC [50905][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-06 05:11:49.893 UTC [50898][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.002 UTC [50940][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-06 05:11:50.002 UTC [50940][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:11:50.089](0.205s) 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 50962 [05:11:50.192](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-06 05:11:48.694 UTC [50531][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:48.695 UTC [50531][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:48.699 UTC [50545][startup] LOG: database system was shut down at 2024-04-06 05:09:21 UTC 2024-04-06 05:11:48.701 UTC [50531][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:48.785 UTC [50600][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.787 UTC [50600][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-06 05:11:48.787 UTC [50600][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.792 UTC [50600][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-06 05:11:48.794 UTC [50600][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.815 UTC [50611][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.816 UTC [50611][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-06 05:11:48.816 UTC [50611][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.816 UTC [50611][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-06 05:11:48.817 UTC [50611][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.845 UTC [50630][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.845 UTC [50630][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-06 05:11:48.845 UTC [50630][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.846 UTC [50630][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-06 05:11:48.847 UTC [50630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.891 UTC [50649][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.892 UTC [50649][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-06 05:11:48.892 UTC [50649][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.892 UTC [50649][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-06 05:11:48.893 UTC [50649][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.936 UTC [50673][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.937 UTC [50673][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-06 05:11:48.937 UTC [50673][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.937 UTC [50673][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-06 05:11:48.938 UTC [50673][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.995 UTC [50698][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.995 UTC [50698][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-06 05:11:48.995 UTC [50698][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.996 UTC [50698][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-06 05:11:49.029 UTC [50698][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.130 UTC [50743][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.130 UTC [50743][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-06 05:11:49.130 UTC [50743][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.135 UTC [50743][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-06 05:11:49.178 UTC [50743][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.267 UTC [50785][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.267 UTC [50785][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-06 05:11:49.267 UTC [50785][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.268 UTC [50785][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-06 05:11:49.297 UTC [50785][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.389 UTC [50806][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.390 UTC [50806][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-06 05:11:49.390 UTC [50806][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.391 UTC [50806][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-06 05:11:49.429 UTC [50806][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.513 UTC [50826][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.514 UTC [50826][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-06 05:11:49.514 UTC [50826][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.514 UTC [50826][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-06 05:11:49.541 UTC [50826][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.556 UTC [50843][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.557 UTC [50843][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-06 05:11:49.557 UTC [50843][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.558 UTC [50843][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-06 05:11:49.594 UTC [50843][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.681 UTC [50531][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:49.681 UTC [50531][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:49.683 UTC [50531][postmaster] LOG: background worker "logical replication launcher" (PID 50552) exited with exit code 1 2024-04-06 05:11:49.683 UTC [50543][checkpointer] LOG: shutting down 2024-04-06 05:11:49.683 UTC [50543][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:49.719 UTC [50543][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.036 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-06 05:11:49.728 UTC [50531][postmaster] LOG: database system is shut down 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:49.817 UTC [50907][startup] LOG: database system was shut down at 2024-04-06 05:11:49 UTC 2024-04-06 05:11:49.820 UTC [50898][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:49.888 UTC [50898][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:49.888 UTC [50898][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:49.889 UTC [50898][postmaster] LOG: background worker "logical replication launcher" (PID 50916) exited with exit code 1 2024-04-06 05:11:49.890 UTC [50905][checkpointer] LOG: shutting down 2024-04-06 05:11:49.890 UTC [50905][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:49.891 UTC [50905][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-06 05:11:49.893 UTC [50898][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.002 UTC [50940][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-06 05:11:50.002 UTC [50940][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.112 UTC [50962][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:50.112 UTC [50962][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:50.112 UTC [50962][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:50.117 UTC [50968][startup] LOG: database system was shut down at 2024-04-06 05:11:49 UTC 2024-04-06 05:11:50.120 UTC [50962][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:50.195 UTC [50962][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:50.195 UTC [50962][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:50.197 UTC [50962][postmaster] LOG: background worker "logical replication launcher" (PID 50973) exited with exit code 1 2024-04-06 05:11:50.197 UTC [50966][checkpointer] LOG: shutting down 2024-04-06 05:11:50.197 UTC [50966][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:50.198 UTC [50966][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-06 05:11:50.200 UTC [50962][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.309 UTC [51004][postmaster] FATAL: could not set SSL protocol version range 2024-04-06 05:11:50.309 UTC [51004][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-06 05:11:50.309 UTC [51004][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:11:50.396](0.204s) 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 51037 [05:11:50.500](0.104s) ok 5 - restart succeeds with correct SSL protocol bounds [05:11:50.500](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 51083 [05:11:50.713](0.213s) ok 6 - server doesn't accept non-SSL connections [05:11:50.713](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [05:11:50.731](0.017s) ok 8 - connect without server root cert sslmode=require [05:11:50.731](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [05:11:50.743](0.012s) ok 10 - connect without server root cert sslmode=verify-ca [05:11:50.744](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [05:11:50.756](0.013s) ok 12 - connect without server root cert sslmode=verify-full [05:11:50.757](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [05:11:50.772](0.015s) ok 14 - connect with wrong server root cert sslmode=require [05:11:50.772](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [05:11:50.786](0.013s) ok 16 - connect with wrong server root cert sslmode=verify-ca [05:11:50.786](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [05:11:50.799](0.013s) ok 18 - connect with wrong server root cert sslmode=verify-full [05:11:50.799](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [05:11:50.812](0.013s) ok 20 - connect with server CA cert, without root CA [05:11:50.812](0.000s) ok 21 - connect with server CA cert, without root CA: matches [05:11:50.827](0.015s) ok 22 - connect with correct server CA cert file sslmode=require [05:11:50.827](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [05:11:50.842](0.015s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [05:11:50.842](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [05:11:50.858](0.015s) ok 26 - connect with correct server CA cert file sslmode=verify-full [05:11:50.858](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [05:11:50.872](0.015s) ok 28 - cert root file that contains two certificates, order 1 [05:11:50.873](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [05:11:50.888](0.015s) ok 30 - cert root file that contains two certificates, order 2 [05:11:50.888](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [05:11:50.903](0.015s) ok 32 - connect with sslcertmode=disable [05:11:50.903](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [05:11:50.917](0.014s) ok 34 - connect with sslcertmode=allow [05:11:50.917](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [05:11:50.931](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [05:11:50.931](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [05:11:50.946](0.014s) ok 38 - sslcrl option with invalid file name [05:11:50.946](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [05:11:50.958](0.012s) ok 40 - CRL belonging to a different CA [05:11:50.958](0.000s) ok 41 - CRL belonging to a different CA: matches [05:11:50.969](0.011s) ok 42 - directory CRL belonging to a different CA [05:11:50.969](0.000s) ok 43 - directory CRL belonging to a different CA: matches [05:11:50.982](0.013s) ok 44 - CRL with a non-revoked cert [05:11:50.982](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [05:11:50.995](0.013s) ok 46 - directory CRL with a non-revoked cert [05:11:50.995](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [05:11:51.009](0.014s) ok 48 - mismatch between host name and server certificate sslmode=require [05:11:51.009](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [05:11:51.024](0.015s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [05:11:51.024](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [05:11:51.037](0.013s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [05:11:51.037](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 51185 [05:11:51.258](0.220s) ok 54 - IP address in the Common Name [05:11:51.258](0.000s) ok 55 - IP address in the Common Name: no stderr [05:11:51.269](0.011s) ok 56 - mismatch between host name and server certificate IP address [05:11:51.269](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 51214 [05:11:51.492](0.223s) ok 58 - IP address in a dNSName [05:11:51.493](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 51240 [05:11:51.717](0.224s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [05:11:51.717](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [05:11:51.732](0.015s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [05:11:51.732](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [05:11:51.747](0.015s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [05:11:51.748](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [05:11:51.761](0.014s) ok 66 - host name not matching with X.509 Subject Alternative Names [05:11:51.762](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [05:11:51.776](0.014s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [05:11:51.776](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 51275 [05:11:52.001](0.225s) ok 70 - host name matching with a single X.509 Subject Alternative Name [05:11:52.001](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [05:11:52.014](0.013s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [05:11:52.014](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [05:11:52.028](0.013s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [05:11:52.028](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 51311 [05:11:52.255](0.227s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [05:11:52.255](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [05:11:52.270](0.015s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [05:11:52.270](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [05:11:52.282](0.013s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [05:11:52.283](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [05:11:52.298](0.015s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [05:11:52.298](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [05:11:52.312](0.014s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [05:11:52.312](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [05:11:52.330](0.017s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [05:11:52.330](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [05:11:52.342](0.012s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [05:11:52.342](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [05:11:52.354](0.012s) ok 90 - IPv6 host with CIDR mask does not match [05:11:52.355](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 51348 [05:11:52.577](0.222s) ok 92 - certificate with both a CN and SANs 1 [05:11:52.577](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [05:11:52.591](0.014s) ok 94 - certificate with both a CN and SANs 2 [05:11:52.591](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [05:11:52.603](0.012s) ok 96 - certificate with both a CN and SANs ignores CN [05:11:52.603](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 51373 [05:11:52.828](0.226s) ok 98 - certificate with both a CN and IP SANs matches CN [05:11:52.829](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [05:11:52.843](0.014s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [05:11:52.843](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [05:11:52.858](0.015s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [05:11:52.858](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 51397 [05:11:53.082](0.223s) ok 104 - certificate with both an IP CN and IP SANs 1 [05:11:53.082](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [05:11:53.097](0.015s) ok 106 - certificate with both an IP CN and IP SANs 2 [05:11:53.097](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [05:11:53.110](0.013s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [05:11:53.110](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 51421 [05:11:53.333](0.223s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [05:11:53.334](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [05:11:53.349](0.015s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [05:11:53.349](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [05:11:53.364](0.015s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [05:11:53.364](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 51445 [05:11:53.588](0.224s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [05:11:53.588](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [05:11:53.601](0.013s) ok 118 - server certificate without CN or SANs sslmode=verify-full [05:11:53.601](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 51457 [05:11:53.819](0.218s) ok 120 - sslrootcert=system does not connect with private CA [05:11:53.820](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [05:11:53.830](0.010s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [05:11:53.830](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [05:11:53.849](0.019s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [05:11:53.849](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [05:11:53.860](0.011s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [05:11:53.861](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 51472 [05:11:54.083](0.222s) ok 128 - connects without client-side CRL [05:11:54.083](0.000s) ok 129 - connects without client-side CRL: no stderr [05:11:54.095](0.012s) ok 130 - does not connect with client-side CRL file [05:11:54.096](0.000s) ok 131 - does not connect with client-side CRL file: matches [05:11:54.107](0.011s) ok 132 - does not connect with client-side CRL directory [05:11:54.107](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() [05:11:54.121](0.014s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [05:11:54.121](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [05:11:54.121](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [05:11:54.135](0.013s) ok 137 - connection success with correct range of TLS protocol versions [05:11:54.135](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [05:11:54.142](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [05:11:54.142](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [05:11:54.149](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [05:11:54.149](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [05:11:54.155](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [05:11:54.155](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [05:11:54.155](0.000s) # running server tests [05:11:54.167](0.012s) ok 145 - certificate authorization fails without client cert [05:11:54.167](0.000s) ok 146 - certificate authorization fails without client cert: matches [05:11:54.186](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [05:11:54.186](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [05:11:54.202](0.016s) ok 149 - certificate authorization succeeds with correct client cert in DER format [05:11:54.202](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [05:11:54.218](0.016s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [05:11:54.218](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [05:11:54.233](0.015s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [05:11:54.233](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [05:11:54.249](0.016s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [05:11:54.249](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [05:11:54.266](0.017s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [05:11:54.267](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [05:11:54.280](0.013s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [05:11:54.280](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [05:11:54.290](0.011s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [05:11:54.291](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [05:11:54.310](0.019s) ok 163 - certificate authorization succeeds with DN mapping [05:11:54.310](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [05:11:54.310](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [05:11:54.330](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [05:11:54.330](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [05:11:54.350](0.020s) ok 168 - certificate authorization succeeds with CN mapping [05:11:54.350](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [05:11:54.350](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [05:11:54.350](0.000s) not ok 171 # TODO & SKIP Need Pty support [05:11:54.351](0.000s) not ok 172 # TODO & SKIP Need Pty support [05:11:54.351](0.000s) not ok 173 # TODO & SKIP Need Pty support [05:11:54.351](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_Zhl3/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:11:54.379](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [05:11:54.379](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [05:11:54.379](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [05:11:54.388](0.009s) ok 178 - certificate authorization fails because of file permissions [05:11:54.389](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [05:11:54.402](0.013s) ok 180 - certificate authorization fails with client cert belonging to another user [05:11:54.402](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [05:11:54.414](0.012s) ok 182 - certificate authorization fails with revoked client cert [05:11:54.414](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [05:11:54.414](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [05:11:54.432](0.018s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [05:11:54.432](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [05:11:54.432](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [05:11:54.446](0.013s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [05:11:54.446](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [05:11:54.446](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [05:11:54.461](0.014s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [05:11:54.461](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [05:11:54.461](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 51532 [05:11:54.685](0.224s) ok 194 - intermediate client certificate is provided by client [05:11:54.685](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [05:11:54.698](0.013s) ok 196 - intermediate client certificate is missing [05:11:54.698](0.000s) ok 197 - intermediate client certificate is missing: matches [05:11:54.710](0.013s) ok 198 - logged client certificate Subjects are truncated if they're too long [05:11:54.711](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 51546 [05:11:54.928](0.217s) ok 200 - intermediate client certificate is untrusted [05:11:54.928](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 51556 [05:11:55.146](0.218s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [05:11:55.146](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [05:11:55.160](0.014s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [05:11:55.161](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 51568 [05:11:55.377](0.216s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [05:11:55.377](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. [05:11:55.377](0.000s) # got: '2' # expected: '0' [05:11:55.378](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:11:55.378](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. [05:11:55.378](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 52760 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: '' [05:11:55.396](0.018s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [05:11:55.396](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 51580 [05:11:55.612](0.216s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [05:11:55.631](0.019s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [05:11:55.631](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 51592 [05:11:55.848](0.217s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [05:11:55.868](0.020s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [05:11:55.868](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 51604 [05:11:56.084](0.217s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [05:11:56.102](0.018s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [05:11:56.102](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 51616 [05:11:56.318](0.216s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [05:11:56.318](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. [05:11:56.318](0.000s) # got: '2' # expected: '0' [05:11:56.318](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:11:56.318](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. [05:11:56.318](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 52760 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: '' [05:11:56.336](0.018s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [05:11:56.336](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 51628 [05:11:56.552](0.216s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [05:11:56.570](0.018s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [05:11:56.571](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 51640 [05:11:56.786](0.216s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [05:11:56.803](0.017s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [05:11:56.803](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 51652 [05:11:57.018](0.214s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [05:11:57.035](0.017s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [05:11:57.035](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 51664 [05:11:57.250](0.215s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [05:11:57.268](0.018s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [05:11:57.268](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-06 05:11:48.694 UTC [50531][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:48.695 UTC [50531][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:48.699 UTC [50545][startup] LOG: database system was shut down at 2024-04-06 05:09:21 UTC 2024-04-06 05:11:48.701 UTC [50531][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:48.785 UTC [50600][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.787 UTC [50600][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-06 05:11:48.787 UTC [50600][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.792 UTC [50600][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-06 05:11:48.794 UTC [50600][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.815 UTC [50611][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.816 UTC [50611][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-06 05:11:48.816 UTC [50611][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.816 UTC [50611][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-06 05:11:48.817 UTC [50611][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.845 UTC [50630][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.845 UTC [50630][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-06 05:11:48.845 UTC [50630][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.846 UTC [50630][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-06 05:11:48.847 UTC [50630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.891 UTC [50649][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.892 UTC [50649][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-06 05:11:48.892 UTC [50649][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.892 UTC [50649][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-06 05:11:48.893 UTC [50649][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.936 UTC [50673][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.937 UTC [50673][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-06 05:11:48.937 UTC [50673][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.937 UTC [50673][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-06 05:11:48.938 UTC [50673][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-04-06 05:11:48.995 UTC [50698][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:48.995 UTC [50698][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-06 05:11:48.995 UTC [50698][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:48.996 UTC [50698][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-06 05:11:49.029 UTC [50698][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.130 UTC [50743][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.130 UTC [50743][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-06 05:11:49.130 UTC [50743][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.135 UTC [50743][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-06 05:11:49.178 UTC [50743][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.267 UTC [50785][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.267 UTC [50785][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-06 05:11:49.267 UTC [50785][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.268 UTC [50785][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-06 05:11:49.297 UTC [50785][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.389 UTC [50806][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.390 UTC [50806][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-06 05:11:49.390 UTC [50806][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.391 UTC [50806][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-06 05:11:49.429 UTC [50806][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.513 UTC [50826][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.514 UTC [50826][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-06 05:11:49.514 UTC [50826][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.514 UTC [50826][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-06 05:11:49.541 UTC [50826][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.556 UTC [50843][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-06 05:11:49.557 UTC [50843][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-06 05:11:49.557 UTC [50843][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-06 05:11:49.558 UTC [50843][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-06 05:11:49.594 UTC [50843][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local] 2024-04-06 05:11:49.681 UTC [50531][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:49.681 UTC [50531][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:49.683 UTC [50531][postmaster] LOG: background worker "logical replication launcher" (PID 50552) exited with exit code 1 2024-04-06 05:11:49.683 UTC [50543][checkpointer] LOG: shutting down 2024-04-06 05:11:49.683 UTC [50543][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:49.719 UTC [50543][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.036 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-06 05:11:49.728 UTC [50531][postmaster] LOG: database system is shut down 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:49.801 UTC [50898][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:49.817 UTC [50907][startup] LOG: database system was shut down at 2024-04-06 05:11:49 UTC 2024-04-06 05:11:49.820 UTC [50898][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:49.888 UTC [50898][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:49.888 UTC [50898][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:49.889 UTC [50898][postmaster] LOG: background worker "logical replication launcher" (PID 50916) exited with exit code 1 2024-04-06 05:11:49.890 UTC [50905][checkpointer] LOG: shutting down 2024-04-06 05:11:49.890 UTC [50905][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:49.891 UTC [50905][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-06 05:11:49.893 UTC [50898][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.002 UTC [50940][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-06 05:11:50.002 UTC [50940][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.112 UTC [50962][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:50.112 UTC [50962][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:50.112 UTC [50962][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:50.117 UTC [50968][startup] LOG: database system was shut down at 2024-04-06 05:11:49 UTC 2024-04-06 05:11:50.120 UTC [50962][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:50.195 UTC [50962][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:50.195 UTC [50962][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:50.197 UTC [50962][postmaster] LOG: background worker "logical replication launcher" (PID 50973) exited with exit code 1 2024-04-06 05:11:50.197 UTC [50966][checkpointer] LOG: shutting down 2024-04-06 05:11:50.197 UTC [50966][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:50.198 UTC [50966][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-06 05:11:50.200 UTC [50962][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.309 UTC [51004][postmaster] FATAL: could not set SSL protocol version range 2024-04-06 05:11:50.309 UTC [51004][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-06 05:11:50.309 UTC [51004][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.420 UTC [51037][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:50.420 UTC [51037][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:50.420 UTC [51037][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:50.422 UTC [51047][startup] LOG: database system was shut down at 2024-04-06 05:11:50 UTC 2024-04-06 05:11:50.425 UTC [51037][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:50.502 UTC [51037][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:50.502 UTC [51037][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:50.504 UTC [51037][postmaster] LOG: background worker "logical replication launcher" (PID 51051) exited with exit code 1 2024-04-06 05:11:50.504 UTC [51045][checkpointer] LOG: shutting down 2024-04-06 05:11:50.504 UTC [51045][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:50.505 UTC [51045][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-06 05:11:50.508 UTC [51037][postmaster] LOG: database system is shut down 2024-04-06 05:11:50.620 UTC [51083][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:50.620 UTC [51083][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:50.620 UTC [51083][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:50.623 UTC [51089][startup] LOG: database system was shut down at 2024-04-06 05:11:50 UTC 2024-04-06 05:11:50.627 UTC [51083][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:50.711 UTC [51107][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45480 2024-04-06 05:11:50.712 UTC [51107][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-06 05:11:50.712 UTC [51107][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-04-06 05:11:50.720 UTC [51109][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45494 2024-04-06 05:11:50.724 UTC [51109][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-06 05:11:50.724 UTC [51109][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-06 05:11:50.729 UTC [51109][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-06 05:11:50.732 UTC [51109][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=45494 2024-04-06 05:11:50.739 UTC [51114][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45506 2024-04-06 05:11:50.741 UTC [51114][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-06 05:11:50.754 UTC [51118][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45522 2024-04-06 05:11:50.758 UTC [51118][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-06 05:11:50.767 UTC [51122][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45536 2024-04-06 05:11:50.771 UTC [51122][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:50.781 UTC [51126][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45542 2024-04-06 05:11:50.785 UTC [51126][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:50.793 UTC [51130][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45552 2024-04-06 05:11:50.796 UTC [51130][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:50.807 UTC [51132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45560 2024-04-06 05:11:50.810 UTC [51132][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:50.820 UTC [51134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45576 2024-04-06 05:11:50.824 UTC [51134][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-06 05:11:50.824 UTC [51134][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-06 05:11:50.825 UTC [51134][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-06 05:11:50.826 UTC [51134][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=45576 2024-04-06 05:11:50.835 UTC [51136][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45578 2024-04-06 05:11:50.839 UTC [51136][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-06 05:11:50.839 UTC [51136][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-06 05:11:50.840 UTC [51136][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-06 05:11:50.841 UTC [51136][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45578 2024-04-06 05:11:50.850 UTC [51138][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45590 2024-04-06 05:11:50.854 UTC [51138][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-06 05:11:50.854 UTC [51138][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-06 05:11:50.855 UTC [51138][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-06 05:11:50.856 UTC [51138][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=45590 2024-04-06 05:11:50.865 UTC [51141][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45600 2024-04-06 05:11:50.869 UTC [51141][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-06 05:11:50.869 UTC [51141][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-06 05:11:50.870 UTC [51141][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-06 05:11:50.871 UTC [51141][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45600 2024-04-06 05:11:50.880 UTC [51144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45610 2024-04-06 05:11:50.885 UTC [51144][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-06 05:11:50.885 UTC [51144][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-06 05:11:50.886 UTC [51144][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-06 05:11:50.886 UTC [51144][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=45610 2024-04-06 05:11:50.896 UTC [51147][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45624 2024-04-06 05:11:50.900 UTC [51147][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-06 05:11:50.900 UTC [51147][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-06 05:11:50.901 UTC [51147][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-06 05:11:50.901 UTC [51147][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45624 2024-04-06 05:11:50.910 UTC [51149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45632 2024-04-06 05:11:50.914 UTC [51149][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-06 05:11:50.914 UTC [51149][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-06 05:11:50.915 UTC [51149][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-06 05:11:50.916 UTC [51149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45632 2024-04-06 05:11:50.925 UTC [51157][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45646 2024-04-06 05:11:50.929 UTC [51157][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-06 05:11:50.929 UTC [51157][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-06 05:11:50.930 UTC [51157][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45646 2024-04-06 05:11:50.939 UTC [51159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45662 2024-04-06 05:11:50.943 UTC [51159][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-06 05:11:50.943 UTC [51159][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-06 05:11:50.944 UTC [51159][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-06 05:11:50.944 UTC [51159][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45662 2024-04-06 05:11:50.953 UTC [51161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45676 2024-04-06 05:11:50.956 UTC [51161][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:50.965 UTC [51163][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45682 2024-04-06 05:11:50.968 UTC [51163][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:50.976 UTC [51165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45694 2024-04-06 05:11:50.980 UTC [51165][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-06 05:11:50.980 UTC [51165][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-06 05:11:50.981 UTC [51165][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-06 05:11:50.981 UTC [51165][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45694 2024-04-06 05:11:50.989 UTC [51167][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45700 2024-04-06 05:11:50.993 UTC [51167][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-06 05:11:50.993 UTC [51167][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-06 05:11:50.994 UTC [51167][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-06 05:11:50.994 UTC [51167][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45700 2024-04-06 05:11:51.002 UTC [51171][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45710 2024-04-06 05:11:51.006 UTC [51171][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-06 05:11:51.006 UTC [51171][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-06 05:11:51.007 UTC [51171][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-06 05:11:51.008 UTC [51171][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45710 2024-04-06 05:11:51.017 UTC [51175][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45724 2024-04-06 05:11:51.021 UTC [51175][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-06 05:11:51.021 UTC [51175][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-06 05:11:51.022 UTC [51175][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-06 05:11:51.022 UTC [51175][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45724 2024-04-06 05:11:51.031 UTC [51181][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45726 2024-04-06 05:11:51.040 UTC [51083][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:51.040 UTC [51083][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:51.041 UTC [51083][postmaster] LOG: background worker "logical replication launcher" (PID 51093) exited with exit code 1 2024-04-06 05:11:51.042 UTC [51087][checkpointer] LOG: shutting down 2024-04-06 05:11:51.042 UTC [51087][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:51.043 UTC [51087][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-06 05:11:51.046 UTC [51083][postmaster] LOG: database system is shut down 2024-04-06 05:11:51.157 UTC [51185][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:51.157 UTC [51185][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:51.157 UTC [51185][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:51.160 UTC [51193][startup] LOG: database system was shut down at 2024-04-06 05:11:51 UTC 2024-04-06 05:11:51.163 UTC [51185][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:51.247 UTC [51203][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45736 2024-04-06 05:11:51.251 UTC [51203][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-06 05:11:51.251 UTC [51203][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-06 05:11:51.256 UTC [51203][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-06 05:11:51.257 UTC [51203][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=45736 2024-04-06 05:11:51.265 UTC [51208][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45740 2024-04-06 05:11:51.272 UTC [51185][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:51.272 UTC [51185][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:51.273 UTC [51185][postmaster] LOG: background worker "logical replication launcher" (PID 51197) exited with exit code 1 2024-04-06 05:11:51.273 UTC [51189][checkpointer] LOG: shutting down 2024-04-06 05:11:51.273 UTC [51189][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:51.274 UTC [51189][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-06 05:11:51.277 UTC [51185][postmaster] LOG: database system is shut down 2024-04-06 05:11:51.390 UTC [51214][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:51.390 UTC [51214][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:51.390 UTC [51214][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:51.393 UTC [51218][startup] LOG: database system was shut down at 2024-04-06 05:11:51 UTC 2024-04-06 05:11:51.396 UTC [51214][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:51.481 UTC [51229][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45756 2024-04-06 05:11:51.485 UTC [51229][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-06 05:11:51.485 UTC [51229][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-06 05:11:51.490 UTC [51229][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-06 05:11:51.491 UTC [51229][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=45756 2024-04-06 05:11:51.495 UTC [51214][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:51.495 UTC [51214][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:51.496 UTC [51214][postmaster] LOG: background worker "logical replication launcher" (PID 51221) exited with exit code 1 2024-04-06 05:11:51.497 UTC [51216][checkpointer] LOG: shutting down 2024-04-06 05:11:51.497 UTC [51216][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:51.498 UTC [51216][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-06 05:11:51.501 UTC [51214][postmaster] LOG: database system is shut down 2024-04-06 05:11:51.614 UTC [51240][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:51.614 UTC [51240][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:51.614 UTC [51240][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:51.617 UTC [51243][startup] LOG: database system was shut down at 2024-04-06 05:11:51 UTC 2024-04-06 05:11:51.620 UTC [51240][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:51.705 UTC [51257][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45762 2024-04-06 05:11:51.709 UTC [51257][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-06 05:11:51.710 UTC [51257][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-06 05:11:51.714 UTC [51257][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-06 05:11:51.715 UTC [51257][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=45762 2024-04-06 05:11:51.725 UTC [51259][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45776 2024-04-06 05:11:51.729 UTC [51259][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-06 05:11:51.729 UTC [51259][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-06 05:11:51.730 UTC [51259][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-06 05:11:51.731 UTC [51259][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45776 2024-04-06 05:11:51.740 UTC [51263][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45778 2024-04-06 05:11:51.744 UTC [51263][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-06 05:11:51.744 UTC [51263][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-06 05:11:51.745 UTC [51263][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-06 05:11:51.746 UTC [51263][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45778 2024-04-06 05:11:51.756 UTC [51267][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45790 2024-04-06 05:11:51.771 UTC [51270][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45806 2024-04-06 05:11:51.779 UTC [51240][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:51.779 UTC [51240][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:51.781 UTC [51240][postmaster] LOG: background worker "logical replication launcher" (PID 51246) exited with exit code 1 2024-04-06 05:11:51.781 UTC [51241][checkpointer] LOG: shutting down 2024-04-06 05:11:51.781 UTC [51241][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:51.781 UTC [51241][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-06 05:11:51.784 UTC [51240][postmaster] LOG: database system is shut down 2024-04-06 05:11:51.897 UTC [51275][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:51.897 UTC [51275][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:51.897 UTC [51275][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:51.900 UTC [51284][startup] LOG: database system was shut down at 2024-04-06 05:11:51 UTC 2024-04-06 05:11:51.903 UTC [51275][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:51.988 UTC [51294][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45818 2024-04-06 05:11:51.992 UTC [51294][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-06 05:11:51.992 UTC [51294][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-06 05:11:51.997 UTC [51294][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-06 05:11:52.000 UTC [51294][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=45818 2024-04-06 05:11:52.009 UTC [51298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45820 2024-04-06 05:11:52.022 UTC [51300][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45836 2024-04-06 05:11:52.034 UTC [51275][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:52.034 UTC [51275][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:52.036 UTC [51275][postmaster] LOG: background worker "logical replication launcher" (PID 51287) exited with exit code 1 2024-04-06 05:11:52.037 UTC [51282][checkpointer] LOG: shutting down 2024-04-06 05:11:52.037 UTC [51282][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:52.037 UTC [51282][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-06 05:11:52.040 UTC [51275][postmaster] LOG: database system is shut down 2024-04-06 05:11:52.153 UTC [51311][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:52.153 UTC [51311][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:52.153 UTC [51311][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:52.155 UTC [51314][startup] LOG: database system was shut down at 2024-04-06 05:11:52 UTC 2024-04-06 05:11:52.158 UTC [51311][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:52.243 UTC [51322][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45844 2024-04-06 05:11:52.247 UTC [51322][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-06 05:11:52.247 UTC [51322][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-06 05:11:52.252 UTC [51322][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-06 05:11:52.253 UTC [51322][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=45844 2024-04-06 05:11:52.263 UTC [51324][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45858 2024-04-06 05:11:52.267 UTC [51324][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-06 05:11:52.267 UTC [51324][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-06 05:11:52.268 UTC [51324][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-06 05:11:52.269 UTC [51324][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45858 2024-04-06 05:11:52.277 UTC [51326][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45864 2024-04-06 05:11:52.290 UTC [51328][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45870 2024-04-06 05:11:52.295 UTC [51328][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-06 05:11:52.295 UTC [51328][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-06 05:11:52.296 UTC [51328][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-06 05:11:52.296 UTC [51328][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=45870 2024-04-06 05:11:52.306 UTC [51330][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45876 2024-04-06 05:11:52.309 UTC [51330][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-06 05:11:52.309 UTC [51330][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-06 05:11:52.310 UTC [51330][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-06 05:11:52.311 UTC [51330][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45876 2024-04-06 05:11:52.320 UTC [51332][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45880 2024-04-06 05:11:52.325 UTC [51332][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-06 05:11:52.325 UTC [51332][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-06 05:11:52.326 UTC [51332][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-06 05:11:52.327 UTC [51332][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=45880 2024-04-06 05:11:52.337 UTC [51335][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45882 2024-04-06 05:11:52.350 UTC [51343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45896 2024-04-06 05:11:52.357 UTC [51311][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:52.357 UTC [51311][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:52.358 UTC [51311][postmaster] LOG: background worker "logical replication launcher" (PID 51317) exited with exit code 1 2024-04-06 05:11:52.358 UTC [51312][checkpointer] LOG: shutting down 2024-04-06 05:11:52.358 UTC [51312][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:52.359 UTC [51312][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-06 05:11:52.362 UTC [51311][postmaster] LOG: database system is shut down 2024-04-06 05:11:52.475 UTC [51348][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:52.475 UTC [51348][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:52.475 UTC [51348][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:52.477 UTC [51351][startup] LOG: database system was shut down at 2024-04-06 05:11:52 UTC 2024-04-06 05:11:52.480 UTC [51348][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:52.566 UTC [51363][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45902 2024-04-06 05:11:52.570 UTC [51363][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-06 05:11:52.570 UTC [51363][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-06 05:11:52.575 UTC [51363][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-06 05:11:52.575 UTC [51363][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=45902 2024-04-06 05:11:52.584 UTC [51365][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45912 2024-04-06 05:11:52.588 UTC [51365][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-06 05:11:52.588 UTC [51365][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-06 05:11:52.589 UTC [51365][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-06 05:11:52.589 UTC [51365][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45912 2024-04-06 05:11:52.598 UTC [51367][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45916 2024-04-06 05:11:52.608 UTC [51348][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:52.608 UTC [51348][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:52.609 UTC [51348][postmaster] LOG: background worker "logical replication launcher" (PID 51354) exited with exit code 1 2024-04-06 05:11:52.609 UTC [51349][checkpointer] LOG: shutting down 2024-04-06 05:11:52.609 UTC [51349][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:52.610 UTC [51349][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-06 05:11:52.613 UTC [51348][postmaster] LOG: database system is shut down 2024-04-06 05:11:52.727 UTC [51373][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:52.727 UTC [51373][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:52.727 UTC [51373][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:52.729 UTC [51376][startup] LOG: database system was shut down at 2024-04-06 05:11:52 UTC 2024-04-06 05:11:52.733 UTC [51373][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:52.817 UTC [51388][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45932 2024-04-06 05:11:52.821 UTC [51388][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-06 05:11:52.821 UTC [51388][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-06 05:11:52.826 UTC [51388][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-06 05:11:52.827 UTC [51388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=45932 2024-04-06 05:11:52.837 UTC [51390][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45938 2024-04-06 05:11:52.840 UTC [51390][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-06 05:11:52.840 UTC [51390][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-06 05:11:52.841 UTC [51390][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-06 05:11:52.842 UTC [51390][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45938 2024-04-06 05:11:52.851 UTC [51392][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45954 2024-04-06 05:11:52.855 UTC [51392][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-06 05:11:52.855 UTC [51392][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-06 05:11:52.856 UTC [51392][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-06 05:11:52.856 UTC [51392][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45954 2024-04-06 05:11:52.861 UTC [51373][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:52.861 UTC [51373][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:52.863 UTC [51373][postmaster] LOG: background worker "logical replication launcher" (PID 51379) exited with exit code 1 2024-04-06 05:11:52.863 UTC [51374][checkpointer] LOG: shutting down 2024-04-06 05:11:52.863 UTC [51374][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:52.863 UTC [51374][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-06 05:11:52.866 UTC [51373][postmaster] LOG: database system is shut down 2024-04-06 05:11:52.979 UTC [51397][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:52.979 UTC [51397][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:52.979 UTC [51397][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:52.981 UTC [51400][startup] LOG: database system was shut down at 2024-04-06 05:11:52 UTC 2024-04-06 05:11:52.984 UTC [51397][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:53.070 UTC [51412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45964 2024-04-06 05:11:53.074 UTC [51412][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-06 05:11:53.074 UTC [51412][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-06 05:11:53.080 UTC [51412][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-06 05:11:53.080 UTC [51412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=45964 2024-04-06 05:11:53.090 UTC [51414][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45980 2024-04-06 05:11:53.094 UTC [51414][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-06 05:11:53.094 UTC [51414][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-06 05:11:53.095 UTC [51414][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-06 05:11:53.096 UTC [51414][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=45980 2024-04-06 05:11:53.105 UTC [51417][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45982 2024-04-06 05:11:53.113 UTC [51397][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:53.113 UTC [51397][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:53.114 UTC [51397][postmaster] LOG: background worker "logical replication launcher" (PID 51403) exited with exit code 1 2024-04-06 05:11:53.116 UTC [51398][checkpointer] LOG: shutting down 2024-04-06 05:11:53.116 UTC [51398][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:53.116 UTC [51398][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-06 05:11:53.119 UTC [51397][postmaster] LOG: database system is shut down 2024-04-06 05:11:53.231 UTC [51421][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:53.231 UTC [51421][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:53.231 UTC [51421][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:53.233 UTC [51425][startup] LOG: database system was shut down at 2024-04-06 05:11:53 UTC 2024-04-06 05:11:53.236 UTC [51421][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:53.322 UTC [51436][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45996 2024-04-06 05:11:53.326 UTC [51436][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-06 05:11:53.326 UTC [51436][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-06 05:11:53.331 UTC [51436][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-06 05:11:53.332 UTC [51436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=45996 2024-04-06 05:11:53.342 UTC [51440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=45998 2024-04-06 05:11:53.346 UTC [51440][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-06 05:11:53.346 UTC [51440][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-06 05:11:53.347 UTC [51440][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-06 05:11:53.347 UTC [51440][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=45998 2024-04-06 05:11:53.357 UTC [51443][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46004 2024-04-06 05:11:53.361 UTC [51443][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-06 05:11:53.361 UTC [51443][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-06 05:11:53.362 UTC [51443][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-06 05:11:53.363 UTC [51443][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=46004 2024-04-06 05:11:53.367 UTC [51421][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:53.367 UTC [51421][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:53.368 UTC [51421][postmaster] LOG: background worker "logical replication launcher" (PID 51428) exited with exit code 1 2024-04-06 05:11:53.368 UTC [51423][checkpointer] LOG: shutting down 2024-04-06 05:11:53.368 UTC [51423][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:53.369 UTC [51423][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-06 05:11:53.372 UTC [51421][postmaster] LOG: database system is shut down 2024-04-06 05:11:53.485 UTC [51445][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:53.485 UTC [51445][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:53.485 UTC [51445][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:53.487 UTC [51448][startup] LOG: database system was shut down at 2024-04-06 05:11:53 UTC 2024-04-06 05:11:53.490 UTC [51445][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:53.576 UTC [51453][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46008 2024-04-06 05:11:53.580 UTC [51453][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-06 05:11:53.580 UTC [51453][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-06 05:11:53.586 UTC [51453][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-06 05:11:53.586 UTC [51453][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=46008 2024-04-06 05:11:53.596 UTC [51455][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46018 2024-04-06 05:11:53.604 UTC [51445][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:53.604 UTC [51445][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:53.606 UTC [51445][postmaster] LOG: background worker "logical replication launcher" (PID 51451) exited with exit code 1 2024-04-06 05:11:53.606 UTC [51446][checkpointer] LOG: shutting down 2024-04-06 05:11:53.606 UTC [51446][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:53.607 UTC [51446][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-06 05:11:53.609 UTC [51445][postmaster] LOG: database system is shut down 2024-04-06 05:11:53.723 UTC [51457][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:53.724 UTC [51457][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:53.724 UTC [51457][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:53.726 UTC [51460][startup] LOG: database system was shut down at 2024-04-06 05:11:53 UTC 2024-04-06 05:11:53.729 UTC [51457][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:53.814 UTC [51465][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46020 2024-04-06 05:11:53.817 UTC [51465][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-06 05:11:53.838 UTC [51468][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46032 2024-04-06 05:11:53.842 UTC [51468][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-06 05:11:53.842 UTC [51468][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-06 05:11:53.847 UTC [51468][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-06 05:11:53.847 UTC [51468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46032 2024-04-06 05:11:53.856 UTC [51470][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46048 2024-04-06 05:11:53.863 UTC [51457][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:53.863 UTC [51457][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:53.865 UTC [51457][postmaster] LOG: background worker "logical replication launcher" (PID 51463) exited with exit code 1 2024-04-06 05:11:53.865 UTC [51458][checkpointer] LOG: shutting down 2024-04-06 05:11:53.865 UTC [51458][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:53.865 UTC [51458][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-06 05:11:53.868 UTC [51457][postmaster] LOG: database system is shut down 2024-04-06 05:11:53.982 UTC [51472][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:53.982 UTC [51472][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:53.982 UTC [51472][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:53.984 UTC [51475][startup] LOG: database system was shut down at 2024-04-06 05:11:53 UTC 2024-04-06 05:11:53.988 UTC [51472][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:54.072 UTC [51480][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46050 2024-04-06 05:11:54.076 UTC [51480][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-06 05:11:54.076 UTC [51480][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-06 05:11:54.081 UTC [51480][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-06 05:11:54.081 UTC [51480][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46050 2024-04-06 05:11:54.091 UTC [51482][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46056 2024-04-06 05:11:54.094 UTC [51482][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-04-06 05:11:54.103 UTC [51484][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46068 2024-04-06 05:11:54.105 UTC [51484][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-04-06 05:11:54.113 UTC [51486][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46080 2024-04-06 05:11:54.117 UTC [51486][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-06 05:11:54.117 UTC [51486][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-06 05:11:54.118 UTC [51486][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-06 05:11:54.120 UTC [51486][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=46080 2024-04-06 05:11:54.129 UTC [51488][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46088 2024-04-06 05:11:54.132 UTC [51488][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-06 05:11:54.132 UTC [51488][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-06 05:11:54.133 UTC [51488][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-06 05:11:54.134 UTC [51488][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=46088 2024-04-06 05:11:54.162 UTC [51493][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46104 2024-04-06 05:11:54.165 UTC [51493][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-04-06 05:11:54.174 UTC [51495][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46112 2024-04-06 05:11:54.179 UTC [51495][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-06 05:11:54.179 UTC [51495][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-06 05:11:54.184 UTC [51495][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_Zhl3/client.key$$ 2024-04-06 05:11:54.184 UTC [51495][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=46112 2024-04-06 05:11:54.193 UTC [51497][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46124 2024-04-06 05:11:54.198 UTC [51497][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-06 05:11:54.198 UTC [51497][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-06 05:11:54.200 UTC [51497][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_Zhl3/client-der.key$$ 2024-04-06 05:11:54.200 UTC [51497][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=46124 2024-04-06 05:11:54.209 UTC [51499][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46130 2024-04-06 05:11:54.215 UTC [51499][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-06 05:11:54.215 UTC [51499][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-06 05:11:54.216 UTC [51499][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_Zhl3/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-04-06 05:11:54.216 UTC [51499][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=46130 2024-04-06 05:11:54.225 UTC [51501][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46134 2024-04-06 05:11:54.230 UTC [51501][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-06 05:11:54.230 UTC [51501][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-06 05:11:54.231 UTC [51501][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_Zhl3/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-04-06 05:11:54.231 UTC [51501][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=46134 2024-04-06 05:11:54.240 UTC [51503][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46142 2024-04-06 05:11:54.246 UTC [51503][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-06 05:11:54.246 UTC [51503][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-06 05:11:54.247 UTC [51503][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_Zhl3/client.key$$ 2024-04-06 05:11:54.247 UTC [51503][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=46142 2024-04-06 05:11:54.257 UTC [51505][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46152 2024-04-06 05:11:54.263 UTC [51505][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-06 05:11:54.263 UTC [51505][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-06 05:11:54.264 UTC [51505][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_Zhl3/client.key$$ 2024-04-06 05:11:54.265 UTC [51505][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=46152 2024-04-06 05:11:54.274 UTC [51507][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46154 2024-04-06 05:11:54.278 UTC [51507][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-04-06 05:11:54.287 UTC [51509][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46162 2024-04-06 05:11:54.289 UTC [51509][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-06 05:11:54.298 UTC [51511][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46170 2024-04-06 05:11:54.303 UTC [51511][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-06 05:11:54.303 UTC [51511][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-06 05:11:54.308 UTC [51511][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_Zhl3/client-dn.key$$ 2024-04-06 05:11:54.308 UTC [51511][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=46170 2024-04-06 05:11:54.318 UTC [51513][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46186 2024-04-06 05:11:54.323 UTC [51513][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-06 05:11:54.323 UTC [51513][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-06 05:11:54.328 UTC [51513][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_Zhl3/client-dn.key$$ 2024-04-06 05:11:54.328 UTC [51513][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=46186 2024-04-06 05:11:54.338 UTC [51515][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46192 2024-04-06 05:11:54.343 UTC [51515][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-06 05:11:54.343 UTC [51515][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-06 05:11:54.348 UTC [51515][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_Zhl3/client-dn.key$$ 2024-04-06 05:11:54.348 UTC [51515][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=46192 2024-04-06 05:11:54.370 UTC [51518][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46198 2024-04-06 05:11:54.375 UTC [51518][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-06 05:11:54.375 UTC [51518][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-06 05:11:54.376 UTC [51518][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-06 05:11:54.378 UTC [51518][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=46198 2024-04-06 05:11:54.386 UTC [51520][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46200 2024-04-06 05:11:54.387 UTC [51520][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-06 05:11:54.395 UTC [51522][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46206 2024-04-06 05:11:54.400 UTC [51522][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-06 05:11:54.400 UTC [51522][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-06 05:11:54.400 UTC [51522][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-04-06 05:11:54.400 UTC [51522][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-06 05:11:54.408 UTC [51524][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46210 2024-04-06 05:11:54.413 UTC [51524][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-06 05:11:54.413 UTC [51524][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-06 05:11:54.420 UTC [51526][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46224 2024-04-06 05:11:54.426 UTC [51526][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-06 05:11:54.426 UTC [51526][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-06 05:11:54.430 UTC [51526][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_Zhl3/client.key$$ 2024-04-06 05:11:54.431 UTC [51526][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=46224 2024-04-06 05:11:54.439 UTC [51528][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46234 2024-04-06 05:11:54.444 UTC [51528][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-06 05:11:54.444 UTC [51528][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-04-06 05:11:54.444 UTC [51528][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-04-06 05:11:54.444 UTC [51528][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-06 05:11:54.453 UTC [51530][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46248 2024-04-06 05:11:54.458 UTC [51530][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-06 05:11:54.458 UTC [51530][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-06 05:11:54.459 UTC [51530][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_Zhl3/client.key$$ 2024-04-06 05:11:54.459 UTC [51530][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=46248 2024-04-06 05:11:54.463 UTC [51472][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:54.463 UTC [51472][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:54.465 UTC [51472][postmaster] LOG: background worker "logical replication launcher" (PID 51478) exited with exit code 1 2024-04-06 05:11:54.465 UTC [51473][checkpointer] LOG: shutting down 2024-04-06 05:11:54.465 UTC [51473][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:54.465 UTC [51473][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-06 05:11:54.468 UTC [51472][postmaster] LOG: database system is shut down 2024-04-06 05:11:54.582 UTC [51532][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:54.582 UTC [51532][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:54.582 UTC [51532][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:54.584 UTC [51535][startup] LOG: database system was shut down at 2024-04-06 05:11:54 UTC 2024-04-06 05:11:54.588 UTC [51532][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:54.672 UTC [51540][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46264 2024-04-06 05:11:54.678 UTC [51540][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-06 05:11:54.678 UTC [51540][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-06 05:11:54.683 UTC [51540][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_Zhl3/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-06 05:11:54.683 UTC [51540][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=46264 2024-04-06 05:11:54.692 UTC [51542][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46268 2024-04-06 05:11:54.696 UTC [51542][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-06 05:11:54.696 UTC [51542][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-06 05:11:54.705 UTC [51544][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46272 2024-04-06 05:11:54.709 UTC [51544][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-06 05:11:54.709 UTC [51544][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-06 05:11:54.713 UTC [51532][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:54.713 UTC [51532][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:54.715 UTC [51532][postmaster] LOG: background worker "logical replication launcher" (PID 51538) exited with exit code 1 2024-04-06 05:11:54.715 UTC [51533][checkpointer] LOG: shutting down 2024-04-06 05:11:54.715 UTC [51533][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:54.715 UTC [51533][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-06 05:11:54.718 UTC [51532][postmaster] LOG: database system is shut down 2024-04-06 05:11:54.831 UTC [51546][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:54.832 UTC [51546][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:54.832 UTC [51546][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:54.834 UTC [51549][startup] LOG: database system was shut down at 2024-04-06 05:11:54 UTC 2024-04-06 05:11:54.837 UTC [51546][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:54.921 UTC [51554][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46286 2024-04-06 05:11:54.926 UTC [51554][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-06 05:11:54.926 UTC [51554][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-06 05:11:54.930 UTC [51546][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:54.930 UTC [51546][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:54.932 UTC [51546][postmaster] LOG: background worker "logical replication launcher" (PID 51552) exited with exit code 1 2024-04-06 05:11:54.932 UTC [51547][checkpointer] LOG: shutting down 2024-04-06 05:11:54.932 UTC [51547][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:54.933 UTC [51547][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-06 05:11:54.935 UTC [51546][postmaster] LOG: database system is shut down 2024-04-06 05:11:55.049 UTC [51556][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:55.049 UTC [51556][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:55.049 UTC [51556][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:55.051 UTC [51559][startup] LOG: database system was shut down at 2024-04-06 05:11:54 UTC 2024-04-06 05:11:55.054 UTC [51556][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:55.139 UTC [51564][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46298 2024-04-06 05:11:55.144 UTC [51564][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-06 05:11:55.144 UTC [51564][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-06 05:11:55.154 UTC [51566][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46304 2024-04-06 05:11:55.158 UTC [51566][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-06 05:11:55.158 UTC [51566][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-06 05:11:55.163 UTC [51556][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:55.163 UTC [51556][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:55.165 UTC [51556][postmaster] LOG: background worker "logical replication launcher" (PID 51562) exited with exit code 1 2024-04-06 05:11:55.165 UTC [51557][checkpointer] LOG: shutting down 2024-04-06 05:11:55.165 UTC [51557][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:55.165 UTC [51557][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-06 05:11:55.168 UTC [51556][postmaster] LOG: database system is shut down 2024-04-06 05:11:55.282 UTC [51568][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:55.282 UTC [51568][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:55.282 UTC [51568][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:55.284 UTC [51571][startup] LOG: database system was shut down at 2024-04-06 05:11:55 UTC 2024-04-06 05:11:55.287 UTC [51568][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:55.372 UTC [51576][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46310 2024-04-06 05:11:55.374 UTC [51576][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:55.374 UTC [51576][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:55.385 UTC [51578][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46318 2024-04-06 05:11:55.389 UTC [51578][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-06 05:11:55.389 UTC [51578][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-06 05:11:55.394 UTC [51578][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-06 05:11:55.395 UTC [51578][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46318 2024-04-06 05:11:55.399 UTC [51568][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:55.399 UTC [51568][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:55.400 UTC [51568][postmaster] LOG: background worker "logical replication launcher" (PID 51574) exited with exit code 1 2024-04-06 05:11:55.400 UTC [51569][checkpointer] LOG: shutting down 2024-04-06 05:11:55.400 UTC [51569][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:55.401 UTC [51569][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-06 05:11:55.403 UTC [51568][postmaster] LOG: database system is shut down 2024-04-06 05:11:55.517 UTC [51580][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:55.517 UTC [51580][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:55.517 UTC [51580][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:55.520 UTC [51583][startup] LOG: database system was shut down at 2024-04-06 05:11:55 UTC 2024-04-06 05:11:55.523 UTC [51580][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:55.608 UTC [51588][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46326 2024-04-06 05:11:55.610 UTC [51588][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:55.610 UTC [51588][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:55.620 UTC [51590][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46330 2024-04-06 05:11:55.624 UTC [51590][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-06 05:11:55.624 UTC [51590][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-06 05:11:55.629 UTC [51590][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-06 05:11:55.629 UTC [51590][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46330 2024-04-06 05:11:55.634 UTC [51580][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:55.634 UTC [51580][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:55.636 UTC [51580][postmaster] LOG: background worker "logical replication launcher" (PID 51586) exited with exit code 1 2024-04-06 05:11:55.636 UTC [51581][checkpointer] LOG: shutting down 2024-04-06 05:11:55.636 UTC [51581][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:55.636 UTC [51581][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-06 05:11:55.639 UTC [51580][postmaster] LOG: database system is shut down 2024-04-06 05:11:55.752 UTC [51592][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:55.752 UTC [51592][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:55.753 UTC [51592][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:55.755 UTC [51595][startup] LOG: database system was shut down at 2024-04-06 05:11:55 UTC 2024-04-06 05:11:55.759 UTC [51592][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:55.844 UTC [51600][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46334 2024-04-06 05:11:55.845 UTC [51600][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:55.845 UTC [51600][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:55.856 UTC [51602][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46346 2024-04-06 05:11:55.860 UTC [51602][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-06 05:11:55.860 UTC [51602][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-06 05:11:55.866 UTC [51602][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-06 05:11:55.866 UTC [51602][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=46346 2024-04-06 05:11:55.871 UTC [51592][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:55.871 UTC [51592][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:55.872 UTC [51592][postmaster] LOG: background worker "logical replication launcher" (PID 51598) exited with exit code 1 2024-04-06 05:11:55.872 UTC [51593][checkpointer] LOG: shutting down 2024-04-06 05:11:55.873 UTC [51593][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:55.873 UTC [51593][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-06 05:11:55.876 UTC [51592][postmaster] LOG: database system is shut down 2024-04-06 05:11:55.988 UTC [51604][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:55.988 UTC [51604][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:55.988 UTC [51604][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:55.990 UTC [51607][startup] LOG: database system was shut down at 2024-04-06 05:11:55 UTC 2024-04-06 05:11:55.994 UTC [51604][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:56.080 UTC [51612][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46360 2024-04-06 05:11:56.081 UTC [51612][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:56.082 UTC [51612][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:56.091 UTC [51614][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46362 2024-04-06 05:11:56.095 UTC [51614][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-06 05:11:56.095 UTC [51614][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-06 05:11:56.100 UTC [51614][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-06 05:11:56.101 UTC [51614][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46362 2024-04-06 05:11:56.104 UTC [51604][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:56.104 UTC [51604][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:56.106 UTC [51604][postmaster] LOG: background worker "logical replication launcher" (PID 51610) exited with exit code 1 2024-04-06 05:11:56.106 UTC [51605][checkpointer] LOG: shutting down 2024-04-06 05:11:56.106 UTC [51605][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:56.107 UTC [51605][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-06 05:11:56.109 UTC [51604][postmaster] LOG: database system is shut down 2024-04-06 05:11:56.222 UTC [51616][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:56.222 UTC [51616][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:56.222 UTC [51616][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:56.224 UTC [51619][startup] LOG: database system was shut down at 2024-04-06 05:11:56 UTC 2024-04-06 05:11:56.227 UTC [51616][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:56.314 UTC [51624][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46378 2024-04-06 05:11:56.315 UTC [51624][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:56.315 UTC [51624][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:56.326 UTC [51626][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46394 2024-04-06 05:11:56.329 UTC [51626][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-06 05:11:56.329 UTC [51626][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-06 05:11:56.334 UTC [51626][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-06 05:11:56.335 UTC [51626][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46394 2024-04-06 05:11:56.339 UTC [51616][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:56.339 UTC [51616][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:56.341 UTC [51616][postmaster] LOG: background worker "logical replication launcher" (PID 51622) exited with exit code 1 2024-04-06 05:11:56.341 UTC [51617][checkpointer] LOG: shutting down 2024-04-06 05:11:56.341 UTC [51617][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:56.341 UTC [51617][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-06 05:11:56.344 UTC [51616][postmaster] LOG: database system is shut down 2024-04-06 05:11:56.457 UTC [51628][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:56.457 UTC [51628][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:56.457 UTC [51628][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:56.459 UTC [51631][startup] LOG: database system was shut down at 2024-04-06 05:11:56 UTC 2024-04-06 05:11:56.463 UTC [51628][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:56.548 UTC [51636][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46400 2024-04-06 05:11:56.549 UTC [51636][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:56.550 UTC [51636][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:56.560 UTC [51638][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46402 2024-04-06 05:11:56.563 UTC [51638][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-06 05:11:56.563 UTC [51638][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-06 05:11:56.568 UTC [51638][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-06 05:11:56.569 UTC [51638][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46402 2024-04-06 05:11:56.573 UTC [51628][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:56.573 UTC [51628][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:56.575 UTC [51628][postmaster] LOG: background worker "logical replication launcher" (PID 51634) exited with exit code 1 2024-04-06 05:11:56.575 UTC [51629][checkpointer] LOG: shutting down 2024-04-06 05:11:56.575 UTC [51629][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:56.575 UTC [51629][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-06 05:11:56.578 UTC [51628][postmaster] LOG: database system is shut down 2024-04-06 05:11:56.692 UTC [51640][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:56.692 UTC [51640][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:56.692 UTC [51640][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:56.695 UTC [51643][startup] LOG: database system was shut down at 2024-04-06 05:11:56 UTC 2024-04-06 05:11:56.698 UTC [51640][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:56.782 UTC [51648][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46406 2024-04-06 05:11:56.783 UTC [51648][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:56.784 UTC [51648][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:56.793 UTC [51650][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46418 2024-04-06 05:11:56.797 UTC [51650][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-06 05:11:56.797 UTC [51650][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-06 05:11:56.802 UTC [51650][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-06 05:11:56.802 UTC [51650][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46418 2024-04-06 05:11:56.806 UTC [51640][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:56.806 UTC [51640][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:56.807 UTC [51640][postmaster] LOG: background worker "logical replication launcher" (PID 51646) exited with exit code 1 2024-04-06 05:11:56.808 UTC [51641][checkpointer] LOG: shutting down 2024-04-06 05:11:56.808 UTC [51641][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:56.808 UTC [51641][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-06 05:11:56.811 UTC [51640][postmaster] LOG: database system is shut down 2024-04-06 05:11:56.924 UTC [51652][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:56.924 UTC [51652][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:56.924 UTC [51652][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:56.926 UTC [51655][startup] LOG: database system was shut down at 2024-04-06 05:11:56 UTC 2024-04-06 05:11:56.929 UTC [51652][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:57.014 UTC [51660][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46422 2024-04-06 05:11:57.016 UTC [51660][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:57.016 UTC [51660][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:57.024 UTC [51662][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46426 2024-04-06 05:11:57.028 UTC [51662][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-06 05:11:57.028 UTC [51662][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-06 05:11:57.033 UTC [51662][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-06 05:11:57.033 UTC [51662][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46426 2024-04-06 05:11:57.038 UTC [51652][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:57.038 UTC [51652][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:57.039 UTC [51652][postmaster] LOG: background worker "logical replication launcher" (PID 51658) exited with exit code 1 2024-04-06 05:11:57.039 UTC [51653][checkpointer] LOG: shutting down 2024-04-06 05:11:57.039 UTC [51653][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:57.040 UTC [51653][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-06 05:11:57.042 UTC [51652][postmaster] LOG: database system is shut down 2024-04-06 05:11:57.156 UTC [51664][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-04-06 05:11:57.156 UTC [51664][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52760 2024-04-06 05:11:57.156 UTC [51664][postmaster] LOG: listening on Unix socket "/tmp/wLGCmxk5R7/.s.PGSQL.52760" 2024-04-06 05:11:57.159 UTC [51667][startup] LOG: database system was shut down at 2024-04-06 05:11:57 UTC 2024-04-06 05:11:57.162 UTC [51664][postmaster] LOG: database system is ready to accept connections 2024-04-06 05:11:57.246 UTC [51672][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46436 2024-04-06 05:11:57.247 UTC [51672][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-06 05:11:57.248 UTC [51672][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-06 05:11:57.257 UTC [51674][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=46442 2024-04-06 05:11:57.261 UTC [51674][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-06 05:11:57.261 UTC [51674][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-06 05:11:57.266 UTC [51674][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-06 05:11:57.267 UTC [51674][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=46442 2024-04-06 05:11:57.271 UTC [51664][postmaster] LOG: received fast shutdown request 2024-04-06 05:11:57.271 UTC [51664][postmaster] LOG: aborting any active transactions 2024-04-06 05:11:57.272 UTC [51664][postmaster] LOG: background worker "logical replication launcher" (PID 51670) exited with exit code 1 2024-04-06 05:11:57.272 UTC [51665][checkpointer] LOG: shutting down 2024-04-06 05:11:57.272 UTC [51665][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-06 05:11:57.273 UTC [51665][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-06 05:11:57.275 UTC [51664][postmaster] LOG: database system is shut down 2024-04-06 05:11:57.384 UTC [51676][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-04-06 05:11:57.384 UTC [51676][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:11:57.472](0.204s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"