[05:09:01.611](0.028s) # setting up data directory # Checking port 54115 # Found port 54115 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=54115 host=/tmp/RK1X6tiCFB Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [05:09:01.617](0.006s) # 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 49675 [05:09:01.865](0.248s) 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 50010 [05:09:02.920](1.055s) # 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-03-28 05:09:01.774 UTC [49675][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:01.774 UTC [49675][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:01.776 UTC [49692][startup] LOG: database system was shut down at 2024-03-28 05:06:44 UTC 2024-03-28 05:09:01.781 UTC [49675][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:01.857 UTC [49721][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.858 UTC [49721][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-03-28 05:09:01.858 UTC [49721][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.863 UTC [49721][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-28 05:09:01.864 UTC [49721][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.872 UTC [49731][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.872 UTC [49731][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-03-28 05:09:01.872 UTC [49731][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.873 UTC [49731][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-28 05:09:01.873 UTC [49731][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.914 UTC [49756][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.914 UTC [49756][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-03-28 05:09:01.914 UTC [49756][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.915 UTC [49756][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-28 05:09:01.915 UTC [49756][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.956 UTC [49764][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.956 UTC [49764][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-03-28 05:09:01.956 UTC [49764][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.957 UTC [49764][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-28 05:09:01.957 UTC [49764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.005 UTC [49778][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.006 UTC [49778][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-03-28 05:09:02.006 UTC [49778][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.007 UTC [49778][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-28 05:09:02.007 UTC [49778][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.053 UTC [49800][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.053 UTC [49800][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-03-28 05:09:02.053 UTC [49800][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.054 UTC [49800][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-28 05:09:02.086 UTC [49800][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.096 UTC [49809][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.097 UTC [49809][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-03-28 05:09:02.097 UTC [49809][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.097 UTC [49809][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-28 05:09:02.144 UTC [49809][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.229 UTC [49840][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.230 UTC [49840][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-03-28 05:09:02.230 UTC [49840][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.230 UTC [49840][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-28 05:09:02.255 UTC [49840][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.269 UTC [49853][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.270 UTC [49853][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-03-28 05:09:02.270 UTC [49853][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.271 UTC [49853][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-28 05:09:02.310 UTC [49853][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.041 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.394 UTC [49895][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.395 UTC [49895][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-03-28 05:09:02.395 UTC [49895][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.395 UTC [49895][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-28 05:09:02.419 UTC [49895][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.436 UTC [49912][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.437 UTC [49912][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-03-28 05:09:02.437 UTC [49912][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.438 UTC [49912][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-28 05:09:02.583 UTC [49912][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.146 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.719 UTC [49675][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:02.719 UTC [49675][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:02.720 UTC [49675][postmaster] LOG: background worker "logical replication launcher" (PID 49696) exited with exit code 1 2024-03-28 05:09:02.721 UTC [49689][checkpointer] LOG: shutting down 2024-03-28 05:09:02.721 UTC [49689][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:02.753 UTC [49689][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.032 s, sync=0.001 s, total=0.033 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414C3F8, redo lsn=0/414C3F8 2024-03-28 05:09:02.761 UTC [49675][postmaster] LOG: database system is shut down 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:02.858 UTC [50017][startup] LOG: database system was shut down at 2024-03-28 05:09:02 UTC 2024-03-28 05:09:02.861 UTC [50010][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:02.922 UTC [50010][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:02.922 UTC [50010][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:02.923 UTC [50010][postmaster] LOG: background worker "logical replication launcher" (PID 50025) exited with exit code 1 2024-03-28 05:09:02.923 UTC [50015][checkpointer] LOG: shutting down 2024-03-28 05:09:02.923 UTC [50015][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:02.924 UTC [50015][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/414C470, redo lsn=0/414C470 2024-03-28 05:09:02.926 UTC [50010][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.035 UTC [50059][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-28 05:09:03.035 UTC [50059][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:09:03.123](0.203s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 50079 [05:09:03.226](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-03-28 05:09:01.774 UTC [49675][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:01.774 UTC [49675][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:01.776 UTC [49692][startup] LOG: database system was shut down at 2024-03-28 05:06:44 UTC 2024-03-28 05:09:01.781 UTC [49675][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:01.857 UTC [49721][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.858 UTC [49721][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-03-28 05:09:01.858 UTC [49721][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.863 UTC [49721][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-28 05:09:01.864 UTC [49721][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.872 UTC [49731][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.872 UTC [49731][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-03-28 05:09:01.872 UTC [49731][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.873 UTC [49731][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-28 05:09:01.873 UTC [49731][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.914 UTC [49756][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.914 UTC [49756][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-03-28 05:09:01.914 UTC [49756][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.915 UTC [49756][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-28 05:09:01.915 UTC [49756][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.956 UTC [49764][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.956 UTC [49764][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-03-28 05:09:01.956 UTC [49764][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.957 UTC [49764][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-28 05:09:01.957 UTC [49764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.005 UTC [49778][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.006 UTC [49778][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-03-28 05:09:02.006 UTC [49778][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.007 UTC [49778][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-28 05:09:02.007 UTC [49778][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.053 UTC [49800][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.053 UTC [49800][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-03-28 05:09:02.053 UTC [49800][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.054 UTC [49800][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-28 05:09:02.086 UTC [49800][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.096 UTC [49809][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.097 UTC [49809][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-03-28 05:09:02.097 UTC [49809][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.097 UTC [49809][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-28 05:09:02.144 UTC [49809][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.229 UTC [49840][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.230 UTC [49840][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-03-28 05:09:02.230 UTC [49840][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.230 UTC [49840][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-28 05:09:02.255 UTC [49840][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.269 UTC [49853][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.270 UTC [49853][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-03-28 05:09:02.270 UTC [49853][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.271 UTC [49853][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-28 05:09:02.310 UTC [49853][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.041 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.394 UTC [49895][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.395 UTC [49895][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-03-28 05:09:02.395 UTC [49895][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.395 UTC [49895][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-28 05:09:02.419 UTC [49895][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.436 UTC [49912][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.437 UTC [49912][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-03-28 05:09:02.437 UTC [49912][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.438 UTC [49912][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-28 05:09:02.583 UTC [49912][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.146 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.719 UTC [49675][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:02.719 UTC [49675][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:02.720 UTC [49675][postmaster] LOG: background worker "logical replication launcher" (PID 49696) exited with exit code 1 2024-03-28 05:09:02.721 UTC [49689][checkpointer] LOG: shutting down 2024-03-28 05:09:02.721 UTC [49689][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:02.753 UTC [49689][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.032 s, sync=0.001 s, total=0.033 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414C3F8, redo lsn=0/414C3F8 2024-03-28 05:09:02.761 UTC [49675][postmaster] LOG: database system is shut down 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:02.858 UTC [50017][startup] LOG: database system was shut down at 2024-03-28 05:09:02 UTC 2024-03-28 05:09:02.861 UTC [50010][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:02.922 UTC [50010][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:02.922 UTC [50010][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:02.923 UTC [50010][postmaster] LOG: background worker "logical replication launcher" (PID 50025) exited with exit code 1 2024-03-28 05:09:02.923 UTC [50015][checkpointer] LOG: shutting down 2024-03-28 05:09:02.923 UTC [50015][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:02.924 UTC [50015][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/414C470, redo lsn=0/414C470 2024-03-28 05:09:02.926 UTC [50010][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.035 UTC [50059][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-28 05:09:03.035 UTC [50059][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.143 UTC [50079][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:03.143 UTC [50079][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:03.143 UTC [50079][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:03.144 UTC [50084][startup] LOG: database system was shut down at 2024-03-28 05:09:02 UTC 2024-03-28 05:09:03.147 UTC [50079][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:03.228 UTC [50079][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:03.228 UTC [50079][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:03.230 UTC [50079][postmaster] LOG: background worker "logical replication launcher" (PID 50087) exited with exit code 1 2024-03-28 05:09:03.230 UTC [50082][checkpointer] LOG: shutting down 2024-03-28 05:09:03.231 UTC [50082][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:03.231 UTC [50082][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/414C4E8, redo lsn=0/414C4E8 2024-03-28 05:09:03.233 UTC [50079][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.341 UTC [50128][postmaster] FATAL: could not set SSL protocol version range 2024-03-28 05:09:03.341 UTC [50128][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-28 05:09:03.341 UTC [50128][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:09:03.429](0.203s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 50150 [05:09:03.532](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [05:09:03.532](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 50213 [05:09:03.744](0.211s) ok 6 - server doesn't accept non-SSL connections [05:09:03.744](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [05:09:03.759](0.016s) ok 8 - connect without server root cert sslmode=require [05:09:03.760](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [05:09:03.769](0.010s) ok 10 - connect without server root cert sslmode=verify-ca [05:09:03.769](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [05:09:03.777](0.008s) ok 12 - connect without server root cert sslmode=verify-full [05:09:03.777](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [05:09:03.790](0.013s) ok 14 - connect with wrong server root cert sslmode=require [05:09:03.790](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [05:09:03.810](0.019s) ok 16 - connect with wrong server root cert sslmode=verify-ca [05:09:03.810](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [05:09:03.821](0.011s) ok 18 - connect with wrong server root cert sslmode=verify-full [05:09:03.822](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [05:09:03.832](0.011s) ok 20 - connect with server CA cert, without root CA [05:09:03.832](0.000s) ok 21 - connect with server CA cert, without root CA: matches [05:09:03.844](0.012s) ok 22 - connect with correct server CA cert file sslmode=require [05:09:03.844](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [05:09:03.856](0.011s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [05:09:03.856](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [05:09:03.867](0.011s) ok 26 - connect with correct server CA cert file sslmode=verify-full [05:09:03.867](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [05:09:03.878](0.011s) ok 28 - cert root file that contains two certificates, order 1 [05:09:03.878](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [05:09:03.888](0.010s) ok 30 - cert root file that contains two certificates, order 2 [05:09:03.888](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [05:09:03.899](0.010s) ok 32 - connect with sslcertmode=disable [05:09:03.899](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [05:09:03.909](0.010s) ok 34 - connect with sslcertmode=allow [05:09:03.909](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [05:09:03.919](0.010s) ok 36 - connect with sslcertmode=require fails without a client certificate [05:09:03.919](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [05:09:03.930](0.011s) ok 38 - sslcrl option with invalid file name [05:09:03.930](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [05:09:03.939](0.009s) ok 40 - CRL belonging to a different CA [05:09:03.939](0.000s) ok 41 - CRL belonging to a different CA: matches [05:09:03.949](0.009s) ok 42 - directory CRL belonging to a different CA [05:09:03.949](0.000s) ok 43 - directory CRL belonging to a different CA: matches [05:09:03.960](0.011s) ok 44 - CRL with a non-revoked cert [05:09:03.960](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [05:09:03.971](0.011s) ok 46 - directory CRL with a non-revoked cert [05:09:03.971](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [05:09:03.982](0.011s) ok 48 - mismatch between host name and server certificate sslmode=require [05:09:03.982](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [05:09:03.993](0.011s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [05:09:03.993](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [05:09:04.002](0.009s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [05:09:04.002](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 50300 [05:09:04.221](0.219s) ok 54 - IP address in the Common Name [05:09:04.221](0.000s) ok 55 - IP address in the Common Name: no stderr [05:09:04.230](0.009s) ok 56 - mismatch between host name and server certificate IP address [05:09:04.231](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 50326 [05:09:04.450](0.219s) ok 58 - IP address in a dNSName [05:09:04.450](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 50348 [05:09:04.668](0.219s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [05:09:04.669](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [05:09:04.680](0.012s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [05:09:04.680](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [05:09:04.692](0.011s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [05:09:04.692](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [05:09:04.702](0.010s) ok 66 - host name not matching with X.509 Subject Alternative Names [05:09:04.702](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [05:09:04.711](0.010s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [05:09:04.712](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 50380 [05:09:04.930](0.219s) ok 70 - host name matching with a single X.509 Subject Alternative Name [05:09:04.930](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [05:09:04.940](0.010s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [05:09:04.940](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [05:09:04.949](0.009s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [05:09:04.949](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 50404 [05:09:05.170](0.221s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [05:09:05.171](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [05:09:05.182](0.011s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [05:09:05.182](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [05:09:05.191](0.009s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [05:09:05.192](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [05:09:05.202](0.011s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [05:09:05.202](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [05:09:05.213](0.010s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [05:09:05.213](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [05:09:05.223](0.010s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [05:09:05.224](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [05:09:05.233](0.009s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [05:09:05.233](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [05:09:05.243](0.010s) ok 90 - IPv6 host with CIDR mask does not match [05:09:05.243](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 50428 [05:09:05.461](0.219s) ok 92 - certificate with both a CN and SANs 1 [05:09:05.462](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [05:09:05.473](0.011s) ok 94 - certificate with both a CN and SANs 2 [05:09:05.473](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [05:09:05.482](0.009s) ok 96 - certificate with both a CN and SANs ignores CN [05:09:05.482](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 50443 [05:09:05.703](0.221s) ok 98 - certificate with both a CN and IP SANs matches CN [05:09:05.703](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [05:09:05.714](0.011s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [05:09:05.715](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [05:09:05.725](0.011s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [05:09:05.725](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 50457 [05:09:05.944](0.219s) ok 104 - certificate with both an IP CN and IP SANs 1 [05:09:05.944](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [05:09:05.955](0.011s) ok 106 - certificate with both an IP CN and IP SANs 2 [05:09:05.955](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [05:09:05.965](0.009s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [05:09:05.965](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 50471 [05:09:06.184](0.219s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [05:09:06.184](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [05:09:06.195](0.011s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [05:09:06.195](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [05:09:06.206](0.011s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [05:09:06.206](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 50485 [05:09:06.425](0.219s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [05:09:06.425](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [05:09:06.434](0.010s) ok 118 - server certificate without CN or SANs sslmode=verify-full [05:09:06.435](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 50497 [05:09:06.648](0.213s) ok 120 - sslrootcert=system does not connect with private CA [05:09:06.648](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [05:09:06.653](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [05:09:06.654](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [05:09:06.668](0.014s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [05:09:06.668](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [05:09:06.677](0.009s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [05:09:06.677](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 50512 [05:09:06.896](0.219s) ok 128 - connects without client-side CRL [05:09:06.897](0.000s) ok 129 - connects without client-side CRL: no stderr [05:09:06.906](0.010s) ok 130 - does not connect with client-side CRL file [05:09:06.906](0.000s) ok 131 - does not connect with client-side CRL file: matches [05:09:06.915](0.009s) ok 132 - does not connect with client-side CRL directory [05:09:06.916](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:09:06.928](0.012s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [05:09:06.928](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [05:09:06.928](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [05:09:06.940](0.012s) ok 137 - connection success with correct range of TLS protocol versions [05:09:06.940](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [05:09:06.946](0.006s) ok 139 - connection failure with incorrect range of TLS protocol versions [05:09:06.946](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [05:09:06.951](0.005s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [05:09:06.951](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [05:09:06.956](0.005s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [05:09:06.956](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [05:09:06.956](0.000s) # running server tests [05:09:06.966](0.009s) ok 145 - certificate authorization fails without client cert [05:09:06.966](0.000s) ok 146 - certificate authorization fails without client cert: matches [05:09:06.984](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [05:09:06.984](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [05:09:06.997](0.012s) ok 149 - certificate authorization succeeds with correct client cert in DER format [05:09:06.997](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [05:09:07.009](0.012s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [05:09:07.009](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [05:09:07.022](0.012s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [05:09:07.022](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [05:09:07.034](0.012s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [05:09:07.034](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [05:09:07.046](0.012s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [05:09:07.046](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [05:09:07.056](0.009s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [05:09:07.056](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [05:09:07.063](0.007s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [05:09:07.064](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [05:09:07.079](0.015s) ok 163 - certificate authorization succeeds with DN mapping [05:09:07.079](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [05:09:07.079](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [05:09:07.095](0.016s) ok 166 - certificate authorization succeeds with DN regex mapping [05:09:07.095](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [05:09:07.111](0.016s) ok 168 - certificate authorization succeeds with CN mapping [05:09:07.111](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [05:09:07.111](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [05:09:07.111](0.000s) not ok 171 # TODO & SKIP Need Pty support [05:09:07.111](0.000s) not ok 172 # TODO & SKIP Need Pty support [05:09:07.112](0.000s) not ok 173 # TODO & SKIP Need Pty support [05:09:07.112](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_QZ6E/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:09:07.131](0.020s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [05:09:07.131](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [05:09:07.132](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [05:09:07.139](0.008s) ok 178 - certificate authorization fails because of file permissions [05:09:07.139](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [05:09:07.150](0.011s) ok 180 - certificate authorization fails with client cert belonging to another user [05:09:07.151](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [05:09:07.161](0.011s) ok 182 - certificate authorization fails with revoked client cert [05:09:07.161](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [05:09:07.161](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [05:09:07.176](0.015s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [05:09:07.177](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [05:09:07.177](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [05:09:07.188](0.011s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [05:09:07.188](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [05:09:07.188](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [05:09:07.201](0.012s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [05:09:07.201](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [05:09:07.201](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 50572 [05:09:07.422](0.221s) ok 194 - intermediate client certificate is provided by client [05:09:07.422](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [05:09:07.433](0.011s) ok 196 - intermediate client certificate is missing [05:09:07.433](0.000s) ok 197 - intermediate client certificate is missing: matches [05:09:07.443](0.011s) ok 198 - logged client certificate Subjects are truncated if they're too long [05:09:07.444](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 50586 [05:09:07.658](0.215s) ok 200 - intermediate client certificate is untrusted [05:09:07.659](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 50596 [05:09:07.874](0.215s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [05:09:07.874](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [05:09:07.884](0.011s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [05:09:07.885](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 50608 [05:09:08.097](0.213s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [05:09:08.097](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:09:08.098](0.000s) # got: '2' # expected: '0' [05:09:08.098](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:09:08.098](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:09:08.098](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 54115 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:09:08.113](0.015s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [05:09:08.113](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 50620 [05:09:08.325](0.212s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [05:09:08.340](0.015s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [05:09:08.340](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 50632 [05:09:08.552](0.212s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [05:09:08.567](0.015s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [05:09:08.567](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 50644 [05:09:08.780](0.213s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [05:09:08.795](0.015s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [05:09:08.795](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 50656 [05:09:09.007](0.213s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [05:09:09.008](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:09:09.008](0.000s) # got: '2' # expected: '0' [05:09:09.008](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:09:09.008](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:09:09.008](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 54115 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:09:09.023](0.015s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [05:09:09.024](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 50668 [05:09:09.237](0.213s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [05:09:09.252](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [05:09:09.253](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 50680 [05:09:09.468](0.215s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [05:09:09.483](0.016s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [05:09:09.484](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 50692 [05:09:09.697](0.213s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [05:09:09.712](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [05:09:09.713](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 50704 [05:09:09.926](0.213s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [05:09:09.942](0.016s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [05:09:09.942](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-03-28 05:09:01.774 UTC [49675][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:01.774 UTC [49675][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:01.776 UTC [49692][startup] LOG: database system was shut down at 2024-03-28 05:06:44 UTC 2024-03-28 05:09:01.781 UTC [49675][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:01.857 UTC [49721][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.858 UTC [49721][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-03-28 05:09:01.858 UTC [49721][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.863 UTC [49721][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-28 05:09:01.864 UTC [49721][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.872 UTC [49731][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.872 UTC [49731][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-03-28 05:09:01.872 UTC [49731][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.873 UTC [49731][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-28 05:09:01.873 UTC [49731][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.914 UTC [49756][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.914 UTC [49756][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-03-28 05:09:01.914 UTC [49756][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.915 UTC [49756][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-28 05:09:01.915 UTC [49756][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:01.956 UTC [49764][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:01.956 UTC [49764][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-03-28 05:09:01.956 UTC [49764][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:01.957 UTC [49764][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-28 05:09:01.957 UTC [49764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.005 UTC [49778][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.006 UTC [49778][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-03-28 05:09:02.006 UTC [49778][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.007 UTC [49778][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-28 05:09:02.007 UTC [49778][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.053 UTC [49800][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.053 UTC [49800][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-03-28 05:09:02.053 UTC [49800][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.054 UTC [49800][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-28 05:09:02.086 UTC [49800][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.096 UTC [49809][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.097 UTC [49809][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-03-28 05:09:02.097 UTC [49809][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.097 UTC [49809][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-28 05:09:02.144 UTC [49809][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.229 UTC [49840][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.230 UTC [49840][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-03-28 05:09:02.230 UTC [49840][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.230 UTC [49840][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-28 05:09:02.255 UTC [49840][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.269 UTC [49853][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.270 UTC [49853][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-03-28 05:09:02.270 UTC [49853][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.271 UTC [49853][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-28 05:09:02.310 UTC [49853][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.041 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.394 UTC [49895][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.395 UTC [49895][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-03-28 05:09:02.395 UTC [49895][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.395 UTC [49895][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-28 05:09:02.419 UTC [49895][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.436 UTC [49912][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-28 05:09:02.437 UTC [49912][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-03-28 05:09:02.437 UTC [49912][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-28 05:09:02.438 UTC [49912][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-28 05:09:02.583 UTC [49912][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.146 user=postgres database=postgres host=[local] 2024-03-28 05:09:02.719 UTC [49675][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:02.719 UTC [49675][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:02.720 UTC [49675][postmaster] LOG: background worker "logical replication launcher" (PID 49696) exited with exit code 1 2024-03-28 05:09:02.721 UTC [49689][checkpointer] LOG: shutting down 2024-03-28 05:09:02.721 UTC [49689][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:02.753 UTC [49689][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.032 s, sync=0.001 s, total=0.033 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414C3F8, redo lsn=0/414C3F8 2024-03-28 05:09:02.761 UTC [49675][postmaster] LOG: database system is shut down 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:02.834 UTC [50010][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:02.858 UTC [50017][startup] LOG: database system was shut down at 2024-03-28 05:09:02 UTC 2024-03-28 05:09:02.861 UTC [50010][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:02.922 UTC [50010][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:02.922 UTC [50010][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:02.923 UTC [50010][postmaster] LOG: background worker "logical replication launcher" (PID 50025) exited with exit code 1 2024-03-28 05:09:02.923 UTC [50015][checkpointer] LOG: shutting down 2024-03-28 05:09:02.923 UTC [50015][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:02.924 UTC [50015][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/414C470, redo lsn=0/414C470 2024-03-28 05:09:02.926 UTC [50010][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.035 UTC [50059][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-28 05:09:03.035 UTC [50059][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.143 UTC [50079][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:03.143 UTC [50079][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:03.143 UTC [50079][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:03.144 UTC [50084][startup] LOG: database system was shut down at 2024-03-28 05:09:02 UTC 2024-03-28 05:09:03.147 UTC [50079][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:03.228 UTC [50079][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:03.228 UTC [50079][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:03.230 UTC [50079][postmaster] LOG: background worker "logical replication launcher" (PID 50087) exited with exit code 1 2024-03-28 05:09:03.230 UTC [50082][checkpointer] LOG: shutting down 2024-03-28 05:09:03.231 UTC [50082][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:03.231 UTC [50082][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/414C4E8, redo lsn=0/414C4E8 2024-03-28 05:09:03.233 UTC [50079][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.341 UTC [50128][postmaster] FATAL: could not set SSL protocol version range 2024-03-28 05:09:03.341 UTC [50128][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-28 05:09:03.341 UTC [50128][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.449 UTC [50150][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:03.449 UTC [50150][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:03.449 UTC [50150][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:03.452 UTC [50159][startup] LOG: database system was shut down at 2024-03-28 05:09:03 UTC 2024-03-28 05:09:03.455 UTC [50150][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:03.534 UTC [50150][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:03.535 UTC [50150][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:03.536 UTC [50150][postmaster] LOG: background worker "logical replication launcher" (PID 50162) exited with exit code 1 2024-03-28 05:09:03.537 UTC [50157][checkpointer] LOG: shutting down 2024-03-28 05:09:03.537 UTC [50157][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:03.537 UTC [50157][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/414C560, redo lsn=0/414C560 2024-03-28 05:09:03.539 UTC [50150][postmaster] LOG: database system is shut down 2024-03-28 05:09:03.651 UTC [50213][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:03.651 UTC [50213][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:03.651 UTC [50213][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:03.653 UTC [50218][startup] LOG: database system was shut down at 2024-03-28 05:09:03 UTC 2024-03-28 05:09:03.655 UTC [50213][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:03.742 UTC [50233][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35852 2024-03-28 05:09:03.742 UTC [50233][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-03-28 05:09:03.742 UTC [50233][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-28 05:09:03.750 UTC [50235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35866 2024-03-28 05:09:03.753 UTC [50235][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-03-28 05:09:03.753 UTC [50235][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-03-28 05:09:03.758 UTC [50235][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-03-28 05:09:03.759 UTC [50235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35866 2024-03-28 05:09:03.766 UTC [50237][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35868 2024-03-28 05:09:03.768 UTC [50237][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-28 05:09:03.775 UTC [50240][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35872 2024-03-28 05:09:03.776 UTC [50240][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-28 05:09:03.785 UTC [50244][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35878 2024-03-28 05:09:03.788 UTC [50244][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:03.804 UTC [50247][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35116 2024-03-28 05:09:03.808 UTC [50247][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:03.817 UTC [50250][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35122 2024-03-28 05:09:03.820 UTC [50250][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:03.828 UTC [50252][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35132 2024-03-28 05:09:03.831 UTC [50252][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:03.839 UTC [50254][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35144 2024-03-28 05:09:03.842 UTC [50254][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-03-28 05:09:03.842 UTC [50254][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-03-28 05:09:03.843 UTC [50254][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-03-28 05:09:03.843 UTC [50254][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35144 2024-03-28 05:09:03.850 UTC [50256][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35158 2024-03-28 05:09:03.854 UTC [50256][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-03-28 05:09:03.854 UTC [50256][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-03-28 05:09:03.855 UTC [50256][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-03-28 05:09:03.855 UTC [50256][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35158 2024-03-28 05:09:03.862 UTC [50258][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35160 2024-03-28 05:09:03.865 UTC [50258][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-03-28 05:09:03.865 UTC [50258][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-03-28 05:09:03.866 UTC [50258][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-03-28 05:09:03.866 UTC [50258][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35160 2024-03-28 05:09:03.873 UTC [50260][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35174 2024-03-28 05:09:03.876 UTC [50260][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-03-28 05:09:03.876 UTC [50260][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-03-28 05:09:03.877 UTC [50260][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-03-28 05:09:03.877 UTC [50260][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35174 2024-03-28 05:09:03.883 UTC [50262][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35188 2024-03-28 05:09:03.886 UTC [50262][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-03-28 05:09:03.886 UTC [50262][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-03-28 05:09:03.887 UTC [50262][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-03-28 05:09:03.887 UTC [50262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35188 2024-03-28 05:09:03.894 UTC [50264][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35190 2024-03-28 05:09:03.897 UTC [50264][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-03-28 05:09:03.897 UTC [50264][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-03-28 05:09:03.898 UTC [50264][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-03-28 05:09:03.898 UTC [50264][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35190 2024-03-28 05:09:03.904 UTC [50266][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35204 2024-03-28 05:09:03.907 UTC [50266][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-03-28 05:09:03.907 UTC [50266][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-03-28 05:09:03.908 UTC [50266][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-03-28 05:09:03.908 UTC [50266][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35204 2024-03-28 05:09:03.914 UTC [50269][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35212 2024-03-28 05:09:03.918 UTC [50269][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-03-28 05:09:03.918 UTC [50269][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-03-28 05:09:03.918 UTC [50269][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35212 2024-03-28 05:09:03.925 UTC [50275][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35220 2024-03-28 05:09:03.928 UTC [50275][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-03-28 05:09:03.928 UTC [50275][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-03-28 05:09:03.929 UTC [50275][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-03-28 05:09:03.929 UTC [50275][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35220 2024-03-28 05:09:03.936 UTC [50280][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35232 2024-03-28 05:09:03.938 UTC [50280][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:03.945 UTC [50283][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35240 2024-03-28 05:09:03.948 UTC [50283][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:03.954 UTC [50285][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35250 2024-03-28 05:09:03.958 UTC [50285][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-03-28 05:09:03.958 UTC [50285][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-03-28 05:09:03.958 UTC [50285][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-03-28 05:09:03.959 UTC [50285][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35250 2024-03-28 05:09:03.965 UTC [50287][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35266 2024-03-28 05:09:03.969 UTC [50287][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-03-28 05:09:03.969 UTC [50287][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-03-28 05:09:03.969 UTC [50287][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-03-28 05:09:03.970 UTC [50287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35266 2024-03-28 05:09:03.976 UTC [50289][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35280 2024-03-28 05:09:03.980 UTC [50289][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-03-28 05:09:03.980 UTC [50289][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-03-28 05:09:03.980 UTC [50289][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-03-28 05:09:03.981 UTC [50289][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35280 2024-03-28 05:09:03.987 UTC [50291][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35282 2024-03-28 05:09:03.991 UTC [50291][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-03-28 05:09:03.991 UTC [50291][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-03-28 05:09:03.991 UTC [50291][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-03-28 05:09:03.992 UTC [50291][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35282 2024-03-28 05:09:03.998 UTC [50293][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35286 2024-03-28 05:09:04.004 UTC [50213][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:04.004 UTC [50213][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:04.005 UTC [50213][postmaster] LOG: background worker "logical replication launcher" (PID 50222) exited with exit code 1 2024-03-28 05:09:04.006 UTC [50216][checkpointer] LOG: shutting down 2024-03-28 05:09:04.006 UTC [50216][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:04.006 UTC [50216][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/414C5D8, redo lsn=0/414C5D8 2024-03-28 05:09:04.008 UTC [50213][postmaster] LOG: database system is shut down 2024-03-28 05:09:04.120 UTC [50300][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:04.120 UTC [50300][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:04.120 UTC [50300][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:04.122 UTC [50304][startup] LOG: database system was shut down at 2024-03-28 05:09:04 UTC 2024-03-28 05:09:04.125 UTC [50300][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:04.211 UTC [50315][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35300 2024-03-28 05:09:04.215 UTC [50315][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-03-28 05:09:04.215 UTC [50315][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-03-28 05:09:04.219 UTC [50315][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-03-28 05:09:04.220 UTC [50315][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35300 2024-03-28 05:09:04.226 UTC [50319][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35310 2024-03-28 05:09:04.232 UTC [50300][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:04.232 UTC [50300][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:04.233 UTC [50300][postmaster] LOG: background worker "logical replication launcher" (PID 50307) exited with exit code 1 2024-03-28 05:09:04.234 UTC [50302][checkpointer] LOG: shutting down 2024-03-28 05:09:04.234 UTC [50302][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:04.235 UTC [50302][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/414C650, redo lsn=0/414C650 2024-03-28 05:09:04.237 UTC [50300][postmaster] LOG: database system is shut down 2024-03-28 05:09:04.349 UTC [50326][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:04.349 UTC [50326][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:04.349 UTC [50326][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:04.351 UTC [50330][startup] LOG: database system was shut down at 2024-03-28 05:09:04 UTC 2024-03-28 05:09:04.354 UTC [50326][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:04.440 UTC [50343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35326 2024-03-28 05:09:04.443 UTC [50343][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-03-28 05:09:04.443 UTC [50343][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-03-28 05:09:04.448 UTC [50343][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-03-28 05:09:04.448 UTC [50343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35326 2024-03-28 05:09:04.451 UTC [50326][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:04.451 UTC [50326][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:04.452 UTC [50326][postmaster] LOG: background worker "logical replication launcher" (PID 50333) exited with exit code 1 2024-03-28 05:09:04.453 UTC [50328][checkpointer] LOG: shutting down 2024-03-28 05:09:04.453 UTC [50328][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:04.454 UTC [50328][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/414C6C8, redo lsn=0/414C6C8 2024-03-28 05:09:04.456 UTC [50326][postmaster] LOG: database system is shut down 2024-03-28 05:09:04.569 UTC [50348][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:04.569 UTC [50348][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:04.569 UTC [50348][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:04.571 UTC [50353][startup] LOG: database system was shut down at 2024-03-28 05:09:04 UTC 2024-03-28 05:09:04.573 UTC [50348][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:04.659 UTC [50366][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35336 2024-03-28 05:09:04.662 UTC [50366][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-03-28 05:09:04.662 UTC [50366][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-03-28 05:09:04.667 UTC [50366][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-03-28 05:09:04.667 UTC [50366][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35336 2024-03-28 05:09:04.675 UTC [50368][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35338 2024-03-28 05:09:04.678 UTC [50368][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-03-28 05:09:04.678 UTC [50368][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-03-28 05:09:04.679 UTC [50368][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-03-28 05:09:04.679 UTC [50368][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35338 2024-03-28 05:09:04.686 UTC [50372][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35354 2024-03-28 05:09:04.689 UTC [50372][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-03-28 05:09:04.689 UTC [50372][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-03-28 05:09:04.690 UTC [50372][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-03-28 05:09:04.691 UTC [50372][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35354 2024-03-28 05:09:04.697 UTC [50374][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35368 2024-03-28 05:09:04.708 UTC [50377][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35376 2024-03-28 05:09:04.713 UTC [50348][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:04.713 UTC [50348][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:04.714 UTC [50348][postmaster] LOG: background worker "logical replication launcher" (PID 50357) exited with exit code 1 2024-03-28 05:09:04.714 UTC [50351][checkpointer] LOG: shutting down 2024-03-28 05:09:04.714 UTC [50351][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:04.715 UTC [50351][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/414C740, redo lsn=0/414C740 2024-03-28 05:09:04.717 UTC [50348][postmaster] LOG: database system is shut down 2024-03-28 05:09:04.830 UTC [50380][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:04.830 UTC [50380][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:04.830 UTC [50380][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:04.832 UTC [50389][startup] LOG: database system was shut down at 2024-03-28 05:09:04 UTC 2024-03-28 05:09:04.835 UTC [50380][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:04.920 UTC [50396][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35384 2024-03-28 05:09:04.924 UTC [50396][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-03-28 05:09:04.924 UTC [50396][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-03-28 05:09:04.928 UTC [50396][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-03-28 05:09:04.929 UTC [50396][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35384 2024-03-28 05:09:04.936 UTC [50399][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35386 2024-03-28 05:09:04.945 UTC [50401][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35402 2024-03-28 05:09:04.953 UTC [50380][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:04.953 UTC [50380][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:04.954 UTC [50380][postmaster] LOG: background worker "logical replication launcher" (PID 50392) exited with exit code 1 2024-03-28 05:09:04.954 UTC [50387][checkpointer] LOG: shutting down 2024-03-28 05:09:04.954 UTC [50387][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:04.955 UTC [50387][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/414C7B8, redo lsn=0/414C7B8 2024-03-28 05:09:04.957 UTC [50380][postmaster] LOG: database system is shut down 2024-03-28 05:09:05.070 UTC [50404][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:05.070 UTC [50404][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:05.070 UTC [50404][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:05.072 UTC [50407][startup] LOG: database system was shut down at 2024-03-28 05:09:04 UTC 2024-03-28 05:09:05.075 UTC [50404][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:05.160 UTC [50412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35418 2024-03-28 05:09:05.164 UTC [50412][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-03-28 05:09:05.164 UTC [50412][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-03-28 05:09:05.169 UTC [50412][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-03-28 05:09:05.169 UTC [50412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35418 2024-03-28 05:09:05.176 UTC [50414][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35420 2024-03-28 05:09:05.180 UTC [50414][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-03-28 05:09:05.180 UTC [50414][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-03-28 05:09:05.181 UTC [50414][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-03-28 05:09:05.181 UTC [50414][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35420 2024-03-28 05:09:05.188 UTC [50416][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35426 2024-03-28 05:09:05.197 UTC [50418][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35440 2024-03-28 05:09:05.200 UTC [50418][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-03-28 05:09:05.200 UTC [50418][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-03-28 05:09:05.201 UTC [50418][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-03-28 05:09:05.201 UTC [50418][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35440 2024-03-28 05:09:05.208 UTC [50420][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35446 2024-03-28 05:09:05.211 UTC [50420][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-03-28 05:09:05.211 UTC [50420][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-03-28 05:09:05.212 UTC [50420][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-03-28 05:09:05.212 UTC [50420][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35446 2024-03-28 05:09:05.218 UTC [50422][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35460 2024-03-28 05:09:05.222 UTC [50422][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-03-28 05:09:05.222 UTC [50422][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-03-28 05:09:05.222 UTC [50422][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-03-28 05:09:05.223 UTC [50422][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35460 2024-03-28 05:09:05.229 UTC [50424][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35472 2024-03-28 05:09:05.239 UTC [50426][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35474 2024-03-28 05:09:05.245 UTC [50404][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:05.245 UTC [50404][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:05.246 UTC [50404][postmaster] LOG: background worker "logical replication launcher" (PID 50410) exited with exit code 1 2024-03-28 05:09:05.246 UTC [50405][checkpointer] LOG: shutting down 2024-03-28 05:09:05.246 UTC [50405][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:05.246 UTC [50405][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/414C830, redo lsn=0/414C830 2024-03-28 05:09:05.249 UTC [50404][postmaster] LOG: database system is shut down 2024-03-28 05:09:05.361 UTC [50428][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:05.361 UTC [50428][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:05.361 UTC [50428][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:05.363 UTC [50431][startup] LOG: database system was shut down at 2024-03-28 05:09:05 UTC 2024-03-28 05:09:05.366 UTC [50428][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:05.452 UTC [50436][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35490 2024-03-28 05:09:05.455 UTC [50436][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-03-28 05:09:05.455 UTC [50436][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-03-28 05:09:05.460 UTC [50436][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-03-28 05:09:05.460 UTC [50436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35490 2024-03-28 05:09:05.467 UTC [50438][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35506 2024-03-28 05:09:05.470 UTC [50438][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-03-28 05:09:05.470 UTC [50438][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-03-28 05:09:05.471 UTC [50438][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-03-28 05:09:05.472 UTC [50438][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35506 2024-03-28 05:09:05.478 UTC [50440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35508 2024-03-28 05:09:05.486 UTC [50428][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:05.486 UTC [50428][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:05.487 UTC [50428][postmaster] LOG: background worker "logical replication launcher" (PID 50434) exited with exit code 1 2024-03-28 05:09:05.487 UTC [50429][checkpointer] LOG: shutting down 2024-03-28 05:09:05.487 UTC [50429][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:05.487 UTC [50429][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/414C8A8, redo lsn=0/414C8A8 2024-03-28 05:09:05.490 UTC [50428][postmaster] LOG: database system is shut down 2024-03-28 05:09:05.603 UTC [50443][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:05.603 UTC [50443][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:05.603 UTC [50443][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:05.605 UTC [50446][startup] LOG: database system was shut down at 2024-03-28 05:09:05 UTC 2024-03-28 05:09:05.607 UTC [50443][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:05.693 UTC [50451][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35518 2024-03-28 05:09:05.697 UTC [50451][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-03-28 05:09:05.697 UTC [50451][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-03-28 05:09:05.702 UTC [50451][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-03-28 05:09:05.702 UTC [50451][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35518 2024-03-28 05:09:05.709 UTC [50453][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35528 2024-03-28 05:09:05.712 UTC [50453][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-03-28 05:09:05.712 UTC [50453][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-03-28 05:09:05.713 UTC [50453][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-03-28 05:09:05.713 UTC [50453][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35528 2024-03-28 05:09:05.720 UTC [50455][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35530 2024-03-28 05:09:05.723 UTC [50455][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-03-28 05:09:05.723 UTC [50455][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-03-28 05:09:05.724 UTC [50455][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-03-28 05:09:05.724 UTC [50455][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35530 2024-03-28 05:09:05.727 UTC [50443][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:05.727 UTC [50443][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:05.728 UTC [50443][postmaster] LOG: background worker "logical replication launcher" (PID 50449) exited with exit code 1 2024-03-28 05:09:05.728 UTC [50444][checkpointer] LOG: shutting down 2024-03-28 05:09:05.728 UTC [50444][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:05.729 UTC [50444][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/414C920, redo lsn=0/414C920 2024-03-28 05:09:05.731 UTC [50443][postmaster] LOG: database system is shut down 2024-03-28 05:09:05.844 UTC [50457][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:05.844 UTC [50457][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:05.844 UTC [50457][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:05.845 UTC [50460][startup] LOG: database system was shut down at 2024-03-28 05:09:05 UTC 2024-03-28 05:09:05.848 UTC [50457][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:05.934 UTC [50465][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35534 2024-03-28 05:09:05.938 UTC [50465][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-03-28 05:09:05.938 UTC [50465][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-03-28 05:09:05.943 UTC [50465][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-03-28 05:09:05.943 UTC [50465][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35534 2024-03-28 05:09:05.950 UTC [50467][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35540 2024-03-28 05:09:05.953 UTC [50467][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-03-28 05:09:05.953 UTC [50467][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-03-28 05:09:05.954 UTC [50467][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-03-28 05:09:05.954 UTC [50467][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35540 2024-03-28 05:09:05.961 UTC [50469][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35554 2024-03-28 05:09:05.967 UTC [50457][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:05.967 UTC [50457][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:05.968 UTC [50457][postmaster] LOG: background worker "logical replication launcher" (PID 50463) exited with exit code 1 2024-03-28 05:09:05.968 UTC [50458][checkpointer] LOG: shutting down 2024-03-28 05:09:05.968 UTC [50458][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:05.968 UTC [50458][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/414C998, redo lsn=0/414C998 2024-03-28 05:09:05.970 UTC [50457][postmaster] LOG: database system is shut down 2024-03-28 05:09:06.083 UTC [50471][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:06.083 UTC [50471][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:06.083 UTC [50471][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:06.085 UTC [50474][startup] LOG: database system was shut down at 2024-03-28 05:09:05 UTC 2024-03-28 05:09:06.088 UTC [50471][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:06.174 UTC [50479][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35564 2024-03-28 05:09:06.177 UTC [50479][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-03-28 05:09:06.177 UTC [50479][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-03-28 05:09:06.182 UTC [50479][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-03-28 05:09:06.182 UTC [50479][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35564 2024-03-28 05:09:06.190 UTC [50481][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35576 2024-03-28 05:09:06.193 UTC [50481][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-03-28 05:09:06.193 UTC [50481][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-03-28 05:09:06.194 UTC [50481][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-03-28 05:09:06.194 UTC [50481][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35576 2024-03-28 05:09:06.201 UTC [50483][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35582 2024-03-28 05:09:06.204 UTC [50483][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-03-28 05:09:06.204 UTC [50483][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-03-28 05:09:06.205 UTC [50483][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-03-28 05:09:06.205 UTC [50483][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35582 2024-03-28 05:09:06.208 UTC [50471][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:06.208 UTC [50471][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:06.209 UTC [50471][postmaster] LOG: background worker "logical replication launcher" (PID 50477) exited with exit code 1 2024-03-28 05:09:06.209 UTC [50472][checkpointer] LOG: shutting down 2024-03-28 05:09:06.209 UTC [50472][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:06.209 UTC [50472][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/414CA10, redo lsn=0/414CA10 2024-03-28 05:09:06.211 UTC [50471][postmaster] LOG: database system is shut down 2024-03-28 05:09:06.325 UTC [50485][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:06.325 UTC [50485][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:06.325 UTC [50485][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:06.327 UTC [50488][startup] LOG: database system was shut down at 2024-03-28 05:09:06 UTC 2024-03-28 05:09:06.330 UTC [50485][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:06.415 UTC [50493][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35588 2024-03-28 05:09:06.419 UTC [50493][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-03-28 05:09:06.419 UTC [50493][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-03-28 05:09:06.423 UTC [50493][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-03-28 05:09:06.424 UTC [50493][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35588 2024-03-28 05:09:06.431 UTC [50495][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35602 2024-03-28 05:09:06.436 UTC [50485][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:06.436 UTC [50485][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:06.437 UTC [50485][postmaster] LOG: background worker "logical replication launcher" (PID 50491) exited with exit code 1 2024-03-28 05:09:06.437 UTC [50486][checkpointer] LOG: shutting down 2024-03-28 05:09:06.437 UTC [50486][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:06.438 UTC [50486][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/414CA88, redo lsn=0/414CA88 2024-03-28 05:09:06.440 UTC [50485][postmaster] LOG: database system is shut down 2024-03-28 05:09:06.553 UTC [50497][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:06.553 UTC [50497][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:06.553 UTC [50497][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:06.555 UTC [50500][startup] LOG: database system was shut down at 2024-03-28 05:09:06 UTC 2024-03-28 05:09:06.557 UTC [50497][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:06.644 UTC [50505][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35612 2024-03-28 05:09:06.646 UTC [50505][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-28 05:09:06.659 UTC [50508][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35620 2024-03-28 05:09:06.662 UTC [50508][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-03-28 05:09:06.662 UTC [50508][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-03-28 05:09:06.667 UTC [50508][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-03-28 05:09:06.667 UTC [50508][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35620 2024-03-28 05:09:06.673 UTC [50510][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35622 2024-03-28 05:09:06.679 UTC [50497][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:06.679 UTC [50497][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:06.680 UTC [50497][postmaster] LOG: background worker "logical replication launcher" (PID 50503) exited with exit code 1 2024-03-28 05:09:06.680 UTC [50498][checkpointer] LOG: shutting down 2024-03-28 05:09:06.680 UTC [50498][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:06.681 UTC [50498][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/414CB00, redo lsn=0/414CB00 2024-03-28 05:09:06.683 UTC [50497][postmaster] LOG: database system is shut down 2024-03-28 05:09:06.796 UTC [50512][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:06.796 UTC [50512][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:06.796 UTC [50512][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:06.798 UTC [50515][startup] LOG: database system was shut down at 2024-03-28 05:09:06 UTC 2024-03-28 05:09:06.800 UTC [50512][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:06.887 UTC [50520][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35630 2024-03-28 05:09:06.890 UTC [50520][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-03-28 05:09:06.890 UTC [50520][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-03-28 05:09:06.895 UTC [50520][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-03-28 05:09:06.895 UTC [50520][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35630 2024-03-28 05:09:06.903 UTC [50522][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35636 2024-03-28 05:09:06.905 UTC [50522][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-28 05:09:06.912 UTC [50524][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35648 2024-03-28 05:09:06.914 UTC [50524][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-28 05:09:06.921 UTC [50526][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35652 2024-03-28 05:09:06.924 UTC [50526][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-03-28 05:09:06.924 UTC [50526][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-03-28 05:09:06.925 UTC [50526][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-28 05:09:06.927 UTC [50526][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35652 2024-03-28 05:09:06.935 UTC [50528][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35656 2024-03-28 05:09:06.938 UTC [50528][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-03-28 05:09:06.938 UTC [50528][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-03-28 05:09:06.939 UTC [50528][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-03-28 05:09:06.939 UTC [50528][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=35656 2024-03-28 05:09:06.961 UTC [50533][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35670 2024-03-28 05:09:06.965 UTC [50533][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-28 05:09:06.974 UTC [50535][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35678 2024-03-28 05:09:06.979 UTC [50535][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-03-28 05:09:06.979 UTC [50535][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-03-28 05:09:06.983 UTC [50535][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_QZ6E/client.key$$ 2024-03-28 05:09:06.983 UTC [50535][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=35678 2024-03-28 05:09:06.990 UTC [50537][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35692 2024-03-28 05:09:06.995 UTC [50537][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-03-28 05:09:06.995 UTC [50537][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-03-28 05:09:06.995 UTC [50537][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_QZ6E/client-der.key$$ 2024-03-28 05:09:06.996 UTC [50537][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=35692 2024-03-28 05:09:07.002 UTC [50539][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35700 2024-03-28 05:09:07.007 UTC [50539][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-03-28 05:09:07.007 UTC [50539][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-03-28 05:09:07.008 UTC [50539][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_QZ6E/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-28 05:09:07.008 UTC [50539][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=35700 2024-03-28 05:09:07.015 UTC [50541][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35702 2024-03-28 05:09:07.020 UTC [50541][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-03-28 05:09:07.020 UTC [50541][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-03-28 05:09:07.020 UTC [50541][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_QZ6E/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-28 05:09:07.021 UTC [50541][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=35702 2024-03-28 05:09:07.027 UTC [50543][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35706 2024-03-28 05:09:07.032 UTC [50543][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-03-28 05:09:07.032 UTC [50543][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-03-28 05:09:07.033 UTC [50543][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_QZ6E/client.key$$ 2024-03-28 05:09:07.033 UTC [50543][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=35706 2024-03-28 05:09:07.039 UTC [50545][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35722 2024-03-28 05:09:07.044 UTC [50545][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-03-28 05:09:07.044 UTC [50545][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-03-28 05:09:07.045 UTC [50545][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_QZ6E/client.key$$ 2024-03-28 05:09:07.045 UTC [50545][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=35722 2024-03-28 05:09:07.052 UTC [50547][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35732 2024-03-28 05:09:07.055 UTC [50547][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-28 05:09:07.061 UTC [50549][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35744 2024-03-28 05:09:07.063 UTC [50549][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-28 05:09:07.069 UTC [50551][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35754 2024-03-28 05:09:07.073 UTC [50551][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-03-28 05:09:07.073 UTC [50551][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-03-28 05:09:07.078 UTC [50551][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_QZ6E/client-dn.key$$ 2024-03-28 05:09:07.078 UTC [50551][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn host=localhost port=35754 2024-03-28 05:09:07.085 UTC [50553][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35770 2024-03-28 05:09:07.090 UTC [50553][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-03-28 05:09:07.090 UTC [50553][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-03-28 05:09:07.094 UTC [50553][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_QZ6E/client-dn.key$$ 2024-03-28 05:09:07.094 UTC [50553][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn_re host=localhost port=35770 2024-03-28 05:09:07.100 UTC [50555][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35774 2024-03-28 05:09:07.105 UTC [50555][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-03-28 05:09:07.105 UTC [50555][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-03-28 05:09:07.109 UTC [50555][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_QZ6E/client-dn.key$$ 2024-03-28 05:09:07.110 UTC [50555][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_cn host=localhost port=35774 2024-03-28 05:09:07.124 UTC [50558][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35788 2024-03-28 05:09:07.128 UTC [50558][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-03-28 05:09:07.128 UTC [50558][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-03-28 05:09:07.129 UTC [50558][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-28 05:09:07.131 UTC [50558][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=35788 2024-03-28 05:09:07.137 UTC [50560][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35792 2024-03-28 05:09:07.138 UTC [50560][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-28 05:09:07.144 UTC [50562][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35800 2024-03-28 05:09:07.149 UTC [50562][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-03-28 05:09:07.149 UTC [50562][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-28 05:09:07.149 UTC [50562][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-28 05:09:07.149 UTC [50562][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-03-28 05:09:07.156 UTC [50564][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35816 2024-03-28 05:09:07.160 UTC [50564][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-28 05:09:07.160 UTC [50564][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-03-28 05:09:07.167 UTC [50566][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35828 2024-03-28 05:09:07.171 UTC [50566][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-03-28 05:09:07.171 UTC [50566][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-03-28 05:09:07.175 UTC [50566][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_QZ6E/client.key$$ 2024-03-28 05:09:07.175 UTC [50566][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=verifydb host=localhost port=35828 2024-03-28 05:09:07.182 UTC [50568][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35836 2024-03-28 05:09:07.187 UTC [50568][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-28 05:09:07.187 UTC [50568][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-28 05:09:07.187 UTC [50568][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-28 05:09:07.187 UTC [50568][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-03-28 05:09:07.194 UTC [50570][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35846 2024-03-28 05:09:07.198 UTC [50570][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-03-28 05:09:07.198 UTC [50570][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-03-28 05:09:07.199 UTC [50570][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_QZ6E/client.key$$ 2024-03-28 05:09:07.200 UTC [50570][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=35846 2024-03-28 05:09:07.203 UTC [50512][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:07.203 UTC [50512][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:07.204 UTC [50512][postmaster] LOG: background worker "logical replication launcher" (PID 50518) exited with exit code 1 2024-03-28 05:09:07.204 UTC [50513][checkpointer] LOG: shutting down 2024-03-28 05:09:07.204 UTC [50513][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:07.204 UTC [50513][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/414CB78, redo lsn=0/414CB78 2024-03-28 05:09:07.207 UTC [50512][postmaster] LOG: database system is shut down 2024-03-28 05:09:07.319 UTC [50572][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:07.319 UTC [50572][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:07.320 UTC [50572][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:07.321 UTC [50575][startup] LOG: database system was shut down at 2024-03-28 05:09:07 UTC 2024-03-28 05:09:07.324 UTC [50572][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:07.410 UTC [50580][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35856 2024-03-28 05:09:07.415 UTC [50580][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-03-28 05:09:07.415 UTC [50580][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-03-28 05:09:07.420 UTC [50580][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_QZ6E/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-28 05:09:07.420 UTC [50580][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=35856 2024-03-28 05:09:07.427 UTC [50582][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35870 2024-03-28 05:09:07.432 UTC [50582][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-28 05:09:07.432 UTC [50582][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-03-28 05:09:07.438 UTC [50584][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35886 2024-03-28 05:09:07.442 UTC [50584][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-28 05:09:07.442 UTC [50584][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-03-28 05:09:07.445 UTC [50572][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:07.445 UTC [50572][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:07.446 UTC [50572][postmaster] LOG: background worker "logical replication launcher" (PID 50578) exited with exit code 1 2024-03-28 05:09:07.446 UTC [50573][checkpointer] LOG: shutting down 2024-03-28 05:09:07.446 UTC [50573][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:07.447 UTC [50573][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414CBF0, redo lsn=0/414CBF0 2024-03-28 05:09:07.449 UTC [50572][postmaster] LOG: database system is shut down 2024-03-28 05:09:07.562 UTC [50586][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:07.562 UTC [50586][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:07.562 UTC [50586][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:07.564 UTC [50589][startup] LOG: database system was shut down at 2024-03-28 05:09:07 UTC 2024-03-28 05:09:07.567 UTC [50586][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:07.653 UTC [50594][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35898 2024-03-28 05:09:07.657 UTC [50594][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-28 05:09:07.657 UTC [50594][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-03-28 05:09:07.660 UTC [50586][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:07.660 UTC [50586][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:07.661 UTC [50586][postmaster] LOG: background worker "logical replication launcher" (PID 50592) exited with exit code 1 2024-03-28 05:09:07.662 UTC [50587][checkpointer] LOG: shutting down 2024-03-28 05:09:07.662 UTC [50587][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:07.662 UTC [50587][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/414CC68, redo lsn=0/414CC68 2024-03-28 05:09:07.664 UTC [50586][postmaster] LOG: database system is shut down 2024-03-28 05:09:07.778 UTC [50596][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:07.778 UTC [50596][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:07.778 UTC [50596][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:07.780 UTC [50599][startup] LOG: database system was shut down at 2024-03-28 05:09:07 UTC 2024-03-28 05:09:07.782 UTC [50596][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:07.868 UTC [50604][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35914 2024-03-28 05:09:07.872 UTC [50604][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-28 05:09:07.872 UTC [50604][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-03-28 05:09:07.879 UTC [50606][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35930 2024-03-28 05:09:07.883 UTC [50606][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-28 05:09:07.883 UTC [50606][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-03-28 05:09:07.886 UTC [50596][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:07.886 UTC [50596][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:07.887 UTC [50596][postmaster] LOG: background worker "logical replication launcher" (PID 50602) exited with exit code 1 2024-03-28 05:09:07.887 UTC [50597][checkpointer] LOG: shutting down 2024-03-28 05:09:07.887 UTC [50597][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:07.888 UTC [50597][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/414CCE0, redo lsn=0/414CCE0 2024-03-28 05:09:07.890 UTC [50596][postmaster] LOG: database system is shut down 2024-03-28 05:09:08.002 UTC [50608][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:08.003 UTC [50608][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:08.003 UTC [50608][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:08.004 UTC [50611][startup] LOG: database system was shut down at 2024-03-28 05:09:07 UTC 2024-03-28 05:09:08.007 UTC [50608][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:08.094 UTC [50616][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35942 2024-03-28 05:09:08.095 UTC [50616][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:08.095 UTC [50616][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:08.103 UTC [50618][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35952 2024-03-28 05:09:08.107 UTC [50618][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-03-28 05:09:08.107 UTC [50618][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-03-28 05:09:08.111 UTC [50618][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-03-28 05:09:08.112 UTC [50618][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35952 2024-03-28 05:09:08.115 UTC [50608][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:08.115 UTC [50608][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:08.116 UTC [50608][postmaster] LOG: background worker "logical replication launcher" (PID 50614) exited with exit code 1 2024-03-28 05:09:08.116 UTC [50609][checkpointer] LOG: shutting down 2024-03-28 05:09:08.116 UTC [50609][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:08.116 UTC [50609][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/414CD58, redo lsn=0/414CD58 2024-03-28 05:09:08.119 UTC [50608][postmaster] LOG: database system is shut down 2024-03-28 05:09:08.231 UTC [50620][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:08.231 UTC [50620][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:08.232 UTC [50620][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:08.233 UTC [50623][startup] LOG: database system was shut down at 2024-03-28 05:09:08 UTC 2024-03-28 05:09:08.236 UTC [50620][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:08.322 UTC [50628][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35958 2024-03-28 05:09:08.323 UTC [50628][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:08.323 UTC [50628][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:08.330 UTC [50630][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35966 2024-03-28 05:09:08.334 UTC [50630][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-03-28 05:09:08.334 UTC [50630][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-03-28 05:09:08.339 UTC [50630][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-03-28 05:09:08.339 UTC [50630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35966 2024-03-28 05:09:08.342 UTC [50620][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:08.342 UTC [50620][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:08.343 UTC [50620][postmaster] LOG: background worker "logical replication launcher" (PID 50626) exited with exit code 1 2024-03-28 05:09:08.343 UTC [50621][checkpointer] LOG: shutting down 2024-03-28 05:09:08.343 UTC [50621][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:08.343 UTC [50621][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/414CDD0, redo lsn=0/414CDD0 2024-03-28 05:09:08.346 UTC [50620][postmaster] LOG: database system is shut down 2024-03-28 05:09:08.458 UTC [50632][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:08.458 UTC [50632][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:08.458 UTC [50632][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:08.460 UTC [50635][startup] LOG: database system was shut down at 2024-03-28 05:09:08 UTC 2024-03-28 05:09:08.463 UTC [50632][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:08.549 UTC [50640][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35982 2024-03-28 05:09:08.550 UTC [50640][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:08.550 UTC [50640][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:08.557 UTC [50642][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35986 2024-03-28 05:09:08.561 UTC [50642][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-03-28 05:09:08.561 UTC [50642][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-03-28 05:09:08.565 UTC [50642][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-03-28 05:09:08.566 UTC [50642][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35986 2024-03-28 05:09:08.569 UTC [50632][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:08.569 UTC [50632][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:08.570 UTC [50632][postmaster] LOG: background worker "logical replication launcher" (PID 50638) exited with exit code 1 2024-03-28 05:09:08.570 UTC [50633][checkpointer] LOG: shutting down 2024-03-28 05:09:08.570 UTC [50633][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:08.570 UTC [50633][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/414CE48, redo lsn=0/414CE48 2024-03-28 05:09:08.573 UTC [50632][postmaster] LOG: database system is shut down 2024-03-28 05:09:08.686 UTC [50644][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:08.686 UTC [50644][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:08.686 UTC [50644][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:08.688 UTC [50647][startup] LOG: database system was shut down at 2024-03-28 05:09:08 UTC 2024-03-28 05:09:08.691 UTC [50644][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:08.776 UTC [50652][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35996 2024-03-28 05:09:08.778 UTC [50652][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:08.778 UTC [50652][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:08.785 UTC [50654][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36010 2024-03-28 05:09:08.788 UTC [50654][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-03-28 05:09:08.788 UTC [50654][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-03-28 05:09:08.793 UTC [50654][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-03-28 05:09:08.793 UTC [50654][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=36010 2024-03-28 05:09:08.796 UTC [50644][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:08.796 UTC [50644][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:08.797 UTC [50644][postmaster] LOG: background worker "logical replication launcher" (PID 50650) exited with exit code 1 2024-03-28 05:09:08.798 UTC [50645][checkpointer] LOG: shutting down 2024-03-28 05:09:08.798 UTC [50645][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:08.798 UTC [50645][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/414CEC0, redo lsn=0/414CEC0 2024-03-28 05:09:08.800 UTC [50644][postmaster] LOG: database system is shut down 2024-03-28 05:09:08.913 UTC [50656][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:08.913 UTC [50656][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:08.913 UTC [50656][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:08.915 UTC [50659][startup] LOG: database system was shut down at 2024-03-28 05:09:08 UTC 2024-03-28 05:09:08.918 UTC [50656][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:09.004 UTC [50664][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36016 2024-03-28 05:09:09.005 UTC [50664][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:09.005 UTC [50664][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:09.014 UTC [50666][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36018 2024-03-28 05:09:09.017 UTC [50666][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-03-28 05:09:09.017 UTC [50666][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-03-28 05:09:09.022 UTC [50666][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-03-28 05:09:09.022 UTC [50666][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=36018 2024-03-28 05:09:09.025 UTC [50656][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:09.025 UTC [50656][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:09.026 UTC [50656][postmaster] LOG: background worker "logical replication launcher" (PID 50662) exited with exit code 1 2024-03-28 05:09:09.026 UTC [50657][checkpointer] LOG: shutting down 2024-03-28 05:09:09.026 UTC [50657][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:09.027 UTC [50657][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/414CF38, redo lsn=0/414CF38 2024-03-28 05:09:09.029 UTC [50656][postmaster] LOG: database system is shut down 2024-03-28 05:09:09.142 UTC [50668][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:09.142 UTC [50668][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:09.142 UTC [50668][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:09.144 UTC [50671][startup] LOG: database system was shut down at 2024-03-28 05:09:09 UTC 2024-03-28 05:09:09.146 UTC [50668][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:09.233 UTC [50676][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36034 2024-03-28 05:09:09.234 UTC [50676][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:09.234 UTC [50676][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:09.243 UTC [50678][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36042 2024-03-28 05:09:09.246 UTC [50678][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-03-28 05:09:09.246 UTC [50678][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-03-28 05:09:09.251 UTC [50678][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-03-28 05:09:09.251 UTC [50678][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36042 2024-03-28 05:09:09.254 UTC [50668][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:09.254 UTC [50668][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:09.256 UTC [50668][postmaster] LOG: background worker "logical replication launcher" (PID 50674) exited with exit code 1 2024-03-28 05:09:09.256 UTC [50669][checkpointer] LOG: shutting down 2024-03-28 05:09:09.256 UTC [50669][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:09.257 UTC [50669][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/414CFB0, redo lsn=0/414CFB0 2024-03-28 05:09:09.259 UTC [50668][postmaster] LOG: database system is shut down 2024-03-28 05:09:09.371 UTC [50680][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:09.371 UTC [50680][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:09.371 UTC [50680][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:09.373 UTC [50683][startup] LOG: database system was shut down at 2024-03-28 05:09:09 UTC 2024-03-28 05:09:09.376 UTC [50680][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:09.464 UTC [50688][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36056 2024-03-28 05:09:09.465 UTC [50688][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:09.465 UTC [50688][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:09.474 UTC [50690][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36058 2024-03-28 05:09:09.477 UTC [50690][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-03-28 05:09:09.477 UTC [50690][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-03-28 05:09:09.482 UTC [50690][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-03-28 05:09:09.482 UTC [50690][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36058 2024-03-28 05:09:09.485 UTC [50680][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:09.485 UTC [50680][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:09.487 UTC [50680][postmaster] LOG: background worker "logical replication launcher" (PID 50686) exited with exit code 1 2024-03-28 05:09:09.487 UTC [50681][checkpointer] LOG: shutting down 2024-03-28 05:09:09.487 UTC [50681][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:09.487 UTC [50681][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/414D028, redo lsn=0/414D028 2024-03-28 05:09:09.490 UTC [50680][postmaster] LOG: database system is shut down 2024-03-28 05:09:09.602 UTC [50692][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:09.602 UTC [50692][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:09.603 UTC [50692][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:09.604 UTC [50695][startup] LOG: database system was shut down at 2024-03-28 05:09:09 UTC 2024-03-28 05:09:09.607 UTC [50692][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:09.693 UTC [50700][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36060 2024-03-28 05:09:09.694 UTC [50700][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:09.694 UTC [50700][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:09.703 UTC [50702][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36068 2024-03-28 05:09:09.706 UTC [50702][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-03-28 05:09:09.706 UTC [50702][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-03-28 05:09:09.711 UTC [50702][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-03-28 05:09:09.711 UTC [50702][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=36068 2024-03-28 05:09:09.714 UTC [50692][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:09.714 UTC [50692][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:09.716 UTC [50692][postmaster] LOG: background worker "logical replication launcher" (PID 50698) exited with exit code 1 2024-03-28 05:09:09.716 UTC [50693][checkpointer] LOG: shutting down 2024-03-28 05:09:09.716 UTC [50693][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:09.716 UTC [50693][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414D0A0, redo lsn=0/414D0A0 2024-03-28 05:09:09.719 UTC [50692][postmaster] LOG: database system is shut down 2024-03-28 05:09:09.832 UTC [50704][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-28 05:09:09.832 UTC [50704][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54115 2024-03-28 05:09:09.832 UTC [50704][postmaster] LOG: listening on Unix socket "/tmp/RK1X6tiCFB/.s.PGSQL.54115" 2024-03-28 05:09:09.834 UTC [50707][startup] LOG: database system was shut down at 2024-03-28 05:09:09 UTC 2024-03-28 05:09:09.837 UTC [50704][postmaster] LOG: database system is ready to accept connections 2024-03-28 05:09:09.922 UTC [50712][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36072 2024-03-28 05:09:09.924 UTC [50712][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-28 05:09:09.924 UTC [50712][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-28 05:09:09.932 UTC [50714][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36078 2024-03-28 05:09:09.935 UTC [50714][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-28 05:09:09.935 UTC [50714][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-28 05:09:09.940 UTC [50714][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-28 05:09:09.941 UTC [50714][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36078 2024-03-28 05:09:09.944 UTC [50704][postmaster] LOG: received fast shutdown request 2024-03-28 05:09:09.944 UTC [50704][postmaster] LOG: aborting any active transactions 2024-03-28 05:09:09.945 UTC [50704][postmaster] LOG: background worker "logical replication launcher" (PID 50710) exited with exit code 1 2024-03-28 05:09:09.945 UTC [50705][checkpointer] LOG: shutting down 2024-03-28 05:09:09.945 UTC [50705][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-28 05:09:09.946 UTC [50705][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/414D118, redo lsn=0/414D118 2024-03-28 05:09:09.948 UTC [50704][postmaster] LOG: database system is shut down 2024-03-28 05:09:10.056 UTC [50716][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-28 05:09:10.056 UTC [50716][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:09:10.145](0.203s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"