[15:20:04.443](0.031s) # setting up data directory # Checking port 50342 # Found port 50342 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=50342 host=/tmp/4ZrvsD_Snl Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [15:20:04.463](0.020s) # 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 49249 [15:20:04.717](0.254s) 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 49550 [15:20:05.710](0.993s) # 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-20 15:20:04.592 UTC [49249][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:04.592 UTC [49249][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:04.594 UTC [49253][startup] LOG: database system was shut down at 2024-03-20 15:17:40 UTC 2024-03-20 15:20:04.596 UTC [49249][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:04.701 UTC [49297][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.701 UTC [49297][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-20 15:20:04.701 UTC [49297][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.715 UTC [49297][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-20 15:20:04.715 UTC [49297][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.738 UTC [49307][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.739 UTC [49307][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-20 15:20:04.739 UTC [49307][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.745 UTC [49307][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-20 15:20:04.746 UTC [49307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.782 UTC [49326][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.783 UTC [49326][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-20 15:20:04.783 UTC [49326][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.784 UTC [49326][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-20 15:20:04.784 UTC [49326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.853 UTC [49361][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.854 UTC [49361][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-20 15:20:04.854 UTC [49361][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.855 UTC [49361][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-20 15:20:04.855 UTC [49361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.882 UTC [49367][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.882 UTC [49367][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-20 15:20:04.882 UTC [49367][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.883 UTC [49367][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-20 15:20:04.884 UTC [49367][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.924 UTC [49380][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.924 UTC [49380][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-20 15:20:04.924 UTC [49380][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.925 UTC [49380][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-20 15:20:04.954 UTC [49380][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.967 UTC [49392][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.967 UTC [49392][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-20 15:20:04.967 UTC [49392][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.968 UTC [49392][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-20 15:20:05.019 UTC [49392][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.095 UTC [49427][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.096 UTC [49427][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-20 15:20:05.096 UTC [49427][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.097 UTC [49427][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-20 15:20:05.122 UTC [49427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.142 UTC [49432][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.142 UTC [49432][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-20 15:20:05.142 UTC [49432][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.143 UTC [49432][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-20 15:20:05.194 UTC [49432][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.290 UTC [49467][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.290 UTC [49467][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-20 15:20:05.290 UTC [49467][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.291 UTC [49467][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-20 15:20:05.318 UTC [49467][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.387 UTC [49500][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.388 UTC [49500][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-20 15:20:05.388 UTC [49500][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.389 UTC [49500][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-20 15:20:05.428 UTC [49500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.508 UTC [49249][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:05.509 UTC [49249][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:05.510 UTC [49249][postmaster] LOG: background worker "logical replication launcher" (PID 49256) exited with exit code 1 2024-03-20 15:20:05.511 UTC [49251][checkpointer] LOG: shutting down 2024-03-20 15:20:05.511 UTC [49251][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:05.546 UTC [49251][checkpointer] LOG: checkpoint complete: wrote 5644 buffers (34.4%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.034 s, sync=0.001 s, total=0.035 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45431 kB, estimate=45431 kB; lsn=0/4187898, redo lsn=0/4187898 2024-03-20 15:20:05.556 UTC [49249][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:05.631 UTC [49558][startup] LOG: database system was shut down at 2024-03-20 15:20:05 UTC 2024-03-20 15:20:05.635 UTC [49550][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:05.723 UTC [49550][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:05.723 UTC [49550][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:05.724 UTC [49550][postmaster] LOG: background worker "logical replication launcher" (PID 49563) exited with exit code 1 2024-03-20 15:20:05.725 UTC [49556][checkpointer] LOG: shutting down 2024-03-20 15:20:05.725 UTC [49556][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:05.740 UTC [49556][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.016 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4187910, redo lsn=0/4187910 2024-03-20 15:20:05.743 UTC [49550][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.836 UTC [49598][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-20 15:20:05.837 UTC [49598][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [15:20:05.924](0.214s) 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 49622 [15:20:06.027](0.104s) 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-20 15:20:04.592 UTC [49249][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:04.592 UTC [49249][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:04.594 UTC [49253][startup] LOG: database system was shut down at 2024-03-20 15:17:40 UTC 2024-03-20 15:20:04.596 UTC [49249][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:04.701 UTC [49297][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.701 UTC [49297][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-20 15:20:04.701 UTC [49297][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.715 UTC [49297][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-20 15:20:04.715 UTC [49297][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.738 UTC [49307][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.739 UTC [49307][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-20 15:20:04.739 UTC [49307][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.745 UTC [49307][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-20 15:20:04.746 UTC [49307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.782 UTC [49326][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.783 UTC [49326][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-20 15:20:04.783 UTC [49326][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.784 UTC [49326][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-20 15:20:04.784 UTC [49326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.853 UTC [49361][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.854 UTC [49361][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-20 15:20:04.854 UTC [49361][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.855 UTC [49361][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-20 15:20:04.855 UTC [49361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.882 UTC [49367][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.882 UTC [49367][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-20 15:20:04.882 UTC [49367][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.883 UTC [49367][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-20 15:20:04.884 UTC [49367][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.924 UTC [49380][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.924 UTC [49380][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-20 15:20:04.924 UTC [49380][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.925 UTC [49380][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-20 15:20:04.954 UTC [49380][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.967 UTC [49392][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.967 UTC [49392][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-20 15:20:04.967 UTC [49392][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.968 UTC [49392][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-20 15:20:05.019 UTC [49392][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.095 UTC [49427][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.096 UTC [49427][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-20 15:20:05.096 UTC [49427][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.097 UTC [49427][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-20 15:20:05.122 UTC [49427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.142 UTC [49432][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.142 UTC [49432][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-20 15:20:05.142 UTC [49432][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.143 UTC [49432][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-20 15:20:05.194 UTC [49432][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.290 UTC [49467][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.290 UTC [49467][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-20 15:20:05.290 UTC [49467][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.291 UTC [49467][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-20 15:20:05.318 UTC [49467][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.387 UTC [49500][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.388 UTC [49500][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-20 15:20:05.388 UTC [49500][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.389 UTC [49500][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-20 15:20:05.428 UTC [49500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.508 UTC [49249][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:05.509 UTC [49249][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:05.510 UTC [49249][postmaster] LOG: background worker "logical replication launcher" (PID 49256) exited with exit code 1 2024-03-20 15:20:05.511 UTC [49251][checkpointer] LOG: shutting down 2024-03-20 15:20:05.511 UTC [49251][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:05.546 UTC [49251][checkpointer] LOG: checkpoint complete: wrote 5644 buffers (34.4%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.034 s, sync=0.001 s, total=0.035 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45431 kB, estimate=45431 kB; lsn=0/4187898, redo lsn=0/4187898 2024-03-20 15:20:05.556 UTC [49249][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:05.631 UTC [49558][startup] LOG: database system was shut down at 2024-03-20 15:20:05 UTC 2024-03-20 15:20:05.635 UTC [49550][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:05.723 UTC [49550][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:05.723 UTC [49550][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:05.724 UTC [49550][postmaster] LOG: background worker "logical replication launcher" (PID 49563) exited with exit code 1 2024-03-20 15:20:05.725 UTC [49556][checkpointer] LOG: shutting down 2024-03-20 15:20:05.725 UTC [49556][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:05.740 UTC [49556][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.016 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4187910, redo lsn=0/4187910 2024-03-20 15:20:05.743 UTC [49550][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.836 UTC [49598][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-20 15:20:05.837 UTC [49598][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.946 UTC [49622][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:05.946 UTC [49622][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:05.946 UTC [49622][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:05.948 UTC [49630][startup] LOG: database system was shut down at 2024-03-20 15:20:05 UTC 2024-03-20 15:20:05.952 UTC [49622][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:06.030 UTC [49622][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:06.030 UTC [49622][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:06.032 UTC [49622][postmaster] LOG: background worker "logical replication launcher" (PID 49633) exited with exit code 1 2024-03-20 15:20:06.033 UTC [49628][checkpointer] LOG: shutting down 2024-03-20 15:20:06.033 UTC [49628][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:06.033 UTC [49628][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/4187988, redo lsn=0/4187988 2024-03-20 15:20:06.036 UTC [49622][postmaster] LOG: database system is shut down 2024-03-20 15:20:06.145 UTC [49664][postmaster] FATAL: could not set SSL protocol version range 2024-03-20 15:20:06.145 UTC [49664][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-20 15:20:06.145 UTC [49664][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [15:20:06.233](0.205s) 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 49680 [15:20:06.336](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [15:20:06.336](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 49727 [15:20:06.551](0.215s) ok 6 - server doesn't accept non-SSL connections [15:20:06.551](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [15:20:06.570](0.018s) ok 8 - connect without server root cert sslmode=require [15:20:06.570](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [15:20:06.581](0.011s) ok 10 - connect without server root cert sslmode=verify-ca [15:20:06.581](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [15:20:06.590](0.009s) ok 12 - connect without server root cert sslmode=verify-full [15:20:06.590](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [15:20:06.605](0.015s) ok 14 - connect with wrong server root cert sslmode=require [15:20:06.607](0.002s) ok 15 - connect with wrong server root cert sslmode=require: matches [15:20:06.620](0.013s) ok 16 - connect with wrong server root cert sslmode=verify-ca [15:20:06.620](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [15:20:06.633](0.012s) ok 18 - connect with wrong server root cert sslmode=verify-full [15:20:06.633](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [15:20:06.644](0.011s) ok 20 - connect with server CA cert, without root CA [15:20:06.644](0.000s) ok 21 - connect with server CA cert, without root CA: matches [15:20:06.658](0.014s) ok 22 - connect with correct server CA cert file sslmode=require [15:20:06.658](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [15:20:06.673](0.014s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [15:20:06.673](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [15:20:06.687](0.014s) ok 26 - connect with correct server CA cert file sslmode=verify-full [15:20:06.687](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [15:20:06.703](0.016s) ok 28 - cert root file that contains two certificates, order 1 [15:20:06.703](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [15:20:06.719](0.015s) ok 30 - cert root file that contains two certificates, order 2 [15:20:06.719](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [15:20:06.735](0.016s) ok 32 - connect with sslcertmode=disable [15:20:06.735](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [15:20:06.751](0.016s) ok 34 - connect with sslcertmode=allow [15:20:06.751](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [15:20:06.765](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [15:20:06.765](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [15:20:06.778](0.013s) ok 38 - sslcrl option with invalid file name [15:20:06.779](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [15:20:06.790](0.011s) ok 40 - CRL belonging to a different CA [15:20:06.790](0.000s) ok 41 - CRL belonging to a different CA: matches [15:20:06.803](0.013s) ok 42 - directory CRL belonging to a different CA [15:20:06.804](0.000s) ok 43 - directory CRL belonging to a different CA: matches [15:20:06.819](0.015s) ok 44 - CRL with a non-revoked cert [15:20:06.819](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [15:20:06.834](0.015s) ok 46 - directory CRL with a non-revoked cert [15:20:06.834](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [15:20:06.850](0.015s) ok 48 - mismatch between host name and server certificate sslmode=require [15:20:06.850](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [15:20:06.865](0.015s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [15:20:06.865](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [15:20:06.878](0.013s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [15:20:06.878](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 49868 [15:20:07.100](0.222s) ok 54 - IP address in the Common Name [15:20:07.100](0.000s) ok 55 - IP address in the Common Name: no stderr [15:20:07.113](0.012s) ok 56 - mismatch between host name and server certificate IP address [15:20:07.113](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 49893 [15:20:07.335](0.222s) ok 58 - IP address in a dNSName [15:20:07.336](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 49915 [15:20:07.558](0.222s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [15:20:07.558](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [15:20:07.573](0.015s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [15:20:07.574](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [15:20:07.588](0.015s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [15:20:07.589](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [15:20:07.602](0.013s) ok 66 - host name not matching with X.509 Subject Alternative Names [15:20:07.602](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [15:20:07.614](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [15:20:07.614](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 49951 [15:20:07.837](0.223s) ok 70 - host name matching with a single X.509 Subject Alternative Name [15:20:07.837](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [15:20:07.851](0.014s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [15:20:07.851](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [15:20:07.864](0.013s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [15:20:07.864](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 49978 [15:20:08.090](0.226s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [15:20:08.091](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [15:20:08.106](0.015s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [15:20:08.106](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [15:20:08.118](0.012s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [15:20:08.118](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [15:20:08.133](0.015s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [15:20:08.133](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [15:20:08.149](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [15:20:08.149](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [15:20:08.163](0.014s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [15:20:08.163](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [15:20:08.176](0.012s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [15:20:08.176](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [15:20:08.187](0.011s) ok 90 - IPv6 host with CIDR mask does not match [15:20:08.187](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 50002 [15:20:08.410](0.223s) ok 92 - certificate with both a CN and SANs 1 [15:20:08.410](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [15:20:08.424](0.014s) ok 94 - certificate with both a CN and SANs 2 [15:20:08.425](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [15:20:08.437](0.013s) ok 96 - certificate with both a CN and SANs ignores CN [15:20:08.438](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50017 [15:20:08.664](0.227s) ok 98 - certificate with both a CN and IP SANs matches CN [15:20:08.664](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [15:20:08.679](0.015s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [15:20:08.679](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [15:20:08.694](0.014s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [15:20:08.694](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 50031 [15:20:08.916](0.222s) ok 104 - certificate with both an IP CN and IP SANs 1 [15:20:08.916](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [15:20:08.930](0.014s) ok 106 - certificate with both an IP CN and IP SANs 2 [15:20:08.930](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [15:20:08.941](0.011s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [15:20:08.942](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 50045 [15:20:09.163](0.222s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [15:20:09.164](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [15:20:09.177](0.013s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [15:20:09.177](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [15:20:09.189](0.012s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [15:20:09.189](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 50059 [15:20:09.410](0.221s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [15:20:09.410](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [15:20:09.422](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full [15:20:09.422](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 50071 [15:20:09.639](0.217s) ok 120 - sslrootcert=system does not connect with private CA [15:20:09.639](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [15:20:09.646](0.007s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [15:20:09.646](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [15:20:09.662](0.016s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [15:20:09.662](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [15:20:09.673](0.011s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [15:20:09.673](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 50086 [15:20:09.895](0.222s) ok 128 - connects without client-side CRL [15:20:09.895](0.000s) ok 129 - connects without client-side CRL: no stderr [15:20:09.907](0.012s) ok 130 - does not connect with client-side CRL file [15:20:09.907](0.000s) ok 131 - does not connect with client-side CRL file: matches [15:20:09.919](0.011s) ok 132 - does not connect with client-side CRL directory [15:20:09.919](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() [15:20:09.933](0.014s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [15:20:09.933](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [15:20:09.933](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [15:20:09.946](0.013s) ok 137 - connection success with correct range of TLS protocol versions [15:20:09.947](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [15:20:09.954](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [15:20:09.954](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [15:20:09.961](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [15:20:09.962](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [15:20:09.969](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [15:20:09.969](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [15:20:09.969](0.000s) # running server tests [15:20:09.982](0.013s) ok 145 - certificate authorization fails without client cert [15:20:09.982](0.000s) ok 146 - certificate authorization fails without client cert: matches [15:20:10.001](0.019s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [15:20:10.002](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [15:20:10.018](0.016s) ok 149 - certificate authorization succeeds with correct client cert in DER format [15:20:10.018](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [15:20:10.035](0.017s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [15:20:10.035](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [15:20:10.052](0.017s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [15:20:10.052](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [15:20:10.068](0.016s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [15:20:10.068](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [15:20:10.084](0.015s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [15:20:10.084](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [15:20:10.096](0.012s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [15:20:10.096](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [15:20:10.106](0.010s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [15:20:10.107](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [15:20:10.126](0.019s) ok 163 - certificate authorization succeeds with DN mapping [15:20:10.126](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [15:20:10.126](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [15:20:10.145](0.019s) ok 166 - certificate authorization succeeds with DN regex mapping [15:20:10.146](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [15:20:10.165](0.020s) ok 168 - certificate authorization succeeds with CN mapping [15:20:10.165](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [15:20:10.166](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [15:20:10.166](0.000s) not ok 171 # TODO & SKIP Need Pty support [15:20:10.166](0.000s) not ok 172 # TODO & SKIP Need Pty support [15:20:10.166](0.000s) not ok 173 # TODO & SKIP Need Pty support [15:20:10.166](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_g_gT/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [15:20:10.192](0.026s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [15:20:10.192](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [15:20:10.192](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [15:20:10.203](0.010s) ok 178 - certificate authorization fails because of file permissions [15:20:10.203](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [15:20:10.218](0.015s) ok 180 - certificate authorization fails with client cert belonging to another user [15:20:10.218](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [15:20:10.232](0.014s) ok 182 - certificate authorization fails with revoked client cert [15:20:10.232](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [15:20:10.232](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [15:20:10.252](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [15:20:10.252](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [15:20:10.252](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [15:20:10.267](0.014s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [15:20:10.267](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [15:20:10.267](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [15:20:10.283](0.016s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [15:20:10.284](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [15:20:10.284](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 50146 [15:20:10.508](0.224s) ok 194 - intermediate client certificate is provided by client [15:20:10.508](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [15:20:10.521](0.013s) ok 196 - intermediate client certificate is missing [15:20:10.521](0.000s) ok 197 - intermediate client certificate is missing: matches [15:20:10.535](0.013s) ok 198 - logged client certificate Subjects are truncated if they're too long [15:20:10.535](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 50160 [15:20:10.752](0.218s) ok 200 - intermediate client certificate is untrusted [15:20:10.753](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 50170 [15:20:10.971](0.218s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [15:20:10.971](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [15:20:10.985](0.013s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [15:20:10.985](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 50182 [15:20:11.200](0.216s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [15:20:11.201](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. [15:20:11.201](0.000s) # got: '2' # expected: '0' [15:20:11.201](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [15:20:11.201](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. [15:20:11.201](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 50342 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: '' [15:20:11.218](0.017s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [15:20:11.219](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 50194 [15:20:11.435](0.216s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [15:20:11.453](0.018s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [15:20:11.453](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 50206 [15:20:11.671](0.218s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [15:20:11.689](0.018s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [15:20:11.689](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 50218 [15:20:11.904](0.215s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [15:20:11.922](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [15:20:11.922](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 50230 [15:20:12.137](0.215s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [15:20:12.137](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. [15:20:12.137](0.000s) # got: '2' # expected: '0' [15:20:12.137](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [15:20:12.137](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. [15:20:12.138](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 50342 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: '' [15:20:12.155](0.018s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [15:20:12.155](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 50242 [15:20:12.370](0.215s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [15:20:12.387](0.017s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [15:20:12.388](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 50254 [15:20:12.602](0.215s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [15:20:12.619](0.016s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [15:20:12.619](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 50266 [15:20:12.833](0.214s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [15:20:12.849](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [15:20:12.849](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 50278 [15:20:13.063](0.214s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [15:20:13.079](0.016s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [15:20:13.079](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-20 15:20:04.592 UTC [49249][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:04.592 UTC [49249][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:04.594 UTC [49253][startup] LOG: database system was shut down at 2024-03-20 15:17:40 UTC 2024-03-20 15:20:04.596 UTC [49249][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:04.701 UTC [49297][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.701 UTC [49297][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-20 15:20:04.701 UTC [49297][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.715 UTC [49297][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-20 15:20:04.715 UTC [49297][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.738 UTC [49307][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.739 UTC [49307][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-20 15:20:04.739 UTC [49307][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.745 UTC [49307][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-20 15:20:04.746 UTC [49307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.782 UTC [49326][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.783 UTC [49326][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-20 15:20:04.783 UTC [49326][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.784 UTC [49326][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-20 15:20:04.784 UTC [49326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.853 UTC [49361][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.854 UTC [49361][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-20 15:20:04.854 UTC [49361][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.855 UTC [49361][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-20 15:20:04.855 UTC [49361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.882 UTC [49367][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.882 UTC [49367][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-20 15:20:04.882 UTC [49367][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.883 UTC [49367][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-20 15:20:04.884 UTC [49367][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.924 UTC [49380][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.924 UTC [49380][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-20 15:20:04.924 UTC [49380][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.925 UTC [49380][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-20 15:20:04.954 UTC [49380][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-03-20 15:20:04.967 UTC [49392][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:04.967 UTC [49392][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-20 15:20:04.967 UTC [49392][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:04.968 UTC [49392][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-20 15:20:05.019 UTC [49392][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.095 UTC [49427][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.096 UTC [49427][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-20 15:20:05.096 UTC [49427][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.097 UTC [49427][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-20 15:20:05.122 UTC [49427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.142 UTC [49432][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.142 UTC [49432][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-20 15:20:05.142 UTC [49432][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.143 UTC [49432][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-20 15:20:05.194 UTC [49432][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.290 UTC [49467][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.290 UTC [49467][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-20 15:20:05.290 UTC [49467][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.291 UTC [49467][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-20 15:20:05.318 UTC [49467][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.387 UTC [49500][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-20 15:20:05.388 UTC [49500][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-20 15:20:05.388 UTC [49500][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-20 15:20:05.389 UTC [49500][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-20 15:20:05.428 UTC [49500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-20 15:20:05.508 UTC [49249][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:05.509 UTC [49249][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:05.510 UTC [49249][postmaster] LOG: background worker "logical replication launcher" (PID 49256) exited with exit code 1 2024-03-20 15:20:05.511 UTC [49251][checkpointer] LOG: shutting down 2024-03-20 15:20:05.511 UTC [49251][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:05.546 UTC [49251][checkpointer] LOG: checkpoint complete: wrote 5644 buffers (34.4%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.034 s, sync=0.001 s, total=0.035 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45431 kB, estimate=45431 kB; lsn=0/4187898, redo lsn=0/4187898 2024-03-20 15:20:05.556 UTC [49249][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:05.629 UTC [49550][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:05.631 UTC [49558][startup] LOG: database system was shut down at 2024-03-20 15:20:05 UTC 2024-03-20 15:20:05.635 UTC [49550][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:05.723 UTC [49550][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:05.723 UTC [49550][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:05.724 UTC [49550][postmaster] LOG: background worker "logical replication launcher" (PID 49563) exited with exit code 1 2024-03-20 15:20:05.725 UTC [49556][checkpointer] LOG: shutting down 2024-03-20 15:20:05.725 UTC [49556][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:05.740 UTC [49556][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.016 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4187910, redo lsn=0/4187910 2024-03-20 15:20:05.743 UTC [49550][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.836 UTC [49598][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-20 15:20:05.837 UTC [49598][postmaster] LOG: database system is shut down 2024-03-20 15:20:05.946 UTC [49622][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:05.946 UTC [49622][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:05.946 UTC [49622][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:05.948 UTC [49630][startup] LOG: database system was shut down at 2024-03-20 15:20:05 UTC 2024-03-20 15:20:05.952 UTC [49622][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:06.030 UTC [49622][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:06.030 UTC [49622][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:06.032 UTC [49622][postmaster] LOG: background worker "logical replication launcher" (PID 49633) exited with exit code 1 2024-03-20 15:20:06.033 UTC [49628][checkpointer] LOG: shutting down 2024-03-20 15:20:06.033 UTC [49628][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:06.033 UTC [49628][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/4187988, redo lsn=0/4187988 2024-03-20 15:20:06.036 UTC [49622][postmaster] LOG: database system is shut down 2024-03-20 15:20:06.145 UTC [49664][postmaster] FATAL: could not set SSL protocol version range 2024-03-20 15:20:06.145 UTC [49664][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-20 15:20:06.145 UTC [49664][postmaster] LOG: database system is shut down 2024-03-20 15:20:06.254 UTC [49680][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:06.254 UTC [49680][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:06.254 UTC [49680][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:06.256 UTC [49693][startup] LOG: database system was shut down at 2024-03-20 15:20:06 UTC 2024-03-20 15:20:06.259 UTC [49680][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:06.339 UTC [49680][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:06.339 UTC [49680][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:06.340 UTC [49680][postmaster] LOG: background worker "logical replication launcher" (PID 49697) exited with exit code 1 2024-03-20 15:20:06.343 UTC [49691][checkpointer] LOG: shutting down 2024-03-20 15:20:06.343 UTC [49691][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:06.344 UTC [49691][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/4187A00, redo lsn=0/4187A00 2024-03-20 15:20:06.346 UTC [49680][postmaster] LOG: database system is shut down 2024-03-20 15:20:06.457 UTC [49727][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:06.457 UTC [49727][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:06.457 UTC [49727][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:06.459 UTC [49731][startup] LOG: database system was shut down at 2024-03-20 15:20:06 UTC 2024-03-20 15:20:06.461 UTC [49727][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:06.549 UTC [49764][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42834 2024-03-20 15:20:06.549 UTC [49764][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-20 15:20:06.549 UTC [49764][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-20 15:20:06.560 UTC [49768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42850 2024-03-20 15:20:06.563 UTC [49768][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-20 15:20:06.563 UTC [49768][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-20 15:20:06.568 UTC [49768][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-20 15:20:06.571 UTC [49768][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=42850 2024-03-20 15:20:06.579 UTC [49775][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42852 2024-03-20 15:20:06.580 UTC [49775][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-20 15:20:06.588 UTC [49780][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42854 2024-03-20 15:20:06.590 UTC [49780][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-20 15:20:06.598 UTC [49784][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42866 2024-03-20 15:20:06.604 UTC [49784][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:06.615 UTC [49789][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42882 2024-03-20 15:20:06.618 UTC [49789][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:06.629 UTC [49791][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42892 2024-03-20 15:20:06.632 UTC [49791][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:06.640 UTC [49793][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42896 2024-03-20 15:20:06.643 UTC [49793][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:06.652 UTC [49796][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42912 2024-03-20 15:20:06.655 UTC [49796][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-20 15:20:06.655 UTC [49796][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-20 15:20:06.656 UTC [49796][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-20 15:20:06.657 UTC [49796][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42912 2024-03-20 15:20:06.666 UTC [49800][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42924 2024-03-20 15:20:06.670 UTC [49800][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-20 15:20:06.670 UTC [49800][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-20 15:20:06.671 UTC [49800][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-20 15:20:06.671 UTC [49800][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42924 2024-03-20 15:20:06.680 UTC [49806][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42932 2024-03-20 15:20:06.684 UTC [49806][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-20 15:20:06.684 UTC [49806][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-20 15:20:06.685 UTC [49806][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-20 15:20:06.685 UTC [49806][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42932 2024-03-20 15:20:06.695 UTC [49809][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42946 2024-03-20 15:20:06.699 UTC [49809][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-20 15:20:06.699 UTC [49809][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-20 15:20:06.700 UTC [49809][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-20 15:20:06.701 UTC [49809][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=42946 2024-03-20 15:20:06.712 UTC [49814][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42954 2024-03-20 15:20:06.716 UTC [49814][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-20 15:20:06.716 UTC [49814][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-20 15:20:06.717 UTC [49814][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-20 15:20:06.720 UTC [49814][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42954 2024-03-20 15:20:06.728 UTC [49818][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42966 2024-03-20 15:20:06.732 UTC [49818][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-20 15:20:06.732 UTC [49818][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-20 15:20:06.733 UTC [49818][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-20 15:20:06.736 UTC [49818][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42966 2024-03-20 15:20:06.744 UTC [49825][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42978 2024-03-20 15:20:06.748 UTC [49825][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-20 15:20:06.748 UTC [49825][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-20 15:20:06.749 UTC [49825][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-20 15:20:06.750 UTC [49825][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42978 2024-03-20 15:20:06.759 UTC [49832][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42984 2024-03-20 15:20:06.763 UTC [49832][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-20 15:20:06.763 UTC [49832][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-20 15:20:06.764 UTC [49832][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42984 2024-03-20 15:20:06.772 UTC [49836][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42992 2024-03-20 15:20:06.776 UTC [49836][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-20 15:20:06.776 UTC [49836][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-20 15:20:06.777 UTC [49836][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-20 15:20:06.778 UTC [49836][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42992 2024-03-20 15:20:06.785 UTC [49840][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43004 2024-03-20 15:20:06.788 UTC [49840][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:06.799 UTC [49843][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43020 2024-03-20 15:20:06.802 UTC [49843][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:06.812 UTC [49847][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43022 2024-03-20 15:20:06.816 UTC [49847][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-20 15:20:06.816 UTC [49847][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-20 15:20:06.817 UTC [49847][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-20 15:20:06.817 UTC [49847][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43022 2024-03-20 15:20:06.827 UTC [49851][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43034 2024-03-20 15:20:06.831 UTC [49851][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-20 15:20:06.831 UTC [49851][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-20 15:20:06.832 UTC [49851][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-20 15:20:06.833 UTC [49851][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43034 2024-03-20 15:20:06.843 UTC [49854][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43046 2024-03-20 15:20:06.847 UTC [49854][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-20 15:20:06.847 UTC [49854][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-20 15:20:06.848 UTC [49854][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-20 15:20:06.848 UTC [49854][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=43046 2024-03-20 15:20:06.858 UTC [49856][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43058 2024-03-20 15:20:06.862 UTC [49856][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-20 15:20:06.862 UTC [49856][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-20 15:20:06.863 UTC [49856][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-20 15:20:06.863 UTC [49856][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43058 2024-03-20 15:20:06.873 UTC [49858][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43068 2024-03-20 15:20:06.881 UTC [49727][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:06.881 UTC [49727][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:06.883 UTC [49727][postmaster] LOG: background worker "logical replication launcher" (PID 49734) exited with exit code 1 2024-03-20 15:20:06.884 UTC [49729][checkpointer] LOG: shutting down 2024-03-20 15:20:06.884 UTC [49729][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:06.884 UTC [49729][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/4187A78, redo lsn=0/4187A78 2024-03-20 15:20:06.887 UTC [49727][postmaster] LOG: database system is shut down 2024-03-20 15:20:07.000 UTC [49868][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:07.000 UTC [49868][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:07.000 UTC [49868][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:07.002 UTC [49872][startup] LOG: database system was shut down at 2024-03-20 15:20:06 UTC 2024-03-20 15:20:07.005 UTC [49868][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:07.090 UTC [49880][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43080 2024-03-20 15:20:07.093 UTC [49880][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-20 15:20:07.093 UTC [49880][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-20 15:20:07.098 UTC [49880][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-20 15:20:07.099 UTC [49880][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43080 2024-03-20 15:20:07.107 UTC [49882][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43086 2024-03-20 15:20:07.116 UTC [49868][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:07.116 UTC [49868][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:07.117 UTC [49868][postmaster] LOG: background worker "logical replication launcher" (PID 49875) exited with exit code 1 2024-03-20 15:20:07.117 UTC [49870][checkpointer] LOG: shutting down 2024-03-20 15:20:07.117 UTC [49870][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:07.118 UTC [49870][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/4187AF0, redo lsn=0/4187AF0 2024-03-20 15:20:07.121 UTC [49868][postmaster] LOG: database system is shut down 2024-03-20 15:20:07.234 UTC [49893][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:07.234 UTC [49893][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:07.234 UTC [49893][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:07.237 UTC [49896][startup] LOG: database system was shut down at 2024-03-20 15:20:07 UTC 2024-03-20 15:20:07.240 UTC [49893][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:07.325 UTC [49906][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43094 2024-03-20 15:20:07.329 UTC [49906][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-20 15:20:07.329 UTC [49906][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-20 15:20:07.334 UTC [49906][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-20 15:20:07.334 UTC [49906][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43094 2024-03-20 15:20:07.338 UTC [49893][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:07.338 UTC [49893][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:07.339 UTC [49893][postmaster] LOG: background worker "logical replication launcher" (PID 49899) exited with exit code 1 2024-03-20 15:20:07.340 UTC [49894][checkpointer] LOG: shutting down 2024-03-20 15:20:07.340 UTC [49894][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:07.341 UTC [49894][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/4187B68, redo lsn=0/4187B68 2024-03-20 15:20:07.344 UTC [49893][postmaster] LOG: database system is shut down 2024-03-20 15:20:07.456 UTC [49915][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:07.456 UTC [49915][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:07.456 UTC [49915][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:07.458 UTC [49920][startup] LOG: database system was shut down at 2024-03-20 15:20:07 UTC 2024-03-20 15:20:07.462 UTC [49915][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:07.547 UTC [49928][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43110 2024-03-20 15:20:07.551 UTC [49928][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-20 15:20:07.551 UTC [49928][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-20 15:20:07.556 UTC [49928][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-20 15:20:07.556 UTC [49928][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43110 2024-03-20 15:20:07.566 UTC [49930][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43120 2024-03-20 15:20:07.571 UTC [49930][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-20 15:20:07.571 UTC [49930][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-20 15:20:07.572 UTC [49930][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-20 15:20:07.572 UTC [49930][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=43120 2024-03-20 15:20:07.581 UTC [49932][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43126 2024-03-20 15:20:07.585 UTC [49932][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-20 15:20:07.585 UTC [49932][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-20 15:20:07.586 UTC [49932][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-20 15:20:07.587 UTC [49932][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43126 2024-03-20 15:20:07.597 UTC [49934][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43138 2024-03-20 15:20:07.609 UTC [49938][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43148 2024-03-20 15:20:07.616 UTC [49915][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:07.616 UTC [49915][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:07.618 UTC [49915][postmaster] LOG: background worker "logical replication launcher" (PID 49923) exited with exit code 1 2024-03-20 15:20:07.619 UTC [49918][checkpointer] LOG: shutting down 2024-03-20 15:20:07.619 UTC [49918][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:07.619 UTC [49918][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/4187BE0, redo lsn=0/4187BE0 2024-03-20 15:20:07.622 UTC [49915][postmaster] LOG: database system is shut down 2024-03-20 15:20:07.736 UTC [49951][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:07.736 UTC [49951][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:07.736 UTC [49951][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:07.738 UTC [49955][startup] LOG: database system was shut down at 2024-03-20 15:20:07 UTC 2024-03-20 15:20:07.741 UTC [49951][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:07.826 UTC [49960][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43160 2024-03-20 15:20:07.830 UTC [49960][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-20 15:20:07.830 UTC [49960][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-20 15:20:07.835 UTC [49960][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-20 15:20:07.835 UTC [49960][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43160 2024-03-20 15:20:07.845 UTC [49964][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43162 2024-03-20 15:20:07.859 UTC [49972][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43164 2024-03-20 15:20:07.870 UTC [49951][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:07.870 UTC [49951][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:07.872 UTC [49951][postmaster] LOG: background worker "logical replication launcher" (PID 49958) exited with exit code 1 2024-03-20 15:20:07.872 UTC [49953][checkpointer] LOG: shutting down 2024-03-20 15:20:07.872 UTC [49953][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:07.873 UTC [49953][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/4187C58, redo lsn=0/4187C58 2024-03-20 15:20:07.876 UTC [49951][postmaster] LOG: database system is shut down 2024-03-20 15:20:07.989 UTC [49978][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:07.989 UTC [49978][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:07.989 UTC [49978][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:07.991 UTC [49981][startup] LOG: database system was shut down at 2024-03-20 15:20:07 UTC 2024-03-20 15:20:07.994 UTC [49978][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:08.080 UTC [49986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43168 2024-03-20 15:20:08.083 UTC [49986][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-20 15:20:08.083 UTC [49986][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-20 15:20:08.088 UTC [49986][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-20 15:20:08.089 UTC [49986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43168 2024-03-20 15:20:08.099 UTC [49988][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43176 2024-03-20 15:20:08.103 UTC [49988][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-20 15:20:08.103 UTC [49988][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-20 15:20:08.104 UTC [49988][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-20 15:20:08.104 UTC [49988][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43176 2024-03-20 15:20:08.113 UTC [49990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43188 2024-03-20 15:20:08.126 UTC [49992][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43202 2024-03-20 15:20:08.130 UTC [49992][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-20 15:20:08.130 UTC [49992][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-20 15:20:08.131 UTC [49992][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-20 15:20:08.131 UTC [49992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43202 2024-03-20 15:20:08.141 UTC [49994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43218 2024-03-20 15:20:08.145 UTC [49994][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-20 15:20:08.145 UTC [49994][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-20 15:20:08.146 UTC [49994][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-20 15:20:08.147 UTC [49994][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=43218 2024-03-20 15:20:08.156 UTC [49996][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43228 2024-03-20 15:20:08.160 UTC [49996][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-20 15:20:08.160 UTC [49996][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-20 15:20:08.161 UTC [49996][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-20 15:20:08.162 UTC [49996][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43228 2024-03-20 15:20:08.171 UTC [49998][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43234 2024-03-20 15:20:08.183 UTC [50000][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43238 2024-03-20 15:20:08.190 UTC [49978][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:08.190 UTC [49978][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:08.191 UTC [49978][postmaster] LOG: background worker "logical replication launcher" (PID 49984) exited with exit code 1 2024-03-20 15:20:08.191 UTC [49979][checkpointer] LOG: shutting down 2024-03-20 15:20:08.191 UTC [49979][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:08.192 UTC [49979][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/4187CD0, redo lsn=0/4187CD0 2024-03-20 15:20:08.195 UTC [49978][postmaster] LOG: database system is shut down 2024-03-20 15:20:08.308 UTC [50002][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:08.308 UTC [50002][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:08.308 UTC [50002][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:08.310 UTC [50005][startup] LOG: database system was shut down at 2024-03-20 15:20:08 UTC 2024-03-20 15:20:08.314 UTC [50002][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:08.399 UTC [50010][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43254 2024-03-20 15:20:08.403 UTC [50010][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-20 15:20:08.403 UTC [50010][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-20 15:20:08.408 UTC [50010][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-20 15:20:08.408 UTC [50010][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=43254 2024-03-20 15:20:08.418 UTC [50012][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43258 2024-03-20 15:20:08.422 UTC [50012][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-20 15:20:08.422 UTC [50012][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-20 15:20:08.423 UTC [50012][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-20 15:20:08.423 UTC [50012][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43258 2024-03-20 15:20:08.432 UTC [50014][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43260 2024-03-20 15:20:08.443 UTC [50002][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:08.443 UTC [50002][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:08.445 UTC [50002][postmaster] LOG: background worker "logical replication launcher" (PID 50008) exited with exit code 1 2024-03-20 15:20:08.445 UTC [50003][checkpointer] LOG: shutting down 2024-03-20 15:20:08.445 UTC [50003][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:08.446 UTC [50003][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/4187D48, redo lsn=0/4187D48 2024-03-20 15:20:08.449 UTC [50002][postmaster] LOG: database system is shut down 2024-03-20 15:20:08.561 UTC [50017][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:08.561 UTC [50017][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:08.561 UTC [50017][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:08.563 UTC [50020][startup] LOG: database system was shut down at 2024-03-20 15:20:08 UTC 2024-03-20 15:20:08.566 UTC [50017][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:08.653 UTC [50025][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43276 2024-03-20 15:20:08.657 UTC [50025][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-20 15:20:08.657 UTC [50025][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-20 15:20:08.662 UTC [50025][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-20 15:20:08.663 UTC [50025][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=43276 2024-03-20 15:20:08.672 UTC [50027][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43288 2024-03-20 15:20:08.677 UTC [50027][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-20 15:20:08.677 UTC [50027][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-20 15:20:08.678 UTC [50027][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-20 15:20:08.678 UTC [50027][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=43288 2024-03-20 15:20:08.687 UTC [50029][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43294 2024-03-20 15:20:08.691 UTC [50029][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-20 15:20:08.691 UTC [50029][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-20 15:20:08.692 UTC [50029][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-20 15:20:08.692 UTC [50029][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43294 2024-03-20 15:20:08.696 UTC [50017][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:08.696 UTC [50017][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:08.698 UTC [50017][postmaster] LOG: background worker "logical replication launcher" (PID 50023) exited with exit code 1 2024-03-20 15:20:08.698 UTC [50018][checkpointer] LOG: shutting down 2024-03-20 15:20:08.698 UTC [50018][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:08.699 UTC [50018][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/4187DC0, redo lsn=0/4187DC0 2024-03-20 15:20:08.702 UTC [50017][postmaster] LOG: database system is shut down 2024-03-20 15:20:08.814 UTC [50031][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:08.814 UTC [50031][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:08.814 UTC [50031][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:08.816 UTC [50034][startup] LOG: database system was shut down at 2024-03-20 15:20:08 UTC 2024-03-20 15:20:08.820 UTC [50031][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:08.906 UTC [50039][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43304 2024-03-20 15:20:08.910 UTC [50039][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-20 15:20:08.910 UTC [50039][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-20 15:20:08.914 UTC [50039][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-20 15:20:08.915 UTC [50039][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43304 2024-03-20 15:20:08.924 UTC [50041][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43316 2024-03-20 15:20:08.928 UTC [50041][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-20 15:20:08.928 UTC [50041][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-20 15:20:08.929 UTC [50041][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-20 15:20:08.929 UTC [50041][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43316 2024-03-20 15:20:08.937 UTC [50043][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43318 2024-03-20 15:20:08.944 UTC [50031][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:08.944 UTC [50031][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:08.945 UTC [50031][postmaster] LOG: background worker "logical replication launcher" (PID 50037) exited with exit code 1 2024-03-20 15:20:08.945 UTC [50032][checkpointer] LOG: shutting down 2024-03-20 15:20:08.945 UTC [50032][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:08.946 UTC [50032][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/4187E38, redo lsn=0/4187E38 2024-03-20 15:20:08.949 UTC [50031][postmaster] LOG: database system is shut down 2024-03-20 15:20:09.063 UTC [50045][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:09.063 UTC [50045][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:09.063 UTC [50045][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:09.065 UTC [50048][startup] LOG: database system was shut down at 2024-03-20 15:20:08 UTC 2024-03-20 15:20:09.068 UTC [50045][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:09.153 UTC [50053][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43330 2024-03-20 15:20:09.157 UTC [50053][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-20 15:20:09.157 UTC [50053][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-20 15:20:09.162 UTC [50053][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-20 15:20:09.162 UTC [50053][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43330 2024-03-20 15:20:09.170 UTC [50055][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43346 2024-03-20 15:20:09.174 UTC [50055][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-20 15:20:09.174 UTC [50055][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-20 15:20:09.175 UTC [50055][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-20 15:20:09.175 UTC [50055][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43346 2024-03-20 15:20:09.183 UTC [50057][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43352 2024-03-20 15:20:09.187 UTC [50057][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-20 15:20:09.187 UTC [50057][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-20 15:20:09.188 UTC [50057][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-20 15:20:09.188 UTC [50057][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=43352 2024-03-20 15:20:09.192 UTC [50045][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:09.192 UTC [50045][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:09.194 UTC [50045][postmaster] LOG: background worker "logical replication launcher" (PID 50051) exited with exit code 1 2024-03-20 15:20:09.194 UTC [50046][checkpointer] LOG: shutting down 2024-03-20 15:20:09.194 UTC [50046][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:09.194 UTC [50046][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/4187EB0, redo lsn=0/4187EB0 2024-03-20 15:20:09.197 UTC [50045][postmaster] LOG: database system is shut down 2024-03-20 15:20:09.310 UTC [50059][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:09.311 UTC [50059][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:09.311 UTC [50059][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:09.313 UTC [50062][startup] LOG: database system was shut down at 2024-03-20 15:20:09 UTC 2024-03-20 15:20:09.316 UTC [50059][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:09.400 UTC [50067][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43360 2024-03-20 15:20:09.404 UTC [50067][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-20 15:20:09.404 UTC [50067][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-20 15:20:09.409 UTC [50067][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-20 15:20:09.409 UTC [50067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43360 2024-03-20 15:20:09.417 UTC [50069][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43374 2024-03-20 15:20:09.425 UTC [50059][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:09.425 UTC [50059][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:09.426 UTC [50059][postmaster] LOG: background worker "logical replication launcher" (PID 50065) exited with exit code 1 2024-03-20 15:20:09.427 UTC [50060][checkpointer] LOG: shutting down 2024-03-20 15:20:09.427 UTC [50060][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:09.427 UTC [50060][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/4187F28, redo lsn=0/4187F28 2024-03-20 15:20:09.430 UTC [50059][postmaster] LOG: database system is shut down 2024-03-20 15:20:09.544 UTC [50071][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:09.544 UTC [50071][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:09.544 UTC [50071][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:09.546 UTC [50074][startup] LOG: database system was shut down at 2024-03-20 15:20:09 UTC 2024-03-20 15:20:09.550 UTC [50071][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:09.634 UTC [50079][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43390 2024-03-20 15:20:09.637 UTC [50079][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-20 15:20:09.652 UTC [50082][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43396 2024-03-20 15:20:09.656 UTC [50082][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-20 15:20:09.656 UTC [50082][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-20 15:20:09.661 UTC [50082][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-20 15:20:09.661 UTC [50082][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43396 2024-03-20 15:20:09.669 UTC [50084][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43400 2024-03-20 15:20:09.676 UTC [50071][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:09.676 UTC [50071][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:09.678 UTC [50071][postmaster] LOG: background worker "logical replication launcher" (PID 50077) exited with exit code 1 2024-03-20 15:20:09.678 UTC [50072][checkpointer] LOG: shutting down 2024-03-20 15:20:09.678 UTC [50072][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:09.679 UTC [50072][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/4187FA0, redo lsn=0/4187FA0 2024-03-20 15:20:09.682 UTC [50071][postmaster] LOG: database system is shut down 2024-03-20 15:20:09.795 UTC [50086][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:09.795 UTC [50086][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:09.795 UTC [50086][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:09.797 UTC [50089][startup] LOG: database system was shut down at 2024-03-20 15:20:09 UTC 2024-03-20 15:20:09.801 UTC [50086][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:09.885 UTC [50094][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=55998 2024-03-20 15:20:09.889 UTC [50094][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-20 15:20:09.889 UTC [50094][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-20 15:20:09.894 UTC [50094][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-20 15:20:09.894 UTC [50094][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=55998 2024-03-20 15:20:09.903 UTC [50096][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56000 2024-03-20 15:20:09.906 UTC [50096][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-20 15:20:09.914 UTC [50098][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56016 2024-03-20 15:20:09.917 UTC [50098][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-20 15:20:09.926 UTC [50100][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56020 2024-03-20 15:20:09.929 UTC [50100][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-20 15:20:09.929 UTC [50100][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-20 15:20:09.930 UTC [50100][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-20 15:20:09.932 UTC [50100][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=56020 2024-03-20 15:20:09.940 UTC [50102][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56036 2024-03-20 15:20:09.944 UTC [50102][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-20 15:20:09.944 UTC [50102][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-20 15:20:09.945 UTC [50102][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-20 15:20:09.945 UTC [50102][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=56036 2024-03-20 15:20:09.976 UTC [50107][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56038 2024-03-20 15:20:09.980 UTC [50107][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-20 15:20:09.990 UTC [50109][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56052 2024-03-20 15:20:09.995 UTC [50109][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-20 15:20:09.995 UTC [50109][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-20 15:20:09.999 UTC [50109][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_g_gT/client.key$$ 2024-03-20 15:20:10.000 UTC [50109][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=56052 2024-03-20 15:20:10.010 UTC [50111][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56066 2024-03-20 15:20:10.015 UTC [50111][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-20 15:20:10.015 UTC [50111][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-20 15:20:10.016 UTC [50111][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_g_gT/client-der.key$$ 2024-03-20 15:20:10.017 UTC [50111][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=56066 2024-03-20 15:20:10.025 UTC [50113][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56070 2024-03-20 15:20:10.032 UTC [50113][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-20 15:20:10.032 UTC [50113][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-20 15:20:10.033 UTC [50113][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_g_gT/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-20 15:20:10.033 UTC [50113][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=56070 2024-03-20 15:20:10.043 UTC [50115][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56074 2024-03-20 15:20:10.049 UTC [50115][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-20 15:20:10.049 UTC [50115][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-20 15:20:10.050 UTC [50115][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_g_gT/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-20 15:20:10.051 UTC [50115][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=56074 2024-03-20 15:20:10.060 UTC [50117][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56084 2024-03-20 15:20:10.065 UTC [50117][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-20 15:20:10.065 UTC [50117][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-20 15:20:10.066 UTC [50117][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_g_gT/client.key$$ 2024-03-20 15:20:10.067 UTC [50117][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=56084 2024-03-20 15:20:10.076 UTC [50119][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56088 2024-03-20 15:20:10.081 UTC [50119][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-20 15:20:10.081 UTC [50119][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-20 15:20:10.082 UTC [50119][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_g_gT/client.key$$ 2024-03-20 15:20:10.082 UTC [50119][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=56088 2024-03-20 15:20:10.091 UTC [50121][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56092 2024-03-20 15:20:10.095 UTC [50121][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-20 15:20:10.104 UTC [50123][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56098 2024-03-20 15:20:10.105 UTC [50123][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-20 15:20:10.114 UTC [50125][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56106 2024-03-20 15:20:10.119 UTC [50125][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-20 15:20:10.119 UTC [50125][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-20 15:20:10.124 UTC [50125][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_g_gT/client-dn.key$$ 2024-03-20 15:20:10.124 UTC [50125][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=56106 2024-03-20 15:20:10.133 UTC [50127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56118 2024-03-20 15:20:10.139 UTC [50127][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-20 15:20:10.139 UTC [50127][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-20 15:20:10.144 UTC [50127][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_g_gT/client-dn.key$$ 2024-03-20 15:20:10.144 UTC [50127][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=56118 2024-03-20 15:20:10.153 UTC [50129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56122 2024-03-20 15:20:10.159 UTC [50129][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-20 15:20:10.159 UTC [50129][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-20 15:20:10.163 UTC [50129][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_g_gT/client-dn.key$$ 2024-03-20 15:20:10.164 UTC [50129][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=56122 2024-03-20 15:20:10.183 UTC [50132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56128 2024-03-20 15:20:10.188 UTC [50132][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-20 15:20:10.188 UTC [50132][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-20 15:20:10.189 UTC [50132][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-20 15:20:10.191 UTC [50132][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=56128 2024-03-20 15:20:10.200 UTC [50134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56138 2024-03-20 15:20:10.201 UTC [50134][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-20 15:20:10.210 UTC [50136][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56144 2024-03-20 15:20:10.216 UTC [50136][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-20 15:20:10.216 UTC [50136][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-20 15:20:10.216 UTC [50136][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-20 15:20:10.216 UTC [50136][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-20 15:20:10.225 UTC [50138][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56146 2024-03-20 15:20:10.230 UTC [50138][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-20 15:20:10.230 UTC [50138][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-20 15:20:10.240 UTC [50140][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56158 2024-03-20 15:20:10.245 UTC [50140][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-20 15:20:10.245 UTC [50140][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-20 15:20:10.250 UTC [50140][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_g_gT/client.key$$ 2024-03-20 15:20:10.250 UTC [50140][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=56158 2024-03-20 15:20:10.260 UTC [50142][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56172 2024-03-20 15:20:10.265 UTC [50142][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-20 15:20:10.265 UTC [50142][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-20 15:20:10.265 UTC [50142][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-20 15:20:10.265 UTC [50142][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-20 15:20:10.275 UTC [50144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56174 2024-03-20 15:20:10.281 UTC [50144][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-20 15:20:10.281 UTC [50144][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-20 15:20:10.282 UTC [50144][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_g_gT/client.key$$ 2024-03-20 15:20:10.282 UTC [50144][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=56174 2024-03-20 15:20:10.286 UTC [50086][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:10.286 UTC [50086][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:10.288 UTC [50086][postmaster] LOG: background worker "logical replication launcher" (PID 50092) exited with exit code 1 2024-03-20 15:20:10.288 UTC [50087][checkpointer] LOG: shutting down 2024-03-20 15:20:10.288 UTC [50087][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:10.289 UTC [50087][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/4188030, redo lsn=0/4188030 2024-03-20 15:20:10.292 UTC [50086][postmaster] LOG: database system is shut down 2024-03-20 15:20:10.405 UTC [50146][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:10.405 UTC [50146][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:10.405 UTC [50146][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:10.407 UTC [50149][startup] LOG: database system was shut down at 2024-03-20 15:20:10 UTC 2024-03-20 15:20:10.411 UTC [50146][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:10.496 UTC [50154][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56176 2024-03-20 15:20:10.501 UTC [50154][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-20 15:20:10.501 UTC [50154][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-20 15:20:10.506 UTC [50154][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_g_gT/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-20 15:20:10.506 UTC [50154][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=56176 2024-03-20 15:20:10.515 UTC [50156][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56190 2024-03-20 15:20:10.519 UTC [50156][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-20 15:20:10.519 UTC [50156][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-20 15:20:10.528 UTC [50158][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56196 2024-03-20 15:20:10.533 UTC [50158][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-20 15:20:10.533 UTC [50158][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-20 15:20:10.537 UTC [50146][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:10.537 UTC [50146][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:10.539 UTC [50146][postmaster] LOG: background worker "logical replication launcher" (PID 50152) exited with exit code 1 2024-03-20 15:20:10.539 UTC [50147][checkpointer] LOG: shutting down 2024-03-20 15:20:10.539 UTC [50147][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:10.540 UTC [50147][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/41880A8, redo lsn=0/41880A8 2024-03-20 15:20:10.542 UTC [50146][postmaster] LOG: database system is shut down 2024-03-20 15:20:10.656 UTC [50160][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:10.656 UTC [50160][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:10.656 UTC [50160][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:10.658 UTC [50163][startup] LOG: database system was shut down at 2024-03-20 15:20:10 UTC 2024-03-20 15:20:10.662 UTC [50160][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:10.746 UTC [50168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56206 2024-03-20 15:20:10.751 UTC [50168][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-20 15:20:10.751 UTC [50168][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-20 15:20:10.755 UTC [50160][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:10.755 UTC [50160][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:10.757 UTC [50160][postmaster] LOG: background worker "logical replication launcher" (PID 50166) exited with exit code 1 2024-03-20 15:20:10.757 UTC [50161][checkpointer] LOG: shutting down 2024-03-20 15:20:10.757 UTC [50161][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:10.758 UTC [50161][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/4188120, redo lsn=0/4188120 2024-03-20 15:20:10.760 UTC [50160][postmaster] LOG: database system is shut down 2024-03-20 15:20:10.874 UTC [50170][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:10.874 UTC [50170][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:10.874 UTC [50170][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:10.876 UTC [50173][startup] LOG: database system was shut down at 2024-03-20 15:20:10 UTC 2024-03-20 15:20:10.880 UTC [50170][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:10.965 UTC [50178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56214 2024-03-20 15:20:10.969 UTC [50178][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-20 15:20:10.969 UTC [50178][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-20 15:20:10.979 UTC [50180][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56216 2024-03-20 15:20:10.983 UTC [50180][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-20 15:20:10.983 UTC [50180][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-20 15:20:10.987 UTC [50170][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:10.987 UTC [50170][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:10.989 UTC [50170][postmaster] LOG: background worker "logical replication launcher" (PID 50176) exited with exit code 1 2024-03-20 15:20:10.989 UTC [50171][checkpointer] LOG: shutting down 2024-03-20 15:20:10.989 UTC [50171][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:10.990 UTC [50171][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/4188198, redo lsn=0/4188198 2024-03-20 15:20:10.993 UTC [50170][postmaster] LOG: database system is shut down 2024-03-20 15:20:11.107 UTC [50182][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:11.107 UTC [50182][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:11.107 UTC [50182][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:11.109 UTC [50185][startup] LOG: database system was shut down at 2024-03-20 15:20:10 UTC 2024-03-20 15:20:11.113 UTC [50182][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:11.197 UTC [50190][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56232 2024-03-20 15:20:11.198 UTC [50190][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:11.198 UTC [50190][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:11.208 UTC [50192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56244 2024-03-20 15:20:11.212 UTC [50192][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-20 15:20:11.212 UTC [50192][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-20 15:20:11.217 UTC [50192][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-20 15:20:11.217 UTC [50192][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56244 2024-03-20 15:20:11.221 UTC [50182][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:11.221 UTC [50182][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:11.223 UTC [50182][postmaster] LOG: background worker "logical replication launcher" (PID 50188) exited with exit code 1 2024-03-20 15:20:11.223 UTC [50183][checkpointer] LOG: shutting down 2024-03-20 15:20:11.223 UTC [50183][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:11.223 UTC [50183][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/4188210, redo lsn=0/4188210 2024-03-20 15:20:11.226 UTC [50182][postmaster] LOG: database system is shut down 2024-03-20 15:20:11.340 UTC [50194][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:11.340 UTC [50194][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:11.340 UTC [50194][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:11.342 UTC [50197][startup] LOG: database system was shut down at 2024-03-20 15:20:11 UTC 2024-03-20 15:20:11.346 UTC [50194][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:11.430 UTC [50202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56246 2024-03-20 15:20:11.432 UTC [50202][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:11.432 UTC [50202][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:11.443 UTC [50204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56254 2024-03-20 15:20:11.446 UTC [50204][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-20 15:20:11.446 UTC [50204][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-20 15:20:11.451 UTC [50204][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-20 15:20:11.452 UTC [50204][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56254 2024-03-20 15:20:11.455 UTC [50194][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:11.455 UTC [50194][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:11.457 UTC [50194][postmaster] LOG: background worker "logical replication launcher" (PID 50200) exited with exit code 1 2024-03-20 15:20:11.457 UTC [50195][checkpointer] LOG: shutting down 2024-03-20 15:20:11.457 UTC [50195][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:11.458 UTC [50195][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/4188288, redo lsn=0/4188288 2024-03-20 15:20:11.461 UTC [50194][postmaster] LOG: database system is shut down 2024-03-20 15:20:11.574 UTC [50206][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:11.574 UTC [50206][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:11.574 UTC [50206][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:11.576 UTC [50209][startup] LOG: database system was shut down at 2024-03-20 15:20:11 UTC 2024-03-20 15:20:11.579 UTC [50206][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:11.667 UTC [50214][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56258 2024-03-20 15:20:11.669 UTC [50214][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:11.669 UTC [50214][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:11.678 UTC [50216][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56260 2024-03-20 15:20:11.682 UTC [50216][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-20 15:20:11.682 UTC [50216][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-20 15:20:11.687 UTC [50216][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-20 15:20:11.688 UTC [50216][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56260 2024-03-20 15:20:11.691 UTC [50206][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:11.691 UTC [50206][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:11.693 UTC [50206][postmaster] LOG: background worker "logical replication launcher" (PID 50212) exited with exit code 1 2024-03-20 15:20:11.693 UTC [50207][checkpointer] LOG: shutting down 2024-03-20 15:20:11.693 UTC [50207][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:11.694 UTC [50207][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/4188300, redo lsn=0/4188300 2024-03-20 15:20:11.696 UTC [50206][postmaster] LOG: database system is shut down 2024-03-20 15:20:11.810 UTC [50218][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:11.810 UTC [50218][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:11.810 UTC [50218][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:11.812 UTC [50221][startup] LOG: database system was shut down at 2024-03-20 15:20:11 UTC 2024-03-20 15:20:11.816 UTC [50218][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:11.901 UTC [50226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56272 2024-03-20 15:20:11.902 UTC [50226][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:11.902 UTC [50226][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:11.911 UTC [50228][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56278 2024-03-20 15:20:11.915 UTC [50228][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-20 15:20:11.915 UTC [50228][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-20 15:20:11.920 UTC [50228][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-20 15:20:11.920 UTC [50228][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56278 2024-03-20 15:20:11.924 UTC [50218][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:11.924 UTC [50218][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:11.926 UTC [50218][postmaster] LOG: background worker "logical replication launcher" (PID 50224) exited with exit code 1 2024-03-20 15:20:11.926 UTC [50219][checkpointer] LOG: shutting down 2024-03-20 15:20:11.926 UTC [50219][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:11.927 UTC [50219][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/4188378, redo lsn=0/4188378 2024-03-20 15:20:11.930 UTC [50218][postmaster] LOG: database system is shut down 2024-03-20 15:20:12.043 UTC [50230][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:12.043 UTC [50230][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:12.043 UTC [50230][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:12.045 UTC [50233][startup] LOG: database system was shut down at 2024-03-20 15:20:11 UTC 2024-03-20 15:20:12.048 UTC [50230][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:12.133 UTC [50238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56282 2024-03-20 15:20:12.134 UTC [50238][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:12.134 UTC [50238][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:12.145 UTC [50240][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56284 2024-03-20 15:20:12.148 UTC [50240][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-20 15:20:12.148 UTC [50240][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-20 15:20:12.153 UTC [50240][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-20 15:20:12.154 UTC [50240][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56284 2024-03-20 15:20:12.158 UTC [50230][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:12.158 UTC [50230][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:12.159 UTC [50230][postmaster] LOG: background worker "logical replication launcher" (PID 50236) exited with exit code 1 2024-03-20 15:20:12.159 UTC [50231][checkpointer] LOG: shutting down 2024-03-20 15:20:12.159 UTC [50231][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:12.160 UTC [50231][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/41883F0, redo lsn=0/41883F0 2024-03-20 15:20:12.162 UTC [50230][postmaster] LOG: database system is shut down 2024-03-20 15:20:12.275 UTC [50242][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:12.275 UTC [50242][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:12.275 UTC [50242][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:12.277 UTC [50245][startup] LOG: database system was shut down at 2024-03-20 15:20:12 UTC 2024-03-20 15:20:12.281 UTC [50242][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:12.366 UTC [50250][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56300 2024-03-20 15:20:12.368 UTC [50250][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:12.368 UTC [50250][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:12.377 UTC [50252][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56308 2024-03-20 15:20:12.381 UTC [50252][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-20 15:20:12.381 UTC [50252][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-20 15:20:12.386 UTC [50252][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-20 15:20:12.386 UTC [50252][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56308 2024-03-20 15:20:12.390 UTC [50242][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:12.390 UTC [50242][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:12.392 UTC [50242][postmaster] LOG: background worker "logical replication launcher" (PID 50248) exited with exit code 1 2024-03-20 15:20:12.392 UTC [50243][checkpointer] LOG: shutting down 2024-03-20 15:20:12.392 UTC [50243][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:12.392 UTC [50243][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/4188468, redo lsn=0/4188468 2024-03-20 15:20:12.395 UTC [50242][postmaster] LOG: database system is shut down 2024-03-20 15:20:12.508 UTC [50254][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:12.508 UTC [50254][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:12.508 UTC [50254][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:12.510 UTC [50257][startup] LOG: database system was shut down at 2024-03-20 15:20:12 UTC 2024-03-20 15:20:12.513 UTC [50254][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:12.599 UTC [50262][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56320 2024-03-20 15:20:12.600 UTC [50262][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:12.600 UTC [50262][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:12.608 UTC [50264][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56326 2024-03-20 15:20:12.612 UTC [50264][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-20 15:20:12.612 UTC [50264][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-20 15:20:12.617 UTC [50264][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-20 15:20:12.617 UTC [50264][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56326 2024-03-20 15:20:12.621 UTC [50254][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:12.621 UTC [50254][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:12.623 UTC [50254][postmaster] LOG: background worker "logical replication launcher" (PID 50260) exited with exit code 1 2024-03-20 15:20:12.623 UTC [50255][checkpointer] LOG: shutting down 2024-03-20 15:20:12.623 UTC [50255][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:12.623 UTC [50255][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/41884E0, redo lsn=0/41884E0 2024-03-20 15:20:12.626 UTC [50254][postmaster] LOG: database system is shut down 2024-03-20 15:20:12.739 UTC [50266][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:12.739 UTC [50266][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:12.739 UTC [50266][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:12.741 UTC [50269][startup] LOG: database system was shut down at 2024-03-20 15:20:12 UTC 2024-03-20 15:20:12.744 UTC [50266][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:12.830 UTC [50274][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56334 2024-03-20 15:20:12.831 UTC [50274][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:12.831 UTC [50274][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:12.839 UTC [50276][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56338 2024-03-20 15:20:12.842 UTC [50276][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-20 15:20:12.842 UTC [50276][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-20 15:20:12.847 UTC [50276][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-20 15:20:12.848 UTC [50276][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56338 2024-03-20 15:20:12.851 UTC [50266][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:12.851 UTC [50266][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:12.852 UTC [50266][postmaster] LOG: background worker "logical replication launcher" (PID 50272) exited with exit code 1 2024-03-20 15:20:12.852 UTC [50267][checkpointer] LOG: shutting down 2024-03-20 15:20:12.853 UTC [50267][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:12.853 UTC [50267][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/4188558, redo lsn=0/4188558 2024-03-20 15:20:12.856 UTC [50266][postmaster] LOG: database system is shut down 2024-03-20 15:20:12.969 UTC [50278][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-20 15:20:12.970 UTC [50278][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50342 2024-03-20 15:20:12.970 UTC [50278][postmaster] LOG: listening on Unix socket "/tmp/4ZrvsD_Snl/.s.PGSQL.50342" 2024-03-20 15:20:12.972 UTC [50281][startup] LOG: database system was shut down at 2024-03-20 15:20:12 UTC 2024-03-20 15:20:12.974 UTC [50278][postmaster] LOG: database system is ready to accept connections 2024-03-20 15:20:13.059 UTC [50286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56350 2024-03-20 15:20:13.061 UTC [50286][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-20 15:20:13.061 UTC [50286][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-20 15:20:13.069 UTC [50288][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=56358 2024-03-20 15:20:13.072 UTC [50288][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-20 15:20:13.072 UTC [50288][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-20 15:20:13.077 UTC [50288][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-20 15:20:13.077 UTC [50288][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=56358 2024-03-20 15:20:13.081 UTC [50278][postmaster] LOG: received fast shutdown request 2024-03-20 15:20:13.081 UTC [50278][postmaster] LOG: aborting any active transactions 2024-03-20 15:20:13.082 UTC [50278][postmaster] LOG: background worker "logical replication launcher" (PID 50284) exited with exit code 1 2024-03-20 15:20:13.082 UTC [50279][checkpointer] LOG: shutting down 2024-03-20 15:20:13.082 UTC [50279][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-20 15:20:13.082 UTC [50279][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/41885D0, redo lsn=0/41885D0 2024-03-20 15:20:13.085 UTC [50278][postmaster] LOG: database system is shut down 2024-03-20 15:20:13.193 UTC [50290][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-20 15:20:13.193 UTC [50290][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [15:20:13.282](0.204s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"