[07:38:47.743](0.026s) # setting up data directory # Checking port 58555 # Found port 58555 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=58555 host=/tmp/1Jsyf0U8vZ Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [07:38:47.748](0.005s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 48119 [07:38:48.019](0.271s) ok 1 - ssl_library parameter ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 48409 [07:38:48.911](0.892s) # 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-12 07:38:47.915 UTC [48119][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:47.915 UTC [48119][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:47.918 UTC [48125][startup] LOG: database system was shut down at 2024-03-12 07:36:19 UTC 2024-03-12 07:38:47.921 UTC [48119][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:48.012 UTC [48162][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.012 UTC [48162][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-12 07:38:48.012 UTC [48162][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.018 UTC [48162][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-12 07:38:48.019 UTC [48162][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.031 UTC [48169][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.032 UTC [48169][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-12 07:38:48.032 UTC [48169][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.033 UTC [48169][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-12 07:38:48.034 UTC [48169][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.080 UTC [48182][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.081 UTC [48182][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-12 07:38:48.081 UTC [48182][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.082 UTC [48182][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-12 07:38:48.083 UTC [48182][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.121 UTC [48194][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.122 UTC [48194][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-12 07:38:48.122 UTC [48194][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.123 UTC [48194][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-12 07:38:48.125 UTC [48194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.167 UTC [48208][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.167 UTC [48208][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-12 07:38:48.167 UTC [48208][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.170 UTC [48208][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-12 07:38:48.171 UTC [48208][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.208 UTC [48226][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.209 UTC [48226][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-12 07:38:48.209 UTC [48226][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.210 UTC [48226][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-12 07:38:48.241 UTC [48226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.251 UTC [48246][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.251 UTC [48246][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-12 07:38:48.251 UTC [48246][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.252 UTC [48246][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-12 07:38:48.312 UTC [48246][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.061 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.375 UTC [48287][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.376 UTC [48287][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-12 07:38:48.376 UTC [48287][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.377 UTC [48287][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-12 07:38:48.409 UTC [48287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.421 UTC [48309][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.421 UTC [48309][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-12 07:38:48.421 UTC [48309][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.422 UTC [48309][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-12 07:38:48.477 UTC [48309][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.548 UTC [48346][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.549 UTC [48346][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-12 07:38:48.549 UTC [48346][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.550 UTC [48346][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-12 07:38:48.574 UTC [48346][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.589 UTC [48354][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.589 UTC [48354][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-12 07:38:48.589 UTC [48354][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.590 UTC [48354][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-12 07:38:48.645 UTC [48354][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.710 UTC [48119][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:48.710 UTC [48119][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:48.713 UTC [48119][postmaster] LOG: background worker "logical replication launcher" (PID 48130) exited with exit code 1 2024-03-12 07:38:48.714 UTC [48123][checkpointer] LOG: shutting down 2024-03-12 07:38:48.714 UTC [48123][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:48.755 UTC [48123][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.042 s, sync=0.001 s, total=0.042 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0 2024-03-12 07:38:48.766 UTC [48119][postmaster] LOG: database system is shut down 2024-03-12 07:38:48.855 UTC [48409][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:48.855 UTC [48409][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:48.856 UTC [48409][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:48.864 UTC [48422][startup] LOG: database system was shut down at 2024-03-12 07:38:48 UTC 2024-03-12 07:38:48.867 UTC [48409][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:48.916 UTC [48409][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:48.916 UTC [48409][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:48.920 UTC [48409][postmaster] LOG: background worker "logical replication launcher" (PID 48428) exited with exit code 1 2024-03-12 07:38:48.920 UTC [48420][checkpointer] LOG: shutting down 2024-03-12 07:38:48.920 UTC [48420][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:48.920 UTC [48420][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/4196268, redo lsn=0/4196268 2024-03-12 07:38:48.923 UTC [48409][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.040 UTC [48464][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-12 07:38:49.040 UTC [48464][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [07:38:49.118](0.206s) 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 48502 [07:38:49.221](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-12 07:38:47.915 UTC [48119][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:47.915 UTC [48119][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:47.918 UTC [48125][startup] LOG: database system was shut down at 2024-03-12 07:36:19 UTC 2024-03-12 07:38:47.921 UTC [48119][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:48.012 UTC [48162][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.012 UTC [48162][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-12 07:38:48.012 UTC [48162][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.018 UTC [48162][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-12 07:38:48.019 UTC [48162][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.031 UTC [48169][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.032 UTC [48169][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-12 07:38:48.032 UTC [48169][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.033 UTC [48169][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-12 07:38:48.034 UTC [48169][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.080 UTC [48182][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.081 UTC [48182][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-12 07:38:48.081 UTC [48182][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.082 UTC [48182][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-12 07:38:48.083 UTC [48182][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.121 UTC [48194][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.122 UTC [48194][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-12 07:38:48.122 UTC [48194][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.123 UTC [48194][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-12 07:38:48.125 UTC [48194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.167 UTC [48208][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.167 UTC [48208][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-12 07:38:48.167 UTC [48208][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.170 UTC [48208][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-12 07:38:48.171 UTC [48208][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.208 UTC [48226][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.209 UTC [48226][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-12 07:38:48.209 UTC [48226][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.210 UTC [48226][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-12 07:38:48.241 UTC [48226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.251 UTC [48246][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.251 UTC [48246][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-12 07:38:48.251 UTC [48246][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.252 UTC [48246][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-12 07:38:48.312 UTC [48246][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.061 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.375 UTC [48287][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.376 UTC [48287][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-12 07:38:48.376 UTC [48287][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.377 UTC [48287][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-12 07:38:48.409 UTC [48287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.421 UTC [48309][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.421 UTC [48309][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-12 07:38:48.421 UTC [48309][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.422 UTC [48309][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-12 07:38:48.477 UTC [48309][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.548 UTC [48346][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.549 UTC [48346][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-12 07:38:48.549 UTC [48346][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.550 UTC [48346][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-12 07:38:48.574 UTC [48346][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.589 UTC [48354][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.589 UTC [48354][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-12 07:38:48.589 UTC [48354][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.590 UTC [48354][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-12 07:38:48.645 UTC [48354][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.710 UTC [48119][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:48.710 UTC [48119][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:48.713 UTC [48119][postmaster] LOG: background worker "logical replication launcher" (PID 48130) exited with exit code 1 2024-03-12 07:38:48.714 UTC [48123][checkpointer] LOG: shutting down 2024-03-12 07:38:48.714 UTC [48123][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:48.755 UTC [48123][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.042 s, sync=0.001 s, total=0.042 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0 2024-03-12 07:38:48.766 UTC [48119][postmaster] LOG: database system is shut down 2024-03-12 07:38:48.855 UTC [48409][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:48.855 UTC [48409][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:48.856 UTC [48409][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:48.864 UTC [48422][startup] LOG: database system was shut down at 2024-03-12 07:38:48 UTC 2024-03-12 07:38:48.867 UTC [48409][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:48.916 UTC [48409][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:48.916 UTC [48409][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:48.920 UTC [48409][postmaster] LOG: background worker "logical replication launcher" (PID 48428) exited with exit code 1 2024-03-12 07:38:48.920 UTC [48420][checkpointer] LOG: shutting down 2024-03-12 07:38:48.920 UTC [48420][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:48.920 UTC [48420][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/4196268, redo lsn=0/4196268 2024-03-12 07:38:48.923 UTC [48409][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.040 UTC [48464][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-12 07:38:49.040 UTC [48464][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.139 UTC [48502][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:49.139 UTC [48502][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:49.139 UTC [48502][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:49.142 UTC [48509][startup] LOG: database system was shut down at 2024-03-12 07:38:48 UTC 2024-03-12 07:38:49.145 UTC [48502][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:49.233 UTC [48502][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:49.233 UTC [48502][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:49.234 UTC [48502][postmaster] LOG: background worker "logical replication launcher" (PID 48512) exited with exit code 1 2024-03-12 07:38:49.236 UTC [48507][checkpointer] LOG: shutting down 2024-03-12 07:38:49.236 UTC [48507][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:49.237 UTC [48507][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/41962E0, redo lsn=0/41962E0 2024-03-12 07:38:49.240 UTC [48502][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.360 UTC [48561][postmaster] FATAL: could not set SSL protocol version range 2024-03-12 07:38:49.360 UTC [48561][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-12 07:38:49.360 UTC [48561][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [07:38:49.434](0.213s) 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 48581 [07:38:49.537](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [07:38:49.538](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 48648 [07:38:49.751](0.214s) ok 6 - server doesn't accept non-SSL connections [07:38:49.752](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [07:38:49.771](0.019s) ok 8 - connect without server root cert sslmode=require [07:38:49.771](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [07:38:49.781](0.010s) ok 10 - connect without server root cert sslmode=verify-ca [07:38:49.781](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [07:38:49.791](0.010s) ok 12 - connect without server root cert sslmode=verify-full [07:38:49.791](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [07:38:49.804](0.013s) ok 14 - connect with wrong server root cert sslmode=require [07:38:49.805](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [07:38:49.819](0.014s) ok 16 - connect with wrong server root cert sslmode=verify-ca [07:38:49.819](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [07:38:49.831](0.012s) ok 18 - connect with wrong server root cert sslmode=verify-full [07:38:49.831](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [07:38:49.847](0.016s) ok 20 - connect with server CA cert, without root CA [07:38:49.847](0.000s) ok 21 - connect with server CA cert, without root CA: matches [07:38:49.864](0.017s) ok 22 - connect with correct server CA cert file sslmode=require [07:38:49.864](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [07:38:49.886](0.021s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [07:38:49.886](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [07:38:49.901](0.015s) ok 26 - connect with correct server CA cert file sslmode=verify-full [07:38:49.901](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [07:38:49.917](0.015s) ok 28 - cert root file that contains two certificates, order 1 [07:38:49.917](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [07:38:49.931](0.014s) ok 30 - cert root file that contains two certificates, order 2 [07:38:49.931](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [07:38:49.946](0.015s) ok 32 - connect with sslcertmode=disable [07:38:49.946](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [07:38:49.960](0.014s) ok 34 - connect with sslcertmode=allow [07:38:49.960](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [07:38:49.977](0.017s) ok 36 - connect with sslcertmode=require fails without a client certificate [07:38:49.977](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [07:38:49.993](0.015s) ok 38 - sslcrl option with invalid file name [07:38:49.993](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [07:38:50.010](0.017s) ok 40 - CRL belonging to a different CA [07:38:50.010](0.000s) ok 41 - CRL belonging to a different CA: matches [07:38:50.023](0.013s) ok 42 - directory CRL belonging to a different CA [07:38:50.023](0.000s) ok 43 - directory CRL belonging to a different CA: matches [07:38:50.039](0.016s) ok 44 - CRL with a non-revoked cert [07:38:50.039](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [07:38:50.052](0.013s) ok 46 - directory CRL with a non-revoked cert [07:38:50.052](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [07:38:50.065](0.013s) ok 48 - mismatch between host name and server certificate sslmode=require [07:38:50.066](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [07:38:50.079](0.013s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [07:38:50.079](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [07:38:50.094](0.015s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [07:38:50.094](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 48791 [07:38:50.317](0.223s) ok 54 - IP address in the Common Name [07:38:50.318](0.000s) ok 55 - IP address in the Common Name: no stderr [07:38:50.335](0.017s) ok 56 - mismatch between host name and server certificate IP address [07:38:50.335](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 48818 [07:38:50.556](0.221s) ok 58 - IP address in a dNSName [07:38:50.557](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 48842 [07:38:50.780](0.223s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [07:38:50.780](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [07:38:50.795](0.015s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [07:38:50.795](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [07:38:50.811](0.016s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [07:38:50.812](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [07:38:50.824](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names [07:38:50.824](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [07:38:50.836](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [07:38:50.837](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 48877 [07:38:51.059](0.222s) ok 70 - host name matching with a single X.509 Subject Alternative Name [07:38:51.059](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [07:38:51.072](0.013s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [07:38:51.073](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [07:38:51.085](0.012s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [07:38:51.085](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 48902 [07:38:51.310](0.225s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [07:38:51.311](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [07:38:51.324](0.013s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [07:38:51.324](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [07:38:51.336](0.011s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [07:38:51.336](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [07:38:51.349](0.013s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [07:38:51.349](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [07:38:51.364](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [07:38:51.364](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [07:38:51.378](0.014s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [07:38:51.378](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [07:38:51.391](0.013s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [07:38:51.391](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [07:38:51.402](0.011s) ok 90 - IPv6 host with CIDR mask does not match [07:38:51.402](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 48939 [07:38:51.625](0.223s) ok 92 - certificate with both a CN and SANs 1 [07:38:51.625](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [07:38:51.641](0.015s) ok 94 - certificate with both a CN and SANs 2 [07:38:51.641](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [07:38:51.654](0.013s) ok 96 - certificate with both a CN and SANs ignores CN [07:38:51.655](0.001s) 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 48954 [07:38:51.882](0.227s) ok 98 - certificate with both a CN and IP SANs matches CN [07:38:51.882](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [07:38:51.897](0.015s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [07:38:51.897](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [07:38:51.911](0.014s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [07:38:51.911](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 48968 [07:38:52.134](0.223s) ok 104 - certificate with both an IP CN and IP SANs 1 [07:38:52.134](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [07:38:52.149](0.015s) ok 106 - certificate with both an IP CN and IP SANs 2 [07:38:52.149](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [07:38:52.161](0.012s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [07:38:52.161](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 48982 [07:38:52.384](0.223s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [07:38:52.384](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [07:38:52.399](0.014s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [07:38:52.399](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [07:38:52.413](0.014s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [07:38:52.413](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 48996 [07:38:52.635](0.222s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [07:38:52.635](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [07:38:52.646](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full [07:38:52.646](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 49008 [07:38:52.861](0.215s) ok 120 - sslrootcert=system does not connect with private CA [07:38:52.862](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [07:38:52.868](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [07:38:52.868](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [07:38:52.885](0.016s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [07:38:52.885](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [07:38:52.896](0.011s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [07:38:52.896](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 49023 [07:38:53.118](0.221s) ok 128 - connects without client-side CRL [07:38:53.118](0.000s) ok 129 - connects without client-side CRL: no stderr [07:38:53.130](0.012s) ok 130 - does not connect with client-side CRL file [07:38:53.130](0.000s) ok 131 - does not connect with client-side CRL file: matches [07:38:53.143](0.013s) ok 132 - does not connect with client-side CRL directory [07:38:53.143](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() [07:38:53.158](0.015s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [07:38:53.158](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [07:38:53.159](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [07:38:53.173](0.014s) ok 137 - connection success with correct range of TLS protocol versions [07:38:53.173](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [07:38:53.180](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [07:38:53.180](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [07:38:53.186](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [07:38:53.186](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [07:38:53.192](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [07:38:53.192](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [07:38:53.192](0.000s) # running server tests [07:38:53.204](0.011s) ok 145 - certificate authorization fails without client cert [07:38:53.204](0.000s) ok 146 - certificate authorization fails without client cert: matches [07:38:53.223](0.019s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [07:38:53.224](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [07:38:53.239](0.015s) ok 149 - certificate authorization succeeds with correct client cert in DER format [07:38:53.239](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [07:38:53.255](0.016s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [07:38:53.255](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [07:38:53.271](0.016s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [07:38:53.271](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [07:38:53.286](0.015s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [07:38:53.286](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [07:38:53.300](0.014s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [07:38:53.300](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [07:38:53.311](0.011s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [07:38:53.311](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [07:38:53.320](0.009s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [07:38:53.320](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [07:38:53.338](0.018s) ok 163 - certificate authorization succeeds with DN mapping [07:38:53.339](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [07:38:53.339](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [07:38:53.357](0.018s) ok 166 - certificate authorization succeeds with DN regex mapping [07:38:53.358](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [07:38:53.377](0.020s) ok 168 - certificate authorization succeeds with CN mapping [07:38:53.377](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [07:38:53.378](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [07:38:53.378](0.000s) not ok 171 # TODO & SKIP Need Pty support [07:38:53.378](0.000s) not ok 172 # TODO & SKIP Need Pty support [07:38:53.378](0.000s) not ok 173 # TODO & SKIP Need Pty support [07:38:53.378](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_4mzn/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [07:38:53.406](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [07:38:53.407](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [07:38:53.407](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [07:38:53.417](0.011s) ok 178 - certificate authorization fails because of file permissions [07:38:53.418](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [07:38:53.433](0.016s) ok 180 - certificate authorization fails with client cert belonging to another user [07:38:53.434](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [07:38:53.449](0.015s) ok 182 - certificate authorization fails with revoked client cert [07:38:53.449](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [07:38:53.449](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [07:38:53.470](0.021s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [07:38:53.470](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [07:38:53.471](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [07:38:53.485](0.014s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [07:38:53.485](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [07:38:53.485](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [07:38:53.502](0.016s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [07:38:53.502](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [07:38:53.502](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 49083 [07:38:53.727](0.225s) ok 194 - intermediate client certificate is provided by client [07:38:53.727](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [07:38:53.743](0.016s) ok 196 - intermediate client certificate is missing [07:38:53.743](0.000s) ok 197 - intermediate client certificate is missing: matches [07:38:53.758](0.015s) ok 198 - logged client certificate Subjects are truncated if they're too long [07:38:53.759](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 49097 [07:38:53.978](0.219s) ok 200 - intermediate client certificate is untrusted [07:38:53.978](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 49107 [07:38:54.197](0.218s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [07:38:54.197](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [07:38:54.212](0.015s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [07:38:54.212](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 49119 [07:38:54.428](0.216s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [07:38:54.429](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. [07:38:54.429](0.000s) # got: '2' # expected: '0' [07:38:54.429](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [07:38:54.429](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. [07:38:54.429](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 58555 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: '' [07:38:54.448](0.018s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [07:38:54.448](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 49131 [07:38:54.664](0.216s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [07:38:54.682](0.018s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [07:38:54.682](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 49143 [07:38:54.898](0.216s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [07:38:54.917](0.019s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [07:38:54.917](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 49155 [07:38:55.133](0.216s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [07:38:55.151](0.018s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [07:38:55.151](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 49167 [07:38:55.367](0.217s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [07:38:55.368](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. [07:38:55.368](0.000s) # got: '2' # expected: '0' [07:38:55.368](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [07:38:55.368](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. [07:38:55.368](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 58555 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: '' [07:38:55.388](0.020s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [07:38:55.389](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 49179 [07:38:55.605](0.217s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [07:38:55.624](0.019s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [07:38:55.624](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 49191 [07:38:55.840](0.216s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [07:38:55.859](0.019s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [07:38:55.860](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 49203 [07:38:56.076](0.216s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [07:38:56.095](0.019s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [07:38:56.095](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 49215 [07:38:56.311](0.216s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [07:38:56.330](0.019s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [07:38:56.330](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-12 07:38:47.915 UTC [48119][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:47.915 UTC [48119][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:47.918 UTC [48125][startup] LOG: database system was shut down at 2024-03-12 07:36:19 UTC 2024-03-12 07:38:47.921 UTC [48119][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:48.012 UTC [48162][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.012 UTC [48162][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-12 07:38:48.012 UTC [48162][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.018 UTC [48162][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-12 07:38:48.019 UTC [48162][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.031 UTC [48169][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.032 UTC [48169][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-12 07:38:48.032 UTC [48169][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.033 UTC [48169][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-12 07:38:48.034 UTC [48169][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.080 UTC [48182][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.081 UTC [48182][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-12 07:38:48.081 UTC [48182][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.082 UTC [48182][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-12 07:38:48.083 UTC [48182][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.121 UTC [48194][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.122 UTC [48194][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-12 07:38:48.122 UTC [48194][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.123 UTC [48194][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-12 07:38:48.125 UTC [48194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.167 UTC [48208][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.167 UTC [48208][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-12 07:38:48.167 UTC [48208][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.170 UTC [48208][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-12 07:38:48.171 UTC [48208][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.208 UTC [48226][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.209 UTC [48226][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-12 07:38:48.209 UTC [48226][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.210 UTC [48226][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-12 07:38:48.241 UTC [48226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.251 UTC [48246][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.251 UTC [48246][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-12 07:38:48.251 UTC [48246][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.252 UTC [48246][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-12 07:38:48.312 UTC [48246][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.061 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.375 UTC [48287][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.376 UTC [48287][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-12 07:38:48.376 UTC [48287][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.377 UTC [48287][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-12 07:38:48.409 UTC [48287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.421 UTC [48309][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.421 UTC [48309][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-12 07:38:48.421 UTC [48309][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.422 UTC [48309][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-12 07:38:48.477 UTC [48309][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.548 UTC [48346][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.549 UTC [48346][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-12 07:38:48.549 UTC [48346][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.550 UTC [48346][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-12 07:38:48.574 UTC [48346][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.589 UTC [48354][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:38:48.589 UTC [48354][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-12 07:38:48.589 UTC [48354][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-12 07:38:48.590 UTC [48354][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-12 07:38:48.645 UTC [48354][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-12 07:38:48.710 UTC [48119][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:48.710 UTC [48119][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:48.713 UTC [48119][postmaster] LOG: background worker "logical replication launcher" (PID 48130) exited with exit code 1 2024-03-12 07:38:48.714 UTC [48123][checkpointer] LOG: shutting down 2024-03-12 07:38:48.714 UTC [48123][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:48.755 UTC [48123][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.042 s, sync=0.001 s, total=0.042 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0 2024-03-12 07:38:48.766 UTC [48119][postmaster] LOG: database system is shut down 2024-03-12 07:38:48.855 UTC [48409][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:48.855 UTC [48409][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:48.856 UTC [48409][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:48.864 UTC [48422][startup] LOG: database system was shut down at 2024-03-12 07:38:48 UTC 2024-03-12 07:38:48.867 UTC [48409][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:48.916 UTC [48409][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:48.916 UTC [48409][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:48.920 UTC [48409][postmaster] LOG: background worker "logical replication launcher" (PID 48428) exited with exit code 1 2024-03-12 07:38:48.920 UTC [48420][checkpointer] LOG: shutting down 2024-03-12 07:38:48.920 UTC [48420][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:48.920 UTC [48420][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/4196268, redo lsn=0/4196268 2024-03-12 07:38:48.923 UTC [48409][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.040 UTC [48464][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-12 07:38:49.040 UTC [48464][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.139 UTC [48502][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:49.139 UTC [48502][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:49.139 UTC [48502][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:49.142 UTC [48509][startup] LOG: database system was shut down at 2024-03-12 07:38:48 UTC 2024-03-12 07:38:49.145 UTC [48502][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:49.233 UTC [48502][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:49.233 UTC [48502][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:49.234 UTC [48502][postmaster] LOG: background worker "logical replication launcher" (PID 48512) exited with exit code 1 2024-03-12 07:38:49.236 UTC [48507][checkpointer] LOG: shutting down 2024-03-12 07:38:49.236 UTC [48507][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:49.237 UTC [48507][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/41962E0, redo lsn=0/41962E0 2024-03-12 07:38:49.240 UTC [48502][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.360 UTC [48561][postmaster] FATAL: could not set SSL protocol version range 2024-03-12 07:38:49.360 UTC [48561][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-12 07:38:49.360 UTC [48561][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.455 UTC [48581][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:49.455 UTC [48581][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:49.455 UTC [48581][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:49.458 UTC [48590][startup] LOG: database system was shut down at 2024-03-12 07:38:49 UTC 2024-03-12 07:38:49.462 UTC [48581][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:49.540 UTC [48581][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:49.540 UTC [48581][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:49.542 UTC [48581][postmaster] LOG: background worker "logical replication launcher" (PID 48596) exited with exit code 1 2024-03-12 07:38:49.542 UTC [48588][checkpointer] LOG: shutting down 2024-03-12 07:38:49.542 UTC [48588][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:49.543 UTC [48588][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/4196358, redo lsn=0/4196358 2024-03-12 07:38:49.546 UTC [48581][postmaster] LOG: database system is shut down 2024-03-12 07:38:49.661 UTC [48648][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:49.661 UTC [48648][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:49.661 UTC [48648][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:49.668 UTC [48655][startup] LOG: database system was shut down at 2024-03-12 07:38:49 UTC 2024-03-12 07:38:49.671 UTC [48648][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:49.749 UTC [48671][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35586 2024-03-12 07:38:49.750 UTC [48671][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-12 07:38:49.750 UTC [48671][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-12 07:38:49.760 UTC [48679][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35596 2024-03-12 07:38:49.763 UTC [48679][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-12 07:38:49.763 UTC [48679][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-12 07:38:49.768 UTC [48679][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-12 07:38:49.769 UTC [48679][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35596 2024-03-12 07:38:49.779 UTC [48683][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35608 2024-03-12 07:38:49.780 UTC [48683][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:38:49.789 UTC [48687][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35624 2024-03-12 07:38:49.790 UTC [48687][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:38:49.800 UTC [48692][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35638 2024-03-12 07:38:49.806 UTC [48692][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:49.813 UTC [48702][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35640 2024-03-12 07:38:49.817 UTC [48702][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:49.826 UTC [48706][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35648 2024-03-12 07:38:49.829 UTC [48706][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:49.842 UTC [48711][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35654 2024-03-12 07:38:49.848 UTC [48711][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:49.857 UTC [48715][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35660 2024-03-12 07:38:49.861 UTC [48715][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-12 07:38:49.861 UTC [48715][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-12 07:38:49.862 UTC [48715][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-12 07:38:49.863 UTC [48715][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35660 2024-03-12 07:38:49.876 UTC [48721][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35664 2024-03-12 07:38:49.881 UTC [48721][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-12 07:38:49.881 UTC [48721][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-12 07:38:49.882 UTC [48721][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-12 07:38:49.883 UTC [48721][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35664 2024-03-12 07:38:49.893 UTC [48727][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35666 2024-03-12 07:38:49.898 UTC [48727][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-12 07:38:49.898 UTC [48727][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-12 07:38:49.899 UTC [48727][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-12 07:38:49.899 UTC [48727][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35666 2024-03-12 07:38:49.909 UTC [48730][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35676 2024-03-12 07:38:49.913 UTC [48730][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-12 07:38:49.913 UTC [48730][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-12 07:38:49.914 UTC [48730][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-12 07:38:49.915 UTC [48730][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35676 2024-03-12 07:38:49.924 UTC [48733][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35692 2024-03-12 07:38:49.928 UTC [48733][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-12 07:38:49.928 UTC [48733][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-12 07:38:49.929 UTC [48733][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-12 07:38:49.930 UTC [48733][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35692 2024-03-12 07:38:49.938 UTC [48737][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35694 2024-03-12 07:38:49.942 UTC [48737][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-12 07:38:49.942 UTC [48737][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-12 07:38:49.944 UTC [48737][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-12 07:38:49.945 UTC [48737][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35694 2024-03-12 07:38:49.953 UTC [48742][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35702 2024-03-12 07:38:49.957 UTC [48742][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-12 07:38:49.957 UTC [48742][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-12 07:38:49.958 UTC [48742][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-12 07:38:49.958 UTC [48742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35702 2024-03-12 07:38:49.969 UTC [48746][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35716 2024-03-12 07:38:49.974 UTC [48746][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-12 07:38:49.974 UTC [48746][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-12 07:38:49.976 UTC [48746][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35716 2024-03-12 07:38:49.986 UTC [48751][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35720 2024-03-12 07:38:49.990 UTC [48751][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-12 07:38:49.990 UTC [48751][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-12 07:38:49.991 UTC [48751][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-12 07:38:49.994 UTC [48751][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35720 2024-03-12 07:38:50.005 UTC [48761][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35732 2024-03-12 07:38:50.009 UTC [48761][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:50.019 UTC [48765][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35746 2024-03-12 07:38:50.024 UTC [48765][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:50.032 UTC [48768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35758 2024-03-12 07:38:50.036 UTC [48768][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-12 07:38:50.036 UTC [48768][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-12 07:38:50.037 UTC [48768][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-12 07:38:50.038 UTC [48768][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35758 2024-03-12 07:38:50.046 UTC [48772][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35772 2024-03-12 07:38:50.050 UTC [48772][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-12 07:38:50.050 UTC [48772][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-12 07:38:50.051 UTC [48772][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-12 07:38:50.051 UTC [48772][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35772 2024-03-12 07:38:50.059 UTC [48775][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35780 2024-03-12 07:38:50.063 UTC [48775][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-12 07:38:50.063 UTC [48775][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-12 07:38:50.064 UTC [48775][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-12 07:38:50.064 UTC [48775][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35780 2024-03-12 07:38:50.072 UTC [48779][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35794 2024-03-12 07:38:50.076 UTC [48779][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-12 07:38:50.076 UTC [48779][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-12 07:38:50.077 UTC [48779][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-12 07:38:50.080 UTC [48779][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35794 2024-03-12 07:38:50.086 UTC [48784][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35796 2024-03-12 07:38:50.097 UTC [48648][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:50.097 UTC [48648][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:50.098 UTC [48648][postmaster] LOG: background worker "logical replication launcher" (PID 48659) exited with exit code 1 2024-03-12 07:38:50.099 UTC [48653][checkpointer] LOG: shutting down 2024-03-12 07:38:50.099 UTC [48653][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:50.100 UTC [48653][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/41963D0, redo lsn=0/41963D0 2024-03-12 07:38:50.102 UTC [48648][postmaster] LOG: database system is shut down 2024-03-12 07:38:50.215 UTC [48791][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:50.215 UTC [48791][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:50.215 UTC [48791][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:50.218 UTC [48795][startup] LOG: database system was shut down at 2024-03-12 07:38:50 UTC 2024-03-12 07:38:50.221 UTC [48791][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:50.306 UTC [48808][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35806 2024-03-12 07:38:50.310 UTC [48808][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-12 07:38:50.310 UTC [48808][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-12 07:38:50.315 UTC [48808][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-12 07:38:50.316 UTC [48808][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35806 2024-03-12 07:38:50.326 UTC [48812][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35822 2024-03-12 07:38:50.338 UTC [48791][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:50.338 UTC [48791][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:50.341 UTC [48791][postmaster] LOG: background worker "logical replication launcher" (PID 48798) exited with exit code 1 2024-03-12 07:38:50.341 UTC [48793][checkpointer] LOG: shutting down 2024-03-12 07:38:50.341 UTC [48793][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:50.342 UTC [48793][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/4196448, redo lsn=0/4196448 2024-03-12 07:38:50.345 UTC [48791][postmaster] LOG: database system is shut down 2024-03-12 07:38:50.455 UTC [48818][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:50.455 UTC [48818][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:50.455 UTC [48818][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:50.457 UTC [48827][startup] LOG: database system was shut down at 2024-03-12 07:38:50 UTC 2024-03-12 07:38:50.460 UTC [48818][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:50.545 UTC [48835][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35832 2024-03-12 07:38:50.550 UTC [48835][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-12 07:38:50.550 UTC [48835][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-12 07:38:50.554 UTC [48835][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-12 07:38:50.555 UTC [48835][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35832 2024-03-12 07:38:50.559 UTC [48818][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:50.559 UTC [48818][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:50.561 UTC [48818][postmaster] LOG: background worker "logical replication launcher" (PID 48830) exited with exit code 1 2024-03-12 07:38:50.561 UTC [48825][checkpointer] LOG: shutting down 2024-03-12 07:38:50.561 UTC [48825][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:50.561 UTC [48825][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/41964C0, redo lsn=0/41964C0 2024-03-12 07:38:50.564 UTC [48818][postmaster] LOG: database system is shut down 2024-03-12 07:38:50.678 UTC [48842][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:50.678 UTC [48842][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:50.678 UTC [48842][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:50.680 UTC [48851][startup] LOG: database system was shut down at 2024-03-12 07:38:50 UTC 2024-03-12 07:38:50.683 UTC [48842][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:50.768 UTC [48858][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35840 2024-03-12 07:38:50.773 UTC [48858][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-12 07:38:50.773 UTC [48858][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-12 07:38:50.778 UTC [48858][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-12 07:38:50.778 UTC [48858][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35840 2024-03-12 07:38:50.788 UTC [48861][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35852 2024-03-12 07:38:50.792 UTC [48861][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-12 07:38:50.792 UTC [48861][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-12 07:38:50.793 UTC [48861][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-12 07:38:50.794 UTC [48861][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35852 2024-03-12 07:38:50.803 UTC [48863][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35866 2024-03-12 07:38:50.807 UTC [48863][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-12 07:38:50.807 UTC [48863][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-12 07:38:50.808 UTC [48863][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-12 07:38:50.809 UTC [48863][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35866 2024-03-12 07:38:50.819 UTC [48866][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35868 2024-03-12 07:38:50.832 UTC [48868][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35870 2024-03-12 07:38:50.839 UTC [48842][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:50.839 UTC [48842][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:50.841 UTC [48842][postmaster] LOG: background worker "logical replication launcher" (PID 48854) exited with exit code 1 2024-03-12 07:38:50.841 UTC [48849][checkpointer] LOG: shutting down 2024-03-12 07:38:50.841 UTC [48849][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:50.841 UTC [48849][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/4196538, redo lsn=0/4196538 2024-03-12 07:38:50.844 UTC [48842][postmaster] LOG: database system is shut down 2024-03-12 07:38:50.958 UTC [48877][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:50.958 UTC [48877][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:50.959 UTC [48877][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:50.961 UTC [48880][startup] LOG: database system was shut down at 2024-03-12 07:38:50 UTC 2024-03-12 07:38:50.964 UTC [48877][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:51.048 UTC [48888][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35876 2024-03-12 07:38:51.052 UTC [48888][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-12 07:38:51.052 UTC [48888][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-12 07:38:51.057 UTC [48888][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-12 07:38:51.058 UTC [48888][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35876 2024-03-12 07:38:51.067 UTC [48890][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35892 2024-03-12 07:38:51.080 UTC [48892][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35904 2024-03-12 07:38:51.091 UTC [48877][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:51.091 UTC [48877][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:51.093 UTC [48877][postmaster] LOG: background worker "logical replication launcher" (PID 48883) exited with exit code 1 2024-03-12 07:38:51.093 UTC [48878][checkpointer] LOG: shutting down 2024-03-12 07:38:51.093 UTC [48878][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:51.093 UTC [48878][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/41965B0, redo lsn=0/41965B0 2024-03-12 07:38:51.096 UTC [48877][postmaster] LOG: database system is shut down 2024-03-12 07:38:51.211 UTC [48902][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:51.211 UTC [48902][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:51.211 UTC [48902][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:51.214 UTC [48906][startup] LOG: database system was shut down at 2024-03-12 07:38:51 UTC 2024-03-12 07:38:51.217 UTC [48902][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:51.300 UTC [48913][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35910 2024-03-12 07:38:51.304 UTC [48913][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-12 07:38:51.304 UTC [48913][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-12 07:38:51.309 UTC [48913][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-12 07:38:51.309 UTC [48913][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35910 2024-03-12 07:38:51.317 UTC [48915][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35912 2024-03-12 07:38:51.321 UTC [48915][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-12 07:38:51.321 UTC [48915][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-12 07:38:51.322 UTC [48915][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-12 07:38:51.323 UTC [48915][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35912 2024-03-12 07:38:51.331 UTC [48917][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35920 2024-03-12 07:38:51.342 UTC [48920][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35922 2024-03-12 07:38:51.346 UTC [48920][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-12 07:38:51.346 UTC [48920][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-12 07:38:51.347 UTC [48920][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-12 07:38:51.348 UTC [48920][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35922 2024-03-12 07:38:51.356 UTC [48922][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35924 2024-03-12 07:38:51.360 UTC [48922][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-12 07:38:51.360 UTC [48922][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-12 07:38:51.361 UTC [48922][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-12 07:38:51.362 UTC [48922][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35924 2024-03-12 07:38:51.371 UTC [48930][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35928 2024-03-12 07:38:51.375 UTC [48930][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-12 07:38:51.375 UTC [48930][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-12 07:38:51.376 UTC [48930][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-12 07:38:51.377 UTC [48930][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35928 2024-03-12 07:38:51.385 UTC [48932][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35942 2024-03-12 07:38:51.398 UTC [48934][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35944 2024-03-12 07:38:51.404 UTC [48902][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:51.404 UTC [48902][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:51.405 UTC [48902][postmaster] LOG: background worker "logical replication launcher" (PID 48909) exited with exit code 1 2024-03-12 07:38:51.406 UTC [48903][checkpointer] LOG: shutting down 2024-03-12 07:38:51.406 UTC [48903][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:51.406 UTC [48903][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/4196628, redo lsn=0/4196628 2024-03-12 07:38:51.409 UTC [48902][postmaster] LOG: database system is shut down 2024-03-12 07:38:51.523 UTC [48939][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:51.523 UTC [48939][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:51.523 UTC [48939][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:51.526 UTC [48942][startup] LOG: database system was shut down at 2024-03-12 07:38:51 UTC 2024-03-12 07:38:51.529 UTC [48939][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:51.614 UTC [48947][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35954 2024-03-12 07:38:51.618 UTC [48947][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-12 07:38:51.618 UTC [48947][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-12 07:38:51.623 UTC [48947][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-12 07:38:51.623 UTC [48947][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35954 2024-03-12 07:38:51.633 UTC [48949][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35962 2024-03-12 07:38:51.637 UTC [48949][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-12 07:38:51.637 UTC [48949][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-12 07:38:51.638 UTC [48949][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-12 07:38:51.639 UTC [48949][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35962 2024-03-12 07:38:51.648 UTC [48951][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35964 2024-03-12 07:38:51.662 UTC [48939][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:51.662 UTC [48939][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:51.664 UTC [48939][postmaster] LOG: background worker "logical replication launcher" (PID 48945) exited with exit code 1 2024-03-12 07:38:51.664 UTC [48940][checkpointer] LOG: shutting down 2024-03-12 07:38:51.664 UTC [48940][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:51.665 UTC [48940][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/41966A0, redo lsn=0/41966A0 2024-03-12 07:38:51.668 UTC [48939][postmaster] LOG: database system is shut down 2024-03-12 07:38:51.780 UTC [48954][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:51.780 UTC [48954][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:51.780 UTC [48954][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:51.783 UTC [48957][startup] LOG: database system was shut down at 2024-03-12 07:38:51 UTC 2024-03-12 07:38:51.785 UTC [48954][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:51.871 UTC [48962][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35972 2024-03-12 07:38:51.875 UTC [48962][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-12 07:38:51.875 UTC [48962][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-12 07:38:51.880 UTC [48962][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-12 07:38:51.881 UTC [48962][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35972 2024-03-12 07:38:51.890 UTC [48964][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35974 2024-03-12 07:38:51.894 UTC [48964][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-12 07:38:51.894 UTC [48964][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-12 07:38:51.895 UTC [48964][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-12 07:38:51.895 UTC [48964][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35974 2024-03-12 07:38:51.904 UTC [48966][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35988 2024-03-12 07:38:51.908 UTC [48966][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-12 07:38:51.908 UTC [48966][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-12 07:38:51.909 UTC [48966][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-12 07:38:51.910 UTC [48966][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35988 2024-03-12 07:38:51.913 UTC [48954][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:51.913 UTC [48954][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:51.915 UTC [48954][postmaster] LOG: background worker "logical replication launcher" (PID 48960) exited with exit code 1 2024-03-12 07:38:51.915 UTC [48955][checkpointer] LOG: shutting down 2024-03-12 07:38:51.915 UTC [48955][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:51.916 UTC [48955][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/4196718, redo lsn=0/4196718 2024-03-12 07:38:51.919 UTC [48954][postmaster] LOG: database system is shut down 2024-03-12 07:38:52.032 UTC [48968][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:52.032 UTC [48968][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:52.032 UTC [48968][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:52.034 UTC [48971][startup] LOG: database system was shut down at 2024-03-12 07:38:51 UTC 2024-03-12 07:38:52.037 UTC [48968][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:52.122 UTC [48976][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36004 2024-03-12 07:38:52.127 UTC [48976][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-12 07:38:52.127 UTC [48976][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-12 07:38:52.132 UTC [48976][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-12 07:38:52.132 UTC [48976][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=36004 2024-03-12 07:38:52.142 UTC [48978][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36008 2024-03-12 07:38:52.146 UTC [48978][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-12 07:38:52.146 UTC [48978][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-12 07:38:52.147 UTC [48978][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-12 07:38:52.147 UTC [48978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36008 2024-03-12 07:38:52.157 UTC [48980][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36018 2024-03-12 07:38:52.163 UTC [48968][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:52.163 UTC [48968][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:52.165 UTC [48968][postmaster] LOG: background worker "logical replication launcher" (PID 48974) exited with exit code 1 2024-03-12 07:38:52.165 UTC [48969][checkpointer] LOG: shutting down 2024-03-12 07:38:52.166 UTC [48969][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:52.166 UTC [48969][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/4196790, redo lsn=0/4196790 2024-03-12 07:38:52.169 UTC [48968][postmaster] LOG: database system is shut down 2024-03-12 07:38:52.282 UTC [48982][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:52.282 UTC [48982][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:52.282 UTC [48982][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:52.284 UTC [48985][startup] LOG: database system was shut down at 2024-03-12 07:38:52 UTC 2024-03-12 07:38:52.287 UTC [48982][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:52.372 UTC [48990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36020 2024-03-12 07:38:52.377 UTC [48990][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-12 07:38:52.377 UTC [48990][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-12 07:38:52.382 UTC [48990][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-12 07:38:52.383 UTC [48990][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=36020 2024-03-12 07:38:52.392 UTC [48992][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36036 2024-03-12 07:38:52.396 UTC [48992][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-12 07:38:52.396 UTC [48992][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-12 07:38:52.397 UTC [48992][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-12 07:38:52.397 UTC [48992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36036 2024-03-12 07:38:52.406 UTC [48994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36044 2024-03-12 07:38:52.410 UTC [48994][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-12 07:38:52.410 UTC [48994][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-12 07:38:52.411 UTC [48994][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-12 07:38:52.411 UTC [48994][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=36044 2024-03-12 07:38:52.415 UTC [48982][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:52.415 UTC [48982][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:52.416 UTC [48982][postmaster] LOG: background worker "logical replication launcher" (PID 48988) exited with exit code 1 2024-03-12 07:38:52.417 UTC [48983][checkpointer] LOG: shutting down 2024-03-12 07:38:52.417 UTC [48983][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:52.417 UTC [48983][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/4196808, redo lsn=0/4196808 2024-03-12 07:38:52.420 UTC [48982][postmaster] LOG: database system is shut down 2024-03-12 07:38:52.534 UTC [48996][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:52.534 UTC [48996][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:52.534 UTC [48996][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:52.537 UTC [48999][startup] LOG: database system was shut down at 2024-03-12 07:38:52 UTC 2024-03-12 07:38:52.540 UTC [48996][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:52.624 UTC [49004][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36048 2024-03-12 07:38:52.628 UTC [49004][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-12 07:38:52.628 UTC [49004][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-12 07:38:52.633 UTC [49004][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-12 07:38:52.634 UTC [49004][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36048 2024-03-12 07:38:52.642 UTC [49006][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36062 2024-03-12 07:38:52.648 UTC [48996][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:52.648 UTC [48996][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:52.649 UTC [48996][postmaster] LOG: background worker "logical replication launcher" (PID 49002) exited with exit code 1 2024-03-12 07:38:52.649 UTC [48997][checkpointer] LOG: shutting down 2024-03-12 07:38:52.649 UTC [48997][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:52.650 UTC [48997][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/4196880, redo lsn=0/4196880 2024-03-12 07:38:52.652 UTC [48996][postmaster] LOG: database system is shut down 2024-03-12 07:38:52.767 UTC [49008][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:52.767 UTC [49008][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:52.767 UTC [49008][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:52.769 UTC [49011][startup] LOG: database system was shut down at 2024-03-12 07:38:52 UTC 2024-03-12 07:38:52.772 UTC [49008][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:52.857 UTC [49016][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36066 2024-03-12 07:38:52.860 UTC [49016][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:38:52.874 UTC [49019][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36080 2024-03-12 07:38:52.878 UTC [49019][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-12 07:38:52.878 UTC [49019][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-12 07:38:52.883 UTC [49019][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-12 07:38:52.883 UTC [49019][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36080 2024-03-12 07:38:52.892 UTC [49021][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36082 2024-03-12 07:38:52.898 UTC [49008][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:52.898 UTC [49008][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:52.900 UTC [49008][postmaster] LOG: background worker "logical replication launcher" (PID 49014) exited with exit code 1 2024-03-12 07:38:52.900 UTC [49009][checkpointer] LOG: shutting down 2024-03-12 07:38:52.900 UTC [49009][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:52.900 UTC [49009][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/41968F8, redo lsn=0/41968F8 2024-03-12 07:38:52.903 UTC [49008][postmaster] LOG: database system is shut down 2024-03-12 07:38:53.017 UTC [49023][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:53.017 UTC [49023][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:53.017 UTC [49023][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:53.019 UTC [49026][startup] LOG: database system was shut down at 2024-03-12 07:38:52 UTC 2024-03-12 07:38:53.022 UTC [49023][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:53.107 UTC [49031][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36084 2024-03-12 07:38:53.111 UTC [49031][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-12 07:38:53.111 UTC [49031][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-12 07:38:53.116 UTC [49031][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-12 07:38:53.117 UTC [49031][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36084 2024-03-12 07:38:53.125 UTC [49033][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36100 2024-03-12 07:38:53.128 UTC [49033][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-12 07:38:53.138 UTC [49035][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36104 2024-03-12 07:38:53.142 UTC [49035][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-12 07:38:53.151 UTC [49037][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36110 2024-03-12 07:38:53.155 UTC [49037][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-12 07:38:53.155 UTC [49037][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-12 07:38:53.156 UTC [49037][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-12 07:38:53.157 UTC [49037][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36110 2024-03-12 07:38:53.166 UTC [49039][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36118 2024-03-12 07:38:53.170 UTC [49039][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-12 07:38:53.170 UTC [49039][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-12 07:38:53.171 UTC [49039][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-12 07:38:53.171 UTC [49039][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=36118 2024-03-12 07:38:53.198 UTC [49044][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36130 2024-03-12 07:38:53.202 UTC [49044][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-12 07:38:53.211 UTC [49046][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36134 2024-03-12 07:38:53.217 UTC [49046][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-12 07:38:53.217 UTC [49046][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-12 07:38:53.222 UTC [49046][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_4mzn/client.key$$ 2024-03-12 07:38:53.222 UTC [49046][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=36134 2024-03-12 07:38:53.231 UTC [49048][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36136 2024-03-12 07:38:53.237 UTC [49048][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-12 07:38:53.237 UTC [49048][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-12 07:38:53.238 UTC [49048][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_4mzn/client-der.key$$ 2024-03-12 07:38:53.238 UTC [49048][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=36136 2024-03-12 07:38:53.246 UTC [49050][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36148 2024-03-12 07:38:53.252 UTC [49050][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-12 07:38:53.252 UTC [49050][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-12 07:38:53.253 UTC [49050][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_4mzn/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-12 07:38:53.254 UTC [49050][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=36148 2024-03-12 07:38:53.262 UTC [49052][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36164 2024-03-12 07:38:53.268 UTC [49052][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-12 07:38:53.268 UTC [49052][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-12 07:38:53.269 UTC [49052][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_4mzn/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-12 07:38:53.270 UTC [49052][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=36164 2024-03-12 07:38:53.278 UTC [49054][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36166 2024-03-12 07:38:53.284 UTC [49054][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-12 07:38:53.284 UTC [49054][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-12 07:38:53.285 UTC [49054][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_4mzn/client.key$$ 2024-03-12 07:38:53.285 UTC [49054][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=36166 2024-03-12 07:38:53.292 UTC [49056][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36174 2024-03-12 07:38:53.298 UTC [49056][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-12 07:38:53.298 UTC [49056][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-12 07:38:53.299 UTC [49056][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_4mzn/client.key$$ 2024-03-12 07:38:53.299 UTC [49056][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=36174 2024-03-12 07:38:53.306 UTC [49058][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36188 2024-03-12 07:38:53.310 UTC [49058][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-12 07:38:53.317 UTC [49060][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36202 2024-03-12 07:38:53.319 UTC [49060][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:38:53.326 UTC [49062][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36214 2024-03-12 07:38:53.332 UTC [49062][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-12 07:38:53.332 UTC [49062][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-12 07:38:53.337 UTC [49062][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_4mzn/client-dn.key$$ 2024-03-12 07:38:53.337 UTC [49062][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=36214 2024-03-12 07:38:53.346 UTC [49064][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36230 2024-03-12 07:38:53.351 UTC [49064][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-12 07:38:53.351 UTC [49064][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-12 07:38:53.356 UTC [49064][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_4mzn/client-dn.key$$ 2024-03-12 07:38:53.356 UTC [49064][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=36230 2024-03-12 07:38:53.364 UTC [49066][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36244 2024-03-12 07:38:53.370 UTC [49066][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-12 07:38:53.370 UTC [49066][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-12 07:38:53.375 UTC [49066][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_4mzn/client-dn.key$$ 2024-03-12 07:38:53.376 UTC [49066][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_cn host=localhost port=36244 2024-03-12 07:38:53.397 UTC [49069][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36248 2024-03-12 07:38:53.402 UTC [49069][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-12 07:38:53.403 UTC [49069][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-12 07:38:53.404 UTC [49069][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-12 07:38:53.405 UTC [49069][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=36248 2024-03-12 07:38:53.415 UTC [49071][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36254 2024-03-12 07:38:53.416 UTC [49071][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:38:53.426 UTC [49073][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36270 2024-03-12 07:38:53.431 UTC [49073][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-12 07:38:53.431 UTC [49073][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-12 07:38:53.431 UTC [49073][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-12 07:38:53.431 UTC [49073][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-12 07:38:53.442 UTC [49075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36274 2024-03-12 07:38:53.447 UTC [49075][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:38:53.447 UTC [49075][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-12 07:38:53.457 UTC [49077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36282 2024-03-12 07:38:53.463 UTC [49077][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-12 07:38:53.463 UTC [49077][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-12 07:38:53.468 UTC [49077][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_4mzn/client.key$$ 2024-03-12 07:38:53.469 UTC [49077][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=verifydb host=localhost port=36282 2024-03-12 07:38:53.478 UTC [49079][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36292 2024-03-12 07:38:53.483 UTC [49079][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-12 07:38:53.483 UTC [49079][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-12 07:38:53.483 UTC [49079][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-12 07:38:53.483 UTC [49079][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-12 07:38:53.493 UTC [49081][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36308 2024-03-12 07:38:53.498 UTC [49081][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-12 07:38:53.498 UTC [49081][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-12 07:38:53.500 UTC [49081][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_4mzn/client.key$$ 2024-03-12 07:38:53.500 UTC [49081][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=36308 2024-03-12 07:38:53.505 UTC [49023][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:53.505 UTC [49023][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:53.506 UTC [49023][postmaster] LOG: background worker "logical replication launcher" (PID 49029) exited with exit code 1 2024-03-12 07:38:53.506 UTC [49024][checkpointer] LOG: shutting down 2024-03-12 07:38:53.506 UTC [49024][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:53.507 UTC [49024][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/4196970, redo lsn=0/4196970 2024-03-12 07:38:53.510 UTC [49023][postmaster] LOG: database system is shut down 2024-03-12 07:38:53.624 UTC [49083][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:53.624 UTC [49083][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:53.624 UTC [49083][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:53.627 UTC [49086][startup] LOG: database system was shut down at 2024-03-12 07:38:53 UTC 2024-03-12 07:38:53.630 UTC [49083][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:53.714 UTC [49091][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36312 2024-03-12 07:38:53.720 UTC [49091][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-12 07:38:53.720 UTC [49091][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-12 07:38:53.725 UTC [49091][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_4mzn/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-12 07:38:53.725 UTC [49091][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=36312 2024-03-12 07:38:53.736 UTC [49093][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36324 2024-03-12 07:38:53.741 UTC [49093][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:38:53.741 UTC [49093][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-12 07:38:53.752 UTC [49095][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36334 2024-03-12 07:38:53.757 UTC [49095][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:38:53.757 UTC [49095][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-12 07:38:53.761 UTC [49083][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:53.761 UTC [49083][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:53.763 UTC [49083][postmaster] LOG: background worker "logical replication launcher" (PID 49089) exited with exit code 1 2024-03-12 07:38:53.763 UTC [49084][checkpointer] LOG: shutting down 2024-03-12 07:38:53.763 UTC [49084][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:53.764 UTC [49084][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/41969E8, redo lsn=0/41969E8 2024-03-12 07:38:53.767 UTC [49083][postmaster] LOG: database system is shut down 2024-03-12 07:38:53.880 UTC [49097][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:53.880 UTC [49097][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:53.880 UTC [49097][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:53.883 UTC [49100][startup] LOG: database system was shut down at 2024-03-12 07:38:53 UTC 2024-03-12 07:38:53.886 UTC [49097][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:53.971 UTC [49105][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36350 2024-03-12 07:38:53.976 UTC [49105][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:38:53.976 UTC [49105][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-12 07:38:53.981 UTC [49097][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:53.981 UTC [49097][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:53.982 UTC [49097][postmaster] LOG: background worker "logical replication launcher" (PID 49103) exited with exit code 1 2024-03-12 07:38:53.982 UTC [49098][checkpointer] LOG: shutting down 2024-03-12 07:38:53.982 UTC [49098][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:53.983 UTC [49098][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/4196A60, redo lsn=0/4196A60 2024-03-12 07:38:53.986 UTC [49097][postmaster] LOG: database system is shut down 2024-03-12 07:38:54.100 UTC [49107][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:54.100 UTC [49107][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:54.100 UTC [49107][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:54.102 UTC [49110][startup] LOG: database system was shut down at 2024-03-12 07:38:53 UTC 2024-03-12 07:38:54.106 UTC [49107][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:54.190 UTC [49115][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36360 2024-03-12 07:38:54.195 UTC [49115][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:38:54.195 UTC [49115][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-12 07:38:54.204 UTC [49117][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36364 2024-03-12 07:38:54.210 UTC [49117][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:38:54.210 UTC [49117][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-12 07:38:54.215 UTC [49107][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:54.215 UTC [49107][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:54.216 UTC [49107][postmaster] LOG: background worker "logical replication launcher" (PID 49113) exited with exit code 1 2024-03-12 07:38:54.216 UTC [49108][checkpointer] LOG: shutting down 2024-03-12 07:38:54.216 UTC [49108][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:54.217 UTC [49108][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/4196AD8, redo lsn=0/4196AD8 2024-03-12 07:38:54.220 UTC [49107][postmaster] LOG: database system is shut down 2024-03-12 07:38:54.334 UTC [49119][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:54.334 UTC [49119][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:54.334 UTC [49119][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:54.337 UTC [49122][startup] LOG: database system was shut down at 2024-03-12 07:38:54 UTC 2024-03-12 07:38:54.341 UTC [49119][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:54.424 UTC [49127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36380 2024-03-12 07:38:54.425 UTC [49127][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:54.425 UTC [49127][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:54.437 UTC [49129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36396 2024-03-12 07:38:54.441 UTC [49129][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-12 07:38:54.441 UTC [49129][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-12 07:38:54.446 UTC [49129][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-12 07:38:54.446 UTC [49129][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=36396 2024-03-12 07:38:54.450 UTC [49119][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:54.450 UTC [49119][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:54.452 UTC [49119][postmaster] LOG: background worker "logical replication launcher" (PID 49125) exited with exit code 1 2024-03-12 07:38:54.452 UTC [49120][checkpointer] LOG: shutting down 2024-03-12 07:38:54.452 UTC [49120][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:54.453 UTC [49120][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/4196B50, redo lsn=0/4196B50 2024-03-12 07:38:54.455 UTC [49119][postmaster] LOG: database system is shut down 2024-03-12 07:38:54.569 UTC [49131][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:54.570 UTC [49131][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:54.570 UTC [49131][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:54.572 UTC [49134][startup] LOG: database system was shut down at 2024-03-12 07:38:54 UTC 2024-03-12 07:38:54.575 UTC [49131][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:54.660 UTC [49139][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36404 2024-03-12 07:38:54.661 UTC [49139][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:54.661 UTC [49139][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:54.671 UTC [49141][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36416 2024-03-12 07:38:54.675 UTC [49141][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-12 07:38:54.675 UTC [49141][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-12 07:38:54.680 UTC [49141][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-12 07:38:54.680 UTC [49141][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=36416 2024-03-12 07:38:54.684 UTC [49131][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:54.684 UTC [49131][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:54.686 UTC [49131][postmaster] LOG: background worker "logical replication launcher" (PID 49137) exited with exit code 1 2024-03-12 07:38:54.686 UTC [49132][checkpointer] LOG: shutting down 2024-03-12 07:38:54.688 UTC [49132][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:54.688 UTC [49132][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196BC8, redo lsn=0/4196BC8 2024-03-12 07:38:54.692 UTC [49131][postmaster] LOG: database system is shut down 2024-03-12 07:38:54.803 UTC [49143][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:54.804 UTC [49143][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:54.804 UTC [49143][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:54.806 UTC [49146][startup] LOG: database system was shut down at 2024-03-12 07:38:54 UTC 2024-03-12 07:38:54.809 UTC [49143][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:54.894 UTC [49151][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55624 2024-03-12 07:38:54.895 UTC [49151][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:54.895 UTC [49151][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:54.905 UTC [49153][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55630 2024-03-12 07:38:54.910 UTC [49153][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-12 07:38:54.910 UTC [49153][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-12 07:38:54.915 UTC [49153][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-12 07:38:54.915 UTC [49153][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=55630 2024-03-12 07:38:54.920 UTC [49143][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:54.920 UTC [49143][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:54.921 UTC [49143][postmaster] LOG: background worker "logical replication launcher" (PID 49149) exited with exit code 1 2024-03-12 07:38:54.921 UTC [49144][checkpointer] LOG: shutting down 2024-03-12 07:38:54.921 UTC [49144][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:54.922 UTC [49144][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/4196C40, redo lsn=0/4196C40 2024-03-12 07:38:54.925 UTC [49143][postmaster] LOG: database system is shut down 2024-03-12 07:38:55.039 UTC [49155][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:55.039 UTC [49155][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:55.039 UTC [49155][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:55.041 UTC [49158][startup] LOG: database system was shut down at 2024-03-12 07:38:54 UTC 2024-03-12 07:38:55.044 UTC [49155][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:55.129 UTC [49163][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55642 2024-03-12 07:38:55.130 UTC [49163][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:55.130 UTC [49163][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:55.140 UTC [49165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55652 2024-03-12 07:38:55.144 UTC [49165][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-12 07:38:55.144 UTC [49165][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-12 07:38:55.149 UTC [49165][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-12 07:38:55.149 UTC [49165][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=55652 2024-03-12 07:38:55.153 UTC [49155][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:55.153 UTC [49155][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:55.155 UTC [49155][postmaster] LOG: background worker "logical replication launcher" (PID 49161) exited with exit code 1 2024-03-12 07:38:55.155 UTC [49156][checkpointer] LOG: shutting down 2024-03-12 07:38:55.155 UTC [49156][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:55.156 UTC [49156][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/4196CB8, redo lsn=0/4196CB8 2024-03-12 07:38:55.159 UTC [49155][postmaster] LOG: database system is shut down 2024-03-12 07:38:55.272 UTC [49167][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:55.272 UTC [49167][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:55.272 UTC [49167][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:55.274 UTC [49170][startup] LOG: database system was shut down at 2024-03-12 07:38:55 UTC 2024-03-12 07:38:55.278 UTC [49167][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:55.363 UTC [49175][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55656 2024-03-12 07:38:55.364 UTC [49175][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:55.365 UTC [49175][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:55.377 UTC [49177][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55668 2024-03-12 07:38:55.381 UTC [49177][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-12 07:38:55.381 UTC [49177][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-12 07:38:55.386 UTC [49177][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-12 07:38:55.387 UTC [49177][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=55668 2024-03-12 07:38:55.391 UTC [49167][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:55.391 UTC [49167][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:55.393 UTC [49167][postmaster] LOG: background worker "logical replication launcher" (PID 49173) exited with exit code 1 2024-03-12 07:38:55.393 UTC [49168][checkpointer] LOG: shutting down 2024-03-12 07:38:55.393 UTC [49168][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:55.394 UTC [49168][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/4196D30, redo lsn=0/4196D30 2024-03-12 07:38:55.397 UTC [49167][postmaster] LOG: database system is shut down 2024-03-12 07:38:55.511 UTC [49179][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:55.511 UTC [49179][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:55.511 UTC [49179][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:55.513 UTC [49182][startup] LOG: database system was shut down at 2024-03-12 07:38:55 UTC 2024-03-12 07:38:55.517 UTC [49179][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:55.601 UTC [49187][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55670 2024-03-12 07:38:55.602 UTC [49187][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:55.602 UTC [49187][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:55.612 UTC [49189][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55684 2024-03-12 07:38:55.617 UTC [49189][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-12 07:38:55.617 UTC [49189][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-12 07:38:55.622 UTC [49189][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-12 07:38:55.622 UTC [49189][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=55684 2024-03-12 07:38:55.627 UTC [49179][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:55.627 UTC [49179][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:55.629 UTC [49179][postmaster] LOG: background worker "logical replication launcher" (PID 49185) exited with exit code 1 2024-03-12 07:38:55.629 UTC [49180][checkpointer] LOG: shutting down 2024-03-12 07:38:55.629 UTC [49180][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:55.630 UTC [49180][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/4196DA8, redo lsn=0/4196DA8 2024-03-12 07:38:55.633 UTC [49179][postmaster] LOG: database system is shut down 2024-03-12 07:38:55.746 UTC [49191][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:55.746 UTC [49191][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:55.746 UTC [49191][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:55.748 UTC [49194][startup] LOG: database system was shut down at 2024-03-12 07:38:55 UTC 2024-03-12 07:38:55.751 UTC [49191][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:55.837 UTC [49199][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55700 2024-03-12 07:38:55.838 UTC [49199][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:55.838 UTC [49199][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:55.848 UTC [49201][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55708 2024-03-12 07:38:55.852 UTC [49201][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-12 07:38:55.852 UTC [49201][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-12 07:38:55.857 UTC [49201][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-12 07:38:55.858 UTC [49201][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=55708 2024-03-12 07:38:55.862 UTC [49191][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:55.862 UTC [49191][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:55.864 UTC [49191][postmaster] LOG: background worker "logical replication launcher" (PID 49197) exited with exit code 1 2024-03-12 07:38:55.864 UTC [49192][checkpointer] LOG: shutting down 2024-03-12 07:38:55.864 UTC [49192][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:55.865 UTC [49192][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/4196E20, redo lsn=0/4196E20 2024-03-12 07:38:55.868 UTC [49191][postmaster] LOG: database system is shut down 2024-03-12 07:38:55.982 UTC [49203][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:55.982 UTC [49203][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:55.982 UTC [49203][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:55.984 UTC [49206][startup] LOG: database system was shut down at 2024-03-12 07:38:55 UTC 2024-03-12 07:38:55.987 UTC [49203][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:56.072 UTC [49211][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55718 2024-03-12 07:38:56.073 UTC [49211][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:56.073 UTC [49211][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:56.083 UTC [49213][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55734 2024-03-12 07:38:56.087 UTC [49213][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-12 07:38:56.087 UTC [49213][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-12 07:38:56.093 UTC [49213][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-12 07:38:56.093 UTC [49213][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=55734 2024-03-12 07:38:56.098 UTC [49203][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:56.098 UTC [49203][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:56.099 UTC [49203][postmaster] LOG: background worker "logical replication launcher" (PID 49209) exited with exit code 1 2024-03-12 07:38:56.099 UTC [49204][checkpointer] LOG: shutting down 2024-03-12 07:38:56.099 UTC [49204][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:56.100 UTC [49204][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/4196E98, redo lsn=0/4196E98 2024-03-12 07:38:56.103 UTC [49203][postmaster] LOG: database system is shut down 2024-03-12 07:38:56.216 UTC [49215][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-12 07:38:56.216 UTC [49215][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58555 2024-03-12 07:38:56.216 UTC [49215][postmaster] LOG: listening on Unix socket "/tmp/1Jsyf0U8vZ/.s.PGSQL.58555" 2024-03-12 07:38:56.218 UTC [49218][startup] LOG: database system was shut down at 2024-03-12 07:38:56 UTC 2024-03-12 07:38:56.222 UTC [49215][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:38:56.307 UTC [49223][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55746 2024-03-12 07:38:56.308 UTC [49223][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:38:56.308 UTC [49223][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:38:56.318 UTC [49225][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55750 2024-03-12 07:38:56.323 UTC [49225][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-12 07:38:56.323 UTC [49225][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-12 07:38:56.328 UTC [49225][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-12 07:38:56.328 UTC [49225][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=55750 2024-03-12 07:38:56.333 UTC [49215][postmaster] LOG: received fast shutdown request 2024-03-12 07:38:56.333 UTC [49215][postmaster] LOG: aborting any active transactions 2024-03-12 07:38:56.334 UTC [49215][postmaster] LOG: background worker "logical replication launcher" (PID 49221) exited with exit code 1 2024-03-12 07:38:56.335 UTC [49216][checkpointer] LOG: shutting down 2024-03-12 07:38:56.335 UTC [49216][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:38:56.335 UTC [49216][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/4196F10, redo lsn=0/4196F10 2024-03-12 07:38:56.339 UTC [49215][postmaster] LOG: database system is shut down 2024-03-12 07:38:56.447 UTC [49227][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-12 07:38:56.447 UTC [49227][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [07:38:56.535](0.204s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"