[14:09:38.195](0.039s) # setting up data directory # Checking port 57904 # Found port 57904 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=57904 host=/tmp/nQ2O3KJXeQ Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [14:09:38.201](0.006s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 48230 [14:09:38.424](0.223s) 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 48577 [14:09:39.561](1.137s) # 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-14 14:09:38.313 UTC [48230][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:38.313 UTC [48230][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:38.315 UTC [48241][startup] LOG: database system was shut down at 2024-03-14 14:07:20 UTC 2024-03-14 14:09:38.317 UTC [48230][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:38.416 UTC [48273][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.416 UTC [48273][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-14 14:09:38.416 UTC [48273][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.423 UTC [48273][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:09:38.425 UTC [48273][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.437 UTC [48282][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.438 UTC [48282][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-14 14:09:38.438 UTC [48282][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.439 UTC [48282][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:09:38.440 UTC [48282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.477 UTC [48303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.477 UTC [48303][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-14 14:09:38.477 UTC [48303][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.478 UTC [48303][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:09:38.479 UTC [48303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.520 UTC [48327][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.521 UTC [48327][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-14 14:09:38.521 UTC [48327][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.521 UTC [48327][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:09:38.522 UTC [48327][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.571 UTC [48344][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.571 UTC [48344][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-14 14:09:38.572 UTC [48344][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.573 UTC [48344][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:09:38.575 UTC [48344][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.612 UTC [48365][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.615 UTC [48365][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-14 14:09:38.615 UTC [48365][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.615 UTC [48365][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:09:38.644 UTC [48365][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.733 UTC [48413][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.734 UTC [48413][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-14 14:09:38.734 UTC [48413][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.735 UTC [48413][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:09:38.793 UTC [48413][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.059 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.855 UTC [48436][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.856 UTC [48436][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-14 14:09:38.856 UTC [48436][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.857 UTC [48436][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:09:38.883 UTC [48436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.904 UTC [48454][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.904 UTC [48454][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-14 14:09:38.904 UTC [48454][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.905 UTC [48454][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:09:39.040 UTC [48454][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.136 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.183 UTC [48500][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:39.184 UTC [48500][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-14 14:09:39.184 UTC [48500][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:39.185 UTC [48500][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:09:39.209 UTC [48500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.242 UTC [48518][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:39.242 UTC [48518][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-14 14:09:39.242 UTC [48518][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:39.243 UTC [48518][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:09:39.304 UTC [48518][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.359 UTC [48230][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.359 UTC [48230][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.362 UTC [48230][postmaster] LOG: background worker "logical replication launcher" (PID 48244) exited with exit code 1 2024-03-14 14:09:39.362 UTC [48238][checkpointer] LOG: shutting down 2024-03-14 14:09:39.362 UTC [48238][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.397 UTC [48238][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.035 s, sync=0.001 s, total=0.036 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196338, redo lsn=0/4196338 2024-03-14 14:09:39.409 UTC [48230][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:39.483 UTC [48583][startup] LOG: database system was shut down at 2024-03-14 14:09:39 UTC 2024-03-14 14:09:39.486 UTC [48577][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:39.563 UTC [48577][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.563 UTC [48577][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.566 UTC [48577][postmaster] LOG: background worker "logical replication launcher" (PID 48586) exited with exit code 1 2024-03-14 14:09:39.567 UTC [48581][checkpointer] LOG: shutting down 2024-03-14 14:09:39.567 UTC [48581][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.567 UTC [48581][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/41963B0, redo lsn=0/41963B0 2024-03-14 14:09:39.570 UTC [48577][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.691 UTC [48637][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:09:39.691 UTC [48637][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:09:39.768](0.207s) 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 48669 [14:09:39.872](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-14 14:09:38.313 UTC [48230][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:38.313 UTC [48230][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:38.315 UTC [48241][startup] LOG: database system was shut down at 2024-03-14 14:07:20 UTC 2024-03-14 14:09:38.317 UTC [48230][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:38.416 UTC [48273][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.416 UTC [48273][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-14 14:09:38.416 UTC [48273][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.423 UTC [48273][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:09:38.425 UTC [48273][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.437 UTC [48282][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.438 UTC [48282][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-14 14:09:38.438 UTC [48282][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.439 UTC [48282][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:09:38.440 UTC [48282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.477 UTC [48303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.477 UTC [48303][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-14 14:09:38.477 UTC [48303][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.478 UTC [48303][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:09:38.479 UTC [48303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.520 UTC [48327][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.521 UTC [48327][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-14 14:09:38.521 UTC [48327][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.521 UTC [48327][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:09:38.522 UTC [48327][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.571 UTC [48344][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.571 UTC [48344][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-14 14:09:38.572 UTC [48344][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.573 UTC [48344][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:09:38.575 UTC [48344][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.612 UTC [48365][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.615 UTC [48365][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-14 14:09:38.615 UTC [48365][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.615 UTC [48365][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:09:38.644 UTC [48365][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.733 UTC [48413][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.734 UTC [48413][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-14 14:09:38.734 UTC [48413][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.735 UTC [48413][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:09:38.793 UTC [48413][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.059 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.855 UTC [48436][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.856 UTC [48436][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-14 14:09:38.856 UTC [48436][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.857 UTC [48436][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:09:38.883 UTC [48436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.904 UTC [48454][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.904 UTC [48454][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-14 14:09:38.904 UTC [48454][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.905 UTC [48454][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:09:39.040 UTC [48454][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.136 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.183 UTC [48500][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:39.184 UTC [48500][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-14 14:09:39.184 UTC [48500][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:39.185 UTC [48500][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:09:39.209 UTC [48500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.242 UTC [48518][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:39.242 UTC [48518][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-14 14:09:39.242 UTC [48518][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:39.243 UTC [48518][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:09:39.304 UTC [48518][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.359 UTC [48230][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.359 UTC [48230][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.362 UTC [48230][postmaster] LOG: background worker "logical replication launcher" (PID 48244) exited with exit code 1 2024-03-14 14:09:39.362 UTC [48238][checkpointer] LOG: shutting down 2024-03-14 14:09:39.362 UTC [48238][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.397 UTC [48238][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.035 s, sync=0.001 s, total=0.036 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196338, redo lsn=0/4196338 2024-03-14 14:09:39.409 UTC [48230][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:39.483 UTC [48583][startup] LOG: database system was shut down at 2024-03-14 14:09:39 UTC 2024-03-14 14:09:39.486 UTC [48577][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:39.563 UTC [48577][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.563 UTC [48577][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.566 UTC [48577][postmaster] LOG: background worker "logical replication launcher" (PID 48586) exited with exit code 1 2024-03-14 14:09:39.567 UTC [48581][checkpointer] LOG: shutting down 2024-03-14 14:09:39.567 UTC [48581][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.567 UTC [48581][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/41963B0, redo lsn=0/41963B0 2024-03-14 14:09:39.570 UTC [48577][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.691 UTC [48637][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:09:39.691 UTC [48637][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.789 UTC [48669][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:39.789 UTC [48669][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:39.789 UTC [48669][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:39.791 UTC [48680][startup] LOG: database system was shut down at 2024-03-14 14:09:39 UTC 2024-03-14 14:09:39.794 UTC [48669][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:39.874 UTC [48669][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.874 UTC [48669][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.875 UTC [48669][postmaster] LOG: background worker "logical replication launcher" (PID 48686) exited with exit code 1 2024-03-14 14:09:39.877 UTC [48678][checkpointer] LOG: shutting down 2024-03-14 14:09:39.877 UTC [48678][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.878 UTC [48678][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/4196428, redo lsn=0/4196428 2024-03-14 14:09:39.880 UTC [48669][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.987 UTC [48731][postmaster] FATAL: could not set SSL protocol version range 2024-03-14 14:09:39.987 UTC [48731][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-14 14:09:39.987 UTC [48731][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:09:40.075](0.203s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 48754 [14:09:40.178](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [14:09:40.178](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 48813 [14:09:40.391](0.212s) ok 6 - server doesn't accept non-SSL connections [14:09:40.391](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [14:09:40.407](0.016s) ok 8 - connect without server root cert sslmode=require [14:09:40.407](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [14:09:40.416](0.009s) ok 10 - connect without server root cert sslmode=verify-ca [14:09:40.416](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [14:09:40.425](0.009s) ok 12 - connect without server root cert sslmode=verify-full [14:09:40.425](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [14:09:40.435](0.009s) ok 14 - connect with wrong server root cert sslmode=require [14:09:40.435](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [14:09:40.444](0.009s) ok 16 - connect with wrong server root cert sslmode=verify-ca [14:09:40.444](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [14:09:40.453](0.009s) ok 18 - connect with wrong server root cert sslmode=verify-full [14:09:40.454](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [14:09:40.463](0.010s) ok 20 - connect with server CA cert, without root CA [14:09:40.463](0.000s) ok 21 - connect with server CA cert, without root CA: matches [14:09:40.475](0.011s) ok 22 - connect with correct server CA cert file sslmode=require [14:09:40.475](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [14:09:40.487](0.012s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [14:09:40.487](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [14:09:40.502](0.014s) ok 26 - connect with correct server CA cert file sslmode=verify-full [14:09:40.502](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [14:09:40.514](0.012s) ok 28 - cert root file that contains two certificates, order 1 [14:09:40.514](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [14:09:40.526](0.012s) ok 30 - cert root file that contains two certificates, order 2 [14:09:40.526](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [14:09:40.538](0.012s) ok 32 - connect with sslcertmode=disable [14:09:40.539](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [14:09:40.550](0.012s) ok 34 - connect with sslcertmode=allow [14:09:40.550](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [14:09:40.561](0.011s) ok 36 - connect with sslcertmode=require fails without a client certificate [14:09:40.562](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [14:09:40.574](0.012s) ok 38 - sslcrl option with invalid file name [14:09:40.574](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [14:09:40.584](0.010s) ok 40 - CRL belonging to a different CA [14:09:40.584](0.000s) ok 41 - CRL belonging to a different CA: matches [14:09:40.595](0.010s) ok 42 - directory CRL belonging to a different CA [14:09:40.595](0.000s) ok 43 - directory CRL belonging to a different CA: matches [14:09:40.610](0.015s) ok 44 - CRL with a non-revoked cert [14:09:40.610](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [14:09:40.625](0.015s) ok 46 - directory CRL with a non-revoked cert [14:09:40.625](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [14:09:40.639](0.014s) ok 48 - mismatch between host name and server certificate sslmode=require [14:09:40.639](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [14:09:40.652](0.013s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [14:09:40.653](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [14:09:40.664](0.011s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [14:09:40.664](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 48906 [14:09:40.884](0.220s) ok 54 - IP address in the Common Name [14:09:40.884](0.000s) ok 55 - IP address in the Common Name: no stderr [14:09:40.894](0.010s) ok 56 - mismatch between host name and server certificate IP address [14:09:40.894](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 48930 [14:09:41.113](0.219s) ok 58 - IP address in a dNSName [14:09:41.113](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 48954 [14:09:41.333](0.220s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [14:09:41.333](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [14:09:41.346](0.013s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [14:09:41.346](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [14:09:41.359](0.013s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [14:09:41.359](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [14:09:41.369](0.010s) ok 66 - host name not matching with X.509 Subject Alternative Names [14:09:41.369](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [14:09:41.379](0.010s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [14:09:41.380](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 48987 [14:09:41.599](0.219s) ok 70 - host name matching with a single X.509 Subject Alternative Name [14:09:41.599](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [14:09:41.609](0.010s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [14:09:41.609](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [14:09:41.619](0.010s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [14:09:41.619](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 49012 [14:09:41.842](0.223s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [14:09:41.842](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [14:09:41.855](0.013s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [14:09:41.856](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [14:09:41.867](0.011s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [14:09:41.867](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [14:09:41.879](0.012s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [14:09:41.879](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [14:09:41.890](0.012s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [14:09:41.891](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [14:09:41.901](0.011s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [14:09:41.902](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [14:09:41.911](0.009s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [14:09:41.911](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [14:09:41.920](0.009s) ok 90 - IPv6 host with CIDR mask does not match [14:09:41.921](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 49036 [14:09:42.140](0.220s) ok 92 - certificate with both a CN and SANs 1 [14:09:42.140](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [14:09:42.153](0.012s) ok 94 - certificate with both a CN and SANs 2 [14:09:42.153](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [14:09:42.163](0.010s) ok 96 - certificate with both a CN and SANs ignores CN [14:09:42.163](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 49051 [14:09:42.385](0.222s) ok 98 - certificate with both a CN and IP SANs matches CN [14:09:42.385](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [14:09:42.397](0.011s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [14:09:42.397](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [14:09:42.408](0.011s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [14:09:42.408](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 49065 [14:09:42.628](0.220s) ok 104 - certificate with both an IP CN and IP SANs 1 [14:09:42.628](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [14:09:42.640](0.012s) ok 106 - certificate with both an IP CN and IP SANs 2 [14:09:42.640](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [14:09:42.651](0.010s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [14:09:42.651](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 49079 [14:09:42.871](0.220s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [14:09:42.872](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [14:09:42.884](0.012s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [14:09:42.884](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [14:09:42.895](0.011s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [14:09:42.895](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 49093 [14:09:43.116](0.221s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [14:09:43.116](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [14:09:43.127](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full [14:09:43.127](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 49105 [14:09:43.341](0.214s) ok 120 - sslrootcert=system does not connect with private CA [14:09:43.341](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [14:09:43.347](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [14:09:43.347](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [14:09:43.362](0.015s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [14:09:43.362](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [14:09:43.371](0.010s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [14:09:43.371](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 49120 [14:09:43.593](0.221s) ok 128 - connects without client-side CRL [14:09:43.593](0.000s) ok 129 - connects without client-side CRL: no stderr [14:09:43.604](0.011s) ok 130 - does not connect with client-side CRL file [14:09:43.604](0.000s) ok 131 - does not connect with client-side CRL file: matches [14:09:43.615](0.011s) ok 132 - does not connect with client-side CRL directory [14:09:43.615](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() [14:09:43.628](0.012s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [14:09:43.628](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [14:09:43.628](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [14:09:43.641](0.012s) ok 137 - connection success with correct range of TLS protocol versions [14:09:43.641](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [14:09:43.647](0.006s) ok 139 - connection failure with incorrect range of TLS protocol versions [14:09:43.648](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [14:09:43.654](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [14:09:43.654](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [14:09:43.659](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [14:09:43.660](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [14:09:43.660](0.000s) # running server tests [14:09:43.671](0.011s) ok 145 - certificate authorization fails without client cert [14:09:43.671](0.000s) ok 146 - certificate authorization fails without client cert: matches [14:09:43.688](0.017s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [14:09:43.688](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [14:09:43.702](0.014s) ok 149 - certificate authorization succeeds with correct client cert in DER format [14:09:43.702](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [14:09:43.716](0.014s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [14:09:43.717](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [14:09:43.731](0.015s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [14:09:43.731](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [14:09:43.746](0.014s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [14:09:43.746](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [14:09:43.759](0.013s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [14:09:43.759](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [14:09:43.770](0.011s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [14:09:43.770](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [14:09:43.779](0.008s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [14:09:43.779](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [14:09:43.796](0.017s) ok 163 - certificate authorization succeeds with DN mapping [14:09:43.796](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [14:09:43.796](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [14:09:43.814](0.017s) ok 166 - certificate authorization succeeds with DN regex mapping [14:09:43.814](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [14:09:43.830](0.016s) ok 168 - certificate authorization succeeds with CN mapping [14:09:43.830](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [14:09:43.831](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [14:09:43.831](0.000s) not ok 171 # TODO & SKIP Need Pty support [14:09:43.831](0.000s) not ok 172 # TODO & SKIP Need Pty support [14:09:43.831](0.000s) not ok 173 # TODO & SKIP Need Pty support [14:09:43.831](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_XqZG/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [14:09:43.854](0.023s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [14:09:43.854](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [14:09:43.854](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [14:09:43.862](0.008s) ok 178 - certificate authorization fails because of file permissions [14:09:43.862](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [14:09:43.874](0.012s) ok 180 - certificate authorization fails with client cert belonging to another user [14:09:43.874](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [14:09:43.886](0.012s) ok 182 - certificate authorization fails with revoked client cert [14:09:43.886](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [14:09:43.886](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [14:09:43.903](0.017s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [14:09:43.903](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [14:09:43.904](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [14:09:43.916](0.013s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [14:09:43.916](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [14:09:43.917](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [14:09:43.930](0.014s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [14:09:43.931](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [14:09:43.931](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 49180 [14:09:44.153](0.222s) ok 194 - intermediate client certificate is provided by client [14:09:44.154](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [14:09:44.165](0.012s) ok 196 - intermediate client certificate is missing [14:09:44.166](0.000s) ok 197 - intermediate client certificate is missing: matches [14:09:44.177](0.012s) ok 198 - logged client certificate Subjects are truncated if they're too long [14:09:44.178](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 49194 [14:09:44.394](0.216s) ok 200 - intermediate client certificate is untrusted [14:09:44.394](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 49204 [14:09:44.611](0.217s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [14:09:44.612](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [14:09:44.624](0.012s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [14:09:44.624](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 49216 [14:09:44.838](0.214s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [14:09:44.838](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. [14:09:44.838](0.000s) # got: '2' # expected: '0' [14:09:44.838](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:09:44.838](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. [14:09:44.838](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 57904 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: '' [14:09:44.854](0.016s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [14:09:44.854](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 49228 [14:09:45.069](0.214s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:45.084](0.016s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [14:09:45.085](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 49240 [14:09:45.299](0.214s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:45.315](0.016s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [14:09:45.315](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 49252 [14:09:45.530](0.215s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [14:09:45.546](0.016s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [14:09:45.546](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 49264 [14:09:45.761](0.215s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [14:09:45.761](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. [14:09:45.761](0.000s) # got: '2' # expected: '0' [14:09:45.761](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:09:45.762](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. [14:09:45.762](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 57904 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: '' [14:09:45.778](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [14:09:45.778](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 49276 [14:09:45.992](0.214s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:46.008](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [14:09:46.008](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 49288 [14:09:46.222](0.214s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:46.237](0.015s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [14:09:46.237](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 49300 [14:09:46.450](0.213s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [14:09:46.466](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [14:09:46.466](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 49312 [14:09:46.679](0.213s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [14:09:46.695](0.015s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [14:09:46.695](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-14 14:09:38.313 UTC [48230][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:38.313 UTC [48230][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:38.315 UTC [48241][startup] LOG: database system was shut down at 2024-03-14 14:07:20 UTC 2024-03-14 14:09:38.317 UTC [48230][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:38.416 UTC [48273][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.416 UTC [48273][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-14 14:09:38.416 UTC [48273][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.423 UTC [48273][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:09:38.425 UTC [48273][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.437 UTC [48282][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.438 UTC [48282][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-14 14:09:38.438 UTC [48282][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.439 UTC [48282][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:09:38.440 UTC [48282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.477 UTC [48303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.477 UTC [48303][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-14 14:09:38.477 UTC [48303][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.478 UTC [48303][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:09:38.479 UTC [48303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.520 UTC [48327][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.521 UTC [48327][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-14 14:09:38.521 UTC [48327][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.521 UTC [48327][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:09:38.522 UTC [48327][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.571 UTC [48344][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.571 UTC [48344][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-14 14:09:38.572 UTC [48344][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.573 UTC [48344][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:09:38.575 UTC [48344][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.612 UTC [48365][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.615 UTC [48365][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-14 14:09:38.615 UTC [48365][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.615 UTC [48365][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:09:38.644 UTC [48365][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.733 UTC [48413][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.734 UTC [48413][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-14 14:09:38.734 UTC [48413][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.735 UTC [48413][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:09:38.793 UTC [48413][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.059 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.855 UTC [48436][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.856 UTC [48436][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-14 14:09:38.856 UTC [48436][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.857 UTC [48436][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:09:38.883 UTC [48436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-14 14:09:38.904 UTC [48454][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:38.904 UTC [48454][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-14 14:09:38.904 UTC [48454][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:38.905 UTC [48454][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:09:39.040 UTC [48454][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.136 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.183 UTC [48500][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:39.184 UTC [48500][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-14 14:09:39.184 UTC [48500][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:39.185 UTC [48500][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:09:39.209 UTC [48500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.242 UTC [48518][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:39.242 UTC [48518][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-14 14:09:39.242 UTC [48518][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:39.243 UTC [48518][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:09:39.304 UTC [48518][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local] 2024-03-14 14:09:39.359 UTC [48230][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.359 UTC [48230][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.362 UTC [48230][postmaster] LOG: background worker "logical replication launcher" (PID 48244) exited with exit code 1 2024-03-14 14:09:39.362 UTC [48238][checkpointer] LOG: shutting down 2024-03-14 14:09:39.362 UTC [48238][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.397 UTC [48238][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.035 s, sync=0.001 s, total=0.036 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196338, redo lsn=0/4196338 2024-03-14 14:09:39.409 UTC [48230][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:39.478 UTC [48577][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:39.483 UTC [48583][startup] LOG: database system was shut down at 2024-03-14 14:09:39 UTC 2024-03-14 14:09:39.486 UTC [48577][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:39.563 UTC [48577][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.563 UTC [48577][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.566 UTC [48577][postmaster] LOG: background worker "logical replication launcher" (PID 48586) exited with exit code 1 2024-03-14 14:09:39.567 UTC [48581][checkpointer] LOG: shutting down 2024-03-14 14:09:39.567 UTC [48581][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.567 UTC [48581][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/41963B0, redo lsn=0/41963B0 2024-03-14 14:09:39.570 UTC [48577][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.691 UTC [48637][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:09:39.691 UTC [48637][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.789 UTC [48669][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:39.789 UTC [48669][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:39.789 UTC [48669][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:39.791 UTC [48680][startup] LOG: database system was shut down at 2024-03-14 14:09:39 UTC 2024-03-14 14:09:39.794 UTC [48669][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:39.874 UTC [48669][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:39.874 UTC [48669][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:39.875 UTC [48669][postmaster] LOG: background worker "logical replication launcher" (PID 48686) exited with exit code 1 2024-03-14 14:09:39.877 UTC [48678][checkpointer] LOG: shutting down 2024-03-14 14:09:39.877 UTC [48678][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:39.878 UTC [48678][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/4196428, redo lsn=0/4196428 2024-03-14 14:09:39.880 UTC [48669][postmaster] LOG: database system is shut down 2024-03-14 14:09:39.987 UTC [48731][postmaster] FATAL: could not set SSL protocol version range 2024-03-14 14:09:39.987 UTC [48731][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-14 14:09:39.987 UTC [48731][postmaster] LOG: database system is shut down 2024-03-14 14:09:40.095 UTC [48754][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:40.095 UTC [48754][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:40.095 UTC [48754][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:40.097 UTC [48760][startup] LOG: database system was shut down at 2024-03-14 14:09:39 UTC 2024-03-14 14:09:40.100 UTC [48754][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:40.181 UTC [48754][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:40.181 UTC [48754][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:40.183 UTC [48754][postmaster] LOG: background worker "logical replication launcher" (PID 48764) exited with exit code 1 2024-03-14 14:09:40.183 UTC [48758][checkpointer] LOG: shutting down 2024-03-14 14:09:40.183 UTC [48758][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:40.184 UTC [48758][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/41964A0, redo lsn=0/41964A0 2024-03-14 14:09:40.187 UTC [48754][postmaster] LOG: database system is shut down 2024-03-14 14:09:40.298 UTC [48813][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:40.298 UTC [48813][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:40.299 UTC [48813][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:40.301 UTC [48819][startup] LOG: database system was shut down at 2024-03-14 14:09:40 UTC 2024-03-14 14:09:40.304 UTC [48813][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:40.389 UTC [48833][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42346 2024-03-14 14:09:40.389 UTC [48833][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-14 14:09:40.389 UTC [48833][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-14 14:09:40.397 UTC [48838][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42358 2024-03-14 14:09:40.401 UTC [48838][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-14 14:09:40.401 UTC [48838][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-14 14:09:40.406 UTC [48838][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-14 14:09:40.406 UTC [48838][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42358 2024-03-14 14:09:40.414 UTC [48844][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42374 2024-03-14 14:09:40.415 UTC [48844][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:40.423 UTC [48847][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42380 2024-03-14 14:09:40.424 UTC [48847][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:40.431 UTC [48849][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42394 2024-03-14 14:09:40.434 UTC [48849][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:40.440 UTC [48851][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42410 2024-03-14 14:09:40.443 UTC [48851][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:40.450 UTC [48853][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42416 2024-03-14 14:09:40.453 UTC [48853][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:40.459 UTC [48855][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42432 2024-03-14 14:09:40.462 UTC [48855][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:40.469 UTC [48857][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42438 2024-03-14 14:09:40.473 UTC [48857][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-14 14:09:40.473 UTC [48857][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-14 14:09:40.474 UTC [48857][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-14 14:09:40.474 UTC [48857][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42438 2024-03-14 14:09:40.481 UTC [48860][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42452 2024-03-14 14:09:40.484 UTC [48860][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-14 14:09:40.484 UTC [48860][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-14 14:09:40.485 UTC [48860][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-14 14:09:40.486 UTC [48860][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42452 2024-03-14 14:09:40.493 UTC [48863][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42462 2024-03-14 14:09:40.499 UTC [48863][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-14 14:09:40.499 UTC [48863][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-14 14:09:40.500 UTC [48863][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-14 14:09:40.500 UTC [48863][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=42462 2024-03-14 14:09:40.508 UTC [48866][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42476 2024-03-14 14:09:40.512 UTC [48866][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-14 14:09:40.512 UTC [48866][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-14 14:09:40.513 UTC [48866][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-14 14:09:40.513 UTC [48866][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42476 2024-03-14 14:09:40.520 UTC [48868][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42486 2024-03-14 14:09:40.524 UTC [48868][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-14 14:09:40.524 UTC [48868][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-14 14:09:40.524 UTC [48868][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-14 14:09:40.525 UTC [48868][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42486 2024-03-14 14:09:40.533 UTC [48870][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42488 2024-03-14 14:09:40.536 UTC [48870][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-14 14:09:40.536 UTC [48870][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-14 14:09:40.537 UTC [48870][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-14 14:09:40.537 UTC [48870][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42488 2024-03-14 14:09:40.545 UTC [48872][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42498 2024-03-14 14:09:40.548 UTC [48872][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-14 14:09:40.548 UTC [48872][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-14 14:09:40.549 UTC [48872][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-14 14:09:40.549 UTC [48872][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42498 2024-03-14 14:09:40.556 UTC [48874][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42500 2024-03-14 14:09:40.560 UTC [48874][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-14 14:09:40.560 UTC [48874][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-14 14:09:40.561 UTC [48874][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42500 2024-03-14 14:09:40.568 UTC [48877][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42510 2024-03-14 14:09:40.571 UTC [48877][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-14 14:09:40.571 UTC [48877][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-14 14:09:40.572 UTC [48877][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-14 14:09:40.573 UTC [48877][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42510 2024-03-14 14:09:40.580 UTC [48880][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42524 2024-03-14 14:09:40.583 UTC [48880][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:40.590 UTC [48882][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42538 2024-03-14 14:09:40.593 UTC [48882][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:40.602 UTC [48885][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42542 2024-03-14 14:09:40.607 UTC [48885][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-14 14:09:40.607 UTC [48885][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-14 14:09:40.608 UTC [48885][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-14 14:09:40.608 UTC [48885][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42542 2024-03-14 14:09:40.617 UTC [48887][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42554 2024-03-14 14:09:40.621 UTC [48887][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-14 14:09:40.621 UTC [48887][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-14 14:09:40.622 UTC [48887][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-14 14:09:40.626 UTC [48887][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42554 2024-03-14 14:09:40.632 UTC [48895][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42562 2024-03-14 14:09:40.636 UTC [48895][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-14 14:09:40.636 UTC [48895][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-14 14:09:40.637 UTC [48895][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-14 14:09:40.637 UTC [48895][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42562 2024-03-14 14:09:40.647 UTC [48897][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42572 2024-03-14 14:09:40.650 UTC [48897][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-14 14:09:40.650 UTC [48897][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-14 14:09:40.651 UTC [48897][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-14 14:09:40.651 UTC [48897][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42572 2024-03-14 14:09:40.659 UTC [48899][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42582 2024-03-14 14:09:40.666 UTC [48813][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:40.666 UTC [48813][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:40.667 UTC [48813][postmaster] LOG: background worker "logical replication launcher" (PID 48823) exited with exit code 1 2024-03-14 14:09:40.667 UTC [48817][checkpointer] LOG: shutting down 2024-03-14 14:09:40.668 UTC [48817][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:40.668 UTC [48817][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/4196518, redo lsn=0/4196518 2024-03-14 14:09:40.670 UTC [48813][postmaster] LOG: database system is shut down 2024-03-14 14:09:40.784 UTC [48906][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:40.784 UTC [48906][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:40.784 UTC [48906][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:40.786 UTC [48909][startup] LOG: database system was shut down at 2024-03-14 14:09:40 UTC 2024-03-14 14:09:40.789 UTC [48906][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:40.874 UTC [48921][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42584 2024-03-14 14:09:40.877 UTC [48921][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-14 14:09:40.877 UTC [48921][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-14 14:09:40.882 UTC [48921][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-14 14:09:40.882 UTC [48921][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42584 2024-03-14 14:09:40.890 UTC [48925][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42592 2024-03-14 14:09:40.896 UTC [48906][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:40.896 UTC [48906][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:40.897 UTC [48906][postmaster] LOG: background worker "logical replication launcher" (PID 48912) exited with exit code 1 2024-03-14 14:09:40.897 UTC [48907][checkpointer] LOG: shutting down 2024-03-14 14:09:40.897 UTC [48907][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:40.897 UTC [48907][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/4196590, redo lsn=0/4196590 2024-03-14 14:09:40.900 UTC [48906][postmaster] LOG: database system is shut down 2024-03-14 14:09:41.012 UTC [48930][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:41.012 UTC [48930][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:41.012 UTC [48930][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:41.014 UTC [48933][startup] LOG: database system was shut down at 2024-03-14 14:09:40 UTC 2024-03-14 14:09:41.017 UTC [48930][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:41.103 UTC [48947][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42600 2024-03-14 14:09:41.107 UTC [48947][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:41.107 UTC [48947][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-14 14:09:41.112 UTC [48947][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-14 14:09:41.112 UTC [48947][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42600 2024-03-14 14:09:41.115 UTC [48930][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:41.115 UTC [48930][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:41.117 UTC [48930][postmaster] LOG: background worker "logical replication launcher" (PID 48936) exited with exit code 1 2024-03-14 14:09:41.117 UTC [48931][checkpointer] LOG: shutting down 2024-03-14 14:09:41.117 UTC [48931][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:41.117 UTC [48931][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/4196608, redo lsn=0/4196608 2024-03-14 14:09:41.119 UTC [48930][postmaster] LOG: database system is shut down 2024-03-14 14:09:41.232 UTC [48954][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:41.233 UTC [48954][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:41.233 UTC [48954][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:41.235 UTC [48957][startup] LOG: database system was shut down at 2024-03-14 14:09:41 UTC 2024-03-14 14:09:41.237 UTC [48954][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:41.323 UTC [48971][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42616 2024-03-14 14:09:41.327 UTC [48971][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-14 14:09:41.327 UTC [48971][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-14 14:09:41.332 UTC [48971][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-14 14:09:41.332 UTC [48971][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42616 2024-03-14 14:09:41.340 UTC [48973][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42620 2024-03-14 14:09:41.344 UTC [48973][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-14 14:09:41.344 UTC [48973][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-14 14:09:41.345 UTC [48973][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-14 14:09:41.345 UTC [48973][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42620 2024-03-14 14:09:41.353 UTC [48977][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42622 2024-03-14 14:09:41.356 UTC [48977][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-14 14:09:41.356 UTC [48977][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-14 14:09:41.357 UTC [48977][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-14 14:09:41.358 UTC [48977][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42622 2024-03-14 14:09:41.365 UTC [48979][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42638 2024-03-14 14:09:41.375 UTC [48982][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42650 2024-03-14 14:09:41.381 UTC [48954][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:41.381 UTC [48954][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:41.382 UTC [48954][postmaster] LOG: background worker "logical replication launcher" (PID 48960) exited with exit code 1 2024-03-14 14:09:41.383 UTC [48955][checkpointer] LOG: shutting down 2024-03-14 14:09:41.383 UTC [48955][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:41.384 UTC [48955][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196680, redo lsn=0/4196680 2024-03-14 14:09:41.386 UTC [48954][postmaster] LOG: database system is shut down 2024-03-14 14:09:41.499 UTC [48987][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:41.499 UTC [48987][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:41.499 UTC [48987][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:41.501 UTC [48991][startup] LOG: database system was shut down at 2024-03-14 14:09:41 UTC 2024-03-14 14:09:41.504 UTC [48987][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:41.589 UTC [49002][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42662 2024-03-14 14:09:41.593 UTC [49002][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-14 14:09:41.593 UTC [49002][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-14 14:09:41.598 UTC [49002][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-14 14:09:41.598 UTC [49002][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42662 2024-03-14 14:09:41.605 UTC [49005][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42678 2024-03-14 14:09:41.615 UTC [49008][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42686 2024-03-14 14:09:41.624 UTC [48987][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:41.624 UTC [48987][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:41.625 UTC [48987][postmaster] LOG: background worker "logical replication launcher" (PID 48994) exited with exit code 1 2024-03-14 14:09:41.625 UTC [48989][checkpointer] LOG: shutting down 2024-03-14 14:09:41.625 UTC [48989][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:41.625 UTC [48989][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/41966F8, redo lsn=0/41966F8 2024-03-14 14:09:41.628 UTC [48987][postmaster] LOG: database system is shut down 2024-03-14 14:09:41.741 UTC [49012][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:41.741 UTC [49012][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:41.741 UTC [49012][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:41.743 UTC [49015][startup] LOG: database system was shut down at 2024-03-14 14:09:41 UTC 2024-03-14 14:09:41.746 UTC [49012][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:41.832 UTC [49020][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42698 2024-03-14 14:09:41.836 UTC [49020][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-14 14:09:41.836 UTC [49020][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-14 14:09:41.841 UTC [49020][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-14 14:09:41.841 UTC [49020][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42698 2024-03-14 14:09:41.849 UTC [49022][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42704 2024-03-14 14:09:41.853 UTC [49022][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-14 14:09:41.853 UTC [49022][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-14 14:09:41.854 UTC [49022][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-14 14:09:41.854 UTC [49022][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42704 2024-03-14 14:09:41.862 UTC [49024][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42718 2024-03-14 14:09:41.873 UTC [49026][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42730 2024-03-14 14:09:41.876 UTC [49026][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-14 14:09:41.876 UTC [49026][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-14 14:09:41.877 UTC [49026][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-14 14:09:41.878 UTC [49026][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42730 2024-03-14 14:09:41.885 UTC [49028][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42732 2024-03-14 14:09:41.888 UTC [49028][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-14 14:09:41.888 UTC [49028][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-14 14:09:41.889 UTC [49028][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-14 14:09:41.889 UTC [49028][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42732 2024-03-14 14:09:41.896 UTC [49030][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42744 2024-03-14 14:09:41.900 UTC [49030][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-14 14:09:41.900 UTC [49030][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-14 14:09:41.900 UTC [49030][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-14 14:09:41.901 UTC [49030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42744 2024-03-14 14:09:41.907 UTC [49032][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42758 2024-03-14 14:09:41.917 UTC [49034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42760 2024-03-14 14:09:41.922 UTC [49012][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:41.922 UTC [49012][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:41.923 UTC [49012][postmaster] LOG: background worker "logical replication launcher" (PID 49018) exited with exit code 1 2024-03-14 14:09:41.923 UTC [49013][checkpointer] LOG: shutting down 2024-03-14 14:09:41.923 UTC [49013][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:41.924 UTC [49013][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/4196770, redo lsn=0/4196770 2024-03-14 14:09:41.926 UTC [49012][postmaster] LOG: database system is shut down 2024-03-14 14:09:42.039 UTC [49036][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:42.040 UTC [49036][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:42.040 UTC [49036][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:42.042 UTC [49039][startup] LOG: database system was shut down at 2024-03-14 14:09:41 UTC 2024-03-14 14:09:42.044 UTC [49036][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:42.130 UTC [49044][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42776 2024-03-14 14:09:42.134 UTC [49044][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-14 14:09:42.134 UTC [49044][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-14 14:09:42.139 UTC [49044][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-14 14:09:42.139 UTC [49044][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42776 2024-03-14 14:09:42.147 UTC [49046][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42778 2024-03-14 14:09:42.150 UTC [49046][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-14 14:09:42.150 UTC [49046][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-14 14:09:42.151 UTC [49046][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-14 14:09:42.152 UTC [49046][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42778 2024-03-14 14:09:42.159 UTC [49048][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42782 2024-03-14 14:09:42.167 UTC [49036][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:42.167 UTC [49036][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:42.168 UTC [49036][postmaster] LOG: background worker "logical replication launcher" (PID 49042) exited with exit code 1 2024-03-14 14:09:42.168 UTC [49037][checkpointer] LOG: shutting down 2024-03-14 14:09:42.168 UTC [49037][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:42.169 UTC [49037][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/41967E8, redo lsn=0/41967E8 2024-03-14 14:09:42.171 UTC [49036][postmaster] LOG: database system is shut down 2024-03-14 14:09:42.284 UTC [49051][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:42.284 UTC [49051][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:42.285 UTC [49051][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:42.287 UTC [49054][startup] LOG: database system was shut down at 2024-03-14 14:09:42 UTC 2024-03-14 14:09:42.289 UTC [49051][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:42.375 UTC [49059][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42798 2024-03-14 14:09:42.379 UTC [49059][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-14 14:09:42.379 UTC [49059][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-14 14:09:42.384 UTC [49059][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-14 14:09:42.384 UTC [49059][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42798 2024-03-14 14:09:42.391 UTC [49061][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42808 2024-03-14 14:09:42.395 UTC [49061][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-14 14:09:42.395 UTC [49061][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-14 14:09:42.396 UTC [49061][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-14 14:09:42.396 UTC [49061][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42808 2024-03-14 14:09:42.402 UTC [49063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42820 2024-03-14 14:09:42.406 UTC [49063][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-14 14:09:42.406 UTC [49063][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-14 14:09:42.406 UTC [49063][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-14 14:09:42.407 UTC [49063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42820 2024-03-14 14:09:42.409 UTC [49051][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:42.409 UTC [49051][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:42.411 UTC [49051][postmaster] LOG: background worker "logical replication launcher" (PID 49057) exited with exit code 1 2024-03-14 14:09:42.411 UTC [49052][checkpointer] LOG: shutting down 2024-03-14 14:09:42.411 UTC [49052][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:42.411 UTC [49052][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/4196860, redo lsn=0/4196860 2024-03-14 14:09:42.414 UTC [49051][postmaster] LOG: database system is shut down 2024-03-14 14:09:42.527 UTC [49065][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:42.527 UTC [49065][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:42.528 UTC [49065][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:42.530 UTC [49068][startup] LOG: database system was shut down at 2024-03-14 14:09:42 UTC 2024-03-14 14:09:42.532 UTC [49065][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:42.618 UTC [49073][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42830 2024-03-14 14:09:42.621 UTC [49073][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-14 14:09:42.621 UTC [49073][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-14 14:09:42.626 UTC [49073][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-14 14:09:42.627 UTC [49073][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42830 2024-03-14 14:09:42.634 UTC [49075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42846 2024-03-14 14:09:42.638 UTC [49075][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-14 14:09:42.638 UTC [49075][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-14 14:09:42.639 UTC [49075][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-14 14:09:42.639 UTC [49075][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42846 2024-03-14 14:09:42.647 UTC [49077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42852 2024-03-14 14:09:42.653 UTC [49065][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:42.653 UTC [49065][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:42.654 UTC [49065][postmaster] LOG: background worker "logical replication launcher" (PID 49071) exited with exit code 1 2024-03-14 14:09:42.654 UTC [49066][checkpointer] LOG: shutting down 2024-03-14 14:09:42.654 UTC [49066][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:42.655 UTC [49066][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/41968D8, redo lsn=0/41968D8 2024-03-14 14:09:42.657 UTC [49065][postmaster] LOG: database system is shut down 2024-03-14 14:09:42.771 UTC [49079][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:42.771 UTC [49079][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:42.771 UTC [49079][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:42.773 UTC [49082][startup] LOG: database system was shut down at 2024-03-14 14:09:42 UTC 2024-03-14 14:09:42.776 UTC [49079][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:42.861 UTC [49087][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42866 2024-03-14 14:09:42.865 UTC [49087][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-14 14:09:42.865 UTC [49087][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-14 14:09:42.870 UTC [49087][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-14 14:09:42.870 UTC [49087][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42866 2024-03-14 14:09:42.878 UTC [49089][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42870 2024-03-14 14:09:42.882 UTC [49089][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-14 14:09:42.882 UTC [49089][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-14 14:09:42.882 UTC [49089][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-14 14:09:42.883 UTC [49089][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42870 2024-03-14 14:09:42.889 UTC [49091][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42874 2024-03-14 14:09:42.893 UTC [49091][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-14 14:09:42.893 UTC [49091][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-14 14:09:42.894 UTC [49091][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-14 14:09:42.894 UTC [49091][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42874 2024-03-14 14:09:42.897 UTC [49079][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:42.897 UTC [49079][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:42.898 UTC [49079][postmaster] LOG: background worker "logical replication launcher" (PID 49085) exited with exit code 1 2024-03-14 14:09:42.898 UTC [49080][checkpointer] LOG: shutting down 2024-03-14 14:09:42.898 UTC [49080][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:42.899 UTC [49080][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/4196950, redo lsn=0/4196950 2024-03-14 14:09:42.901 UTC [49079][postmaster] LOG: database system is shut down 2024-03-14 14:09:43.014 UTC [49093][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:43.014 UTC [49093][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:43.014 UTC [49093][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:43.016 UTC [49096][startup] LOG: database system was shut down at 2024-03-14 14:09:42 UTC 2024-03-14 14:09:43.020 UTC [49093][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:43.105 UTC [49101][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42880 2024-03-14 14:09:43.109 UTC [49101][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-14 14:09:43.109 UTC [49101][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-14 14:09:43.114 UTC [49101][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-14 14:09:43.114 UTC [49101][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42880 2024-03-14 14:09:43.122 UTC [49103][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42882 2024-03-14 14:09:43.129 UTC [49093][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:43.129 UTC [49093][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:43.130 UTC [49093][postmaster] LOG: background worker "logical replication launcher" (PID 49099) exited with exit code 1 2024-03-14 14:09:43.130 UTC [49094][checkpointer] LOG: shutting down 2024-03-14 14:09:43.130 UTC [49094][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:43.131 UTC [49094][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/41969C8, redo lsn=0/41969C8 2024-03-14 14:09:43.135 UTC [49093][postmaster] LOG: database system is shut down 2024-03-14 14:09:43.246 UTC [49105][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:43.246 UTC [49105][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:43.246 UTC [49105][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:43.249 UTC [49108][startup] LOG: database system was shut down at 2024-03-14 14:09:43 UTC 2024-03-14 14:09:43.252 UTC [49105][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:43.337 UTC [49113][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42888 2024-03-14 14:09:43.340 UTC [49113][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:43.352 UTC [49116][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42894 2024-03-14 14:09:43.355 UTC [49116][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-14 14:09:43.355 UTC [49116][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-14 14:09:43.360 UTC [49116][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-14 14:09:43.361 UTC [49116][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=42894 2024-03-14 14:09:43.367 UTC [49118][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42898 2024-03-14 14:09:43.373 UTC [49105][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:43.373 UTC [49105][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:43.375 UTC [49105][postmaster] LOG: background worker "logical replication launcher" (PID 49111) exited with exit code 1 2024-03-14 14:09:43.375 UTC [49106][checkpointer] LOG: shutting down 2024-03-14 14:09:43.375 UTC [49106][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:43.375 UTC [49106][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/4196A40, redo lsn=0/4196A40 2024-03-14 14:09:43.378 UTC [49105][postmaster] LOG: database system is shut down 2024-03-14 14:09:43.491 UTC [49120][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:43.491 UTC [49120][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:43.491 UTC [49120][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:43.493 UTC [49123][startup] LOG: database system was shut down at 2024-03-14 14:09:43 UTC 2024-03-14 14:09:43.496 UTC [49120][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:43.582 UTC [49128][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42908 2024-03-14 14:09:43.586 UTC [49128][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-14 14:09:43.586 UTC [49128][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-14 14:09:43.591 UTC [49128][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-14 14:09:43.591 UTC [49128][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=42908 2024-03-14 14:09:43.600 UTC [49130][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42924 2024-03-14 14:09:43.603 UTC [49130][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-14 14:09:43.611 UTC [49132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42928 2024-03-14 14:09:43.614 UTC [49132][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-14 14:09:43.621 UTC [49134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42930 2024-03-14 14:09:43.625 UTC [49134][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-14 14:09:43.625 UTC [49134][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-14 14:09:43.626 UTC [49134][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-14 14:09:43.627 UTC [49134][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=42930 2024-03-14 14:09:43.635 UTC [49136][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42938 2024-03-14 14:09:43.638 UTC [49136][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-14 14:09:43.638 UTC [49136][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-14 14:09:43.639 UTC [49136][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-14 14:09:43.640 UTC [49136][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=42938 2024-03-14 14:09:43.666 UTC [49141][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42940 2024-03-14 14:09:43.669 UTC [49141][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-14 14:09:43.677 UTC [49143][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42948 2024-03-14 14:09:43.682 UTC [49143][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-14 14:09:43.682 UTC [49143][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-14 14:09:43.686 UTC [49143][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_XqZG/client.key$$ 2024-03-14 14:09:43.687 UTC [49143][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=42948 2024-03-14 14:09:43.695 UTC [49145][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42954 2024-03-14 14:09:43.700 UTC [49145][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-14 14:09:43.700 UTC [49145][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-14 14:09:43.701 UTC [49145][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_XqZG/client-der.key$$ 2024-03-14 14:09:43.701 UTC [49145][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=42954 2024-03-14 14:09:43.709 UTC [49147][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42964 2024-03-14 14:09:43.714 UTC [49147][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-14 14:09:43.714 UTC [49147][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-14 14:09:43.715 UTC [49147][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_XqZG/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-14 14:09:43.715 UTC [49147][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=42964 2024-03-14 14:09:43.724 UTC [49149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42966 2024-03-14 14:09:43.729 UTC [49149][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-14 14:09:43.729 UTC [49149][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-14 14:09:43.730 UTC [49149][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_XqZG/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-14 14:09:43.730 UTC [49149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=42966 2024-03-14 14:09:43.738 UTC [49151][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42982 2024-03-14 14:09:43.743 UTC [49151][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-14 14:09:43.743 UTC [49151][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-14 14:09:43.744 UTC [49151][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_XqZG/client.key$$ 2024-03-14 14:09:43.744 UTC [49151][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=42982 2024-03-14 14:09:43.752 UTC [49153][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=42988 2024-03-14 14:09:43.757 UTC [49153][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-14 14:09:43.757 UTC [49153][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-14 14:09:43.758 UTC [49153][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_XqZG/client.key$$ 2024-03-14 14:09:43.758 UTC [49153][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=42988 2024-03-14 14:09:43.765 UTC [49155][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43000 2024-03-14 14:09:43.769 UTC [49155][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-14 14:09:43.776 UTC [49157][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43014 2024-03-14 14:09:43.778 UTC [49157][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:43.785 UTC [49159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43030 2024-03-14 14:09:43.790 UTC [49159][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-14 14:09:43.790 UTC [49159][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-14 14:09:43.794 UTC [49159][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_XqZG/client-dn.key$$ 2024-03-14 14:09:43.794 UTC [49159][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=43030 2024-03-14 14:09:43.803 UTC [49161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43046 2024-03-14 14:09:43.808 UTC [49161][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-14 14:09:43.808 UTC [49161][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-14 14:09:43.812 UTC [49161][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_XqZG/client-dn.key$$ 2024-03-14 14:09:43.812 UTC [49161][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn_re host=localhost port=43046 2024-03-14 14:09:43.820 UTC [49163][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43048 2024-03-14 14:09:43.824 UTC [49163][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-14 14:09:43.824 UTC [49163][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-14 14:09:43.829 UTC [49163][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_XqZG/client-dn.key$$ 2024-03-14 14:09:43.829 UTC [49163][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_cn host=localhost port=43048 2024-03-14 14:09:43.846 UTC [49166][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43052 2024-03-14 14:09:43.851 UTC [49166][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-14 14:09:43.851 UTC [49166][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-14 14:09:43.852 UTC [49166][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-14 14:09:43.853 UTC [49166][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=43052 2024-03-14 14:09:43.860 UTC [49168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43058 2024-03-14 14:09:43.861 UTC [49168][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:43.868 UTC [49170][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43064 2024-03-14 14:09:43.873 UTC [49170][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-14 14:09:43.873 UTC [49170][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-14 14:09:43.873 UTC [49170][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-14 14:09:43.873 UTC [49170][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-14 14:09:43.880 UTC [49172][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43074 2024-03-14 14:09:43.885 UTC [49172][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:43.885 UTC [49172][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-14 14:09:43.893 UTC [49174][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43082 2024-03-14 14:09:43.897 UTC [49174][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-14 14:09:43.897 UTC [49174][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-14 14:09:43.902 UTC [49174][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_XqZG/client.key$$ 2024-03-14 14:09:43.902 UTC [49174][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=verifydb host=localhost port=43082 2024-03-14 14:09:43.910 UTC [49176][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43096 2024-03-14 14:09:43.915 UTC [49176][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-14 14:09:43.915 UTC [49176][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-14 14:09:43.915 UTC [49176][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-14 14:09:43.915 UTC [49176][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-14 14:09:43.923 UTC [49178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43098 2024-03-14 14:09:43.928 UTC [49178][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-14 14:09:43.928 UTC [49178][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-14 14:09:43.929 UTC [49178][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_XqZG/client.key$$ 2024-03-14 14:09:43.929 UTC [49178][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=43098 2024-03-14 14:09:43.933 UTC [49120][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:43.933 UTC [49120][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:43.934 UTC [49120][postmaster] LOG: background worker "logical replication launcher" (PID 49126) exited with exit code 1 2024-03-14 14:09:43.935 UTC [49121][checkpointer] LOG: shutting down 2024-03-14 14:09:43.935 UTC [49121][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:43.935 UTC [49121][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/4196AB8, redo lsn=0/4196AB8 2024-03-14 14:09:43.939 UTC [49120][postmaster] LOG: database system is shut down 2024-03-14 14:09:44.050 UTC [49180][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:44.050 UTC [49180][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:44.050 UTC [49180][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:44.053 UTC [49183][startup] LOG: database system was shut down at 2024-03-14 14:09:43 UTC 2024-03-14 14:09:44.056 UTC [49180][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:44.142 UTC [49188][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43112 2024-03-14 14:09:44.147 UTC [49188][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-14 14:09:44.147 UTC [49188][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-14 14:09:44.152 UTC [49188][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_XqZG/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-14 14:09:44.152 UTC [49188][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=43112 2024-03-14 14:09:44.160 UTC [49190][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43122 2024-03-14 14:09:44.164 UTC [49190][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:44.164 UTC [49190][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-14 14:09:44.172 UTC [49192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43138 2024-03-14 14:09:44.176 UTC [49192][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:44.176 UTC [49192][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-14 14:09:44.180 UTC [49180][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:44.180 UTC [49180][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:44.181 UTC [49180][postmaster] LOG: background worker "logical replication launcher" (PID 49186) exited with exit code 1 2024-03-14 14:09:44.181 UTC [49181][checkpointer] LOG: shutting down 2024-03-14 14:09:44.181 UTC [49181][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:44.182 UTC [49181][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/4196B30, redo lsn=0/4196B30 2024-03-14 14:09:44.185 UTC [49180][postmaster] LOG: database system is shut down 2024-03-14 14:09:44.298 UTC [49194][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:44.298 UTC [49194][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:44.298 UTC [49194][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:44.300 UTC [49197][startup] LOG: database system was shut down at 2024-03-14 14:09:44 UTC 2024-03-14 14:09:44.303 UTC [49194][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:44.388 UTC [49202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43154 2024-03-14 14:09:44.393 UTC [49202][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:44.393 UTC [49202][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-14 14:09:44.397 UTC [49194][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:44.397 UTC [49194][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:44.398 UTC [49194][postmaster] LOG: background worker "logical replication launcher" (PID 49200) exited with exit code 1 2024-03-14 14:09:44.398 UTC [49195][checkpointer] LOG: shutting down 2024-03-14 14:09:44.398 UTC [49195][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:44.398 UTC [49195][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/4196BA8, redo lsn=0/4196BA8 2024-03-14 14:09:44.401 UTC [49194][postmaster] LOG: database system is shut down 2024-03-14 14:09:44.514 UTC [49204][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:44.515 UTC [49204][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:44.515 UTC [49204][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:44.517 UTC [49207][startup] LOG: database system was shut down at 2024-03-14 14:09:44 UTC 2024-03-14 14:09:44.520 UTC [49204][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:44.606 UTC [49212][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43164 2024-03-14 14:09:44.610 UTC [49212][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:44.610 UTC [49212][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-14 14:09:44.618 UTC [49214][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43166 2024-03-14 14:09:44.622 UTC [49214][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:44.622 UTC [49214][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-14 14:09:44.626 UTC [49204][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:44.626 UTC [49204][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:44.628 UTC [49204][postmaster] LOG: background worker "logical replication launcher" (PID 49210) exited with exit code 1 2024-03-14 14:09:44.628 UTC [49205][checkpointer] LOG: shutting down 2024-03-14 14:09:44.628 UTC [49205][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:44.628 UTC [49205][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/4196C20, redo lsn=0/4196C20 2024-03-14 14:09:44.631 UTC [49204][postmaster] LOG: database system is shut down 2024-03-14 14:09:44.744 UTC [49216][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:44.744 UTC [49216][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:44.744 UTC [49216][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:44.746 UTC [49219][startup] LOG: database system was shut down at 2024-03-14 14:09:44 UTC 2024-03-14 14:09:44.749 UTC [49216][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:44.834 UTC [49224][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43178 2024-03-14 14:09:44.836 UTC [49224][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:44.836 UTC [49224][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:44.844 UTC [49226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43180 2024-03-14 14:09:44.848 UTC [49226][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-14 14:09:44.848 UTC [49226][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-14 14:09:44.853 UTC [49226][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-14 14:09:44.853 UTC [49226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43180 2024-03-14 14:09:44.857 UTC [49216][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:44.857 UTC [49216][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:44.858 UTC [49216][postmaster] LOG: background worker "logical replication launcher" (PID 49222) exited with exit code 1 2024-03-14 14:09:44.858 UTC [49217][checkpointer] LOG: shutting down 2024-03-14 14:09:44.858 UTC [49217][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:44.859 UTC [49217][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/4196C98, redo lsn=0/4196C98 2024-03-14 14:09:44.861 UTC [49216][postmaster] LOG: database system is shut down 2024-03-14 14:09:44.974 UTC [49228][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:44.974 UTC [49228][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:44.974 UTC [49228][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:44.977 UTC [49231][startup] LOG: database system was shut down at 2024-03-14 14:09:44 UTC 2024-03-14 14:09:44.980 UTC [49228][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:45.065 UTC [49236][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43184 2024-03-14 14:09:45.066 UTC [49236][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:45.066 UTC [49236][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:45.075 UTC [49238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43200 2024-03-14 14:09:45.078 UTC [49238][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-14 14:09:45.078 UTC [49238][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-14 14:09:45.083 UTC [49238][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-14 14:09:45.083 UTC [49238][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=43200 2024-03-14 14:09:45.087 UTC [49228][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:45.087 UTC [49228][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:45.088 UTC [49228][postmaster] LOG: background worker "logical replication launcher" (PID 49234) exited with exit code 1 2024-03-14 14:09:45.088 UTC [49229][checkpointer] LOG: shutting down 2024-03-14 14:09:45.088 UTC [49229][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:45.089 UTC [49229][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/4196D10, redo lsn=0/4196D10 2024-03-14 14:09:45.091 UTC [49228][postmaster] LOG: database system is shut down 2024-03-14 14:09:45.205 UTC [49240][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:45.205 UTC [49240][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:45.205 UTC [49240][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:45.207 UTC [49243][startup] LOG: database system was shut down at 2024-03-14 14:09:45 UTC 2024-03-14 14:09:45.209 UTC [49240][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:45.295 UTC [49248][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43216 2024-03-14 14:09:45.297 UTC [49248][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:45.297 UTC [49248][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:45.305 UTC [49250][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43228 2024-03-14 14:09:45.309 UTC [49250][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-14 14:09:45.309 UTC [49250][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-14 14:09:45.313 UTC [49250][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-14 14:09:45.314 UTC [49250][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=43228 2024-03-14 14:09:45.317 UTC [49240][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:45.317 UTC [49240][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:45.319 UTC [49240][postmaster] LOG: background worker "logical replication launcher" (PID 49246) exited with exit code 1 2024-03-14 14:09:45.319 UTC [49241][checkpointer] LOG: shutting down 2024-03-14 14:09:45.319 UTC [49241][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:45.319 UTC [49241][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/4196D88, redo lsn=0/4196D88 2024-03-14 14:09:45.322 UTC [49240][postmaster] LOG: database system is shut down 2024-03-14 14:09:45.435 UTC [49252][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:45.435 UTC [49252][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:45.435 UTC [49252][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:45.437 UTC [49255][startup] LOG: database system was shut down at 2024-03-14 14:09:45 UTC 2024-03-14 14:09:45.439 UTC [49252][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:45.526 UTC [49260][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43244 2024-03-14 14:09:45.528 UTC [49260][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:45.528 UTC [49260][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:45.536 UTC [49262][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43248 2024-03-14 14:09:45.540 UTC [49262][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-14 14:09:45.540 UTC [49262][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-14 14:09:45.545 UTC [49262][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-14 14:09:45.545 UTC [49262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=43248 2024-03-14 14:09:45.548 UTC [49252][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:45.548 UTC [49252][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:45.550 UTC [49252][postmaster] LOG: background worker "logical replication launcher" (PID 49258) exited with exit code 1 2024-03-14 14:09:45.550 UTC [49253][checkpointer] LOG: shutting down 2024-03-14 14:09:45.550 UTC [49253][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:45.551 UTC [49253][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/4196E00, redo lsn=0/4196E00 2024-03-14 14:09:45.553 UTC [49252][postmaster] LOG: database system is shut down 2024-03-14 14:09:45.666 UTC [49264][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:45.666 UTC [49264][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:45.667 UTC [49264][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:45.669 UTC [49267][startup] LOG: database system was shut down at 2024-03-14 14:09:45 UTC 2024-03-14 14:09:45.671 UTC [49264][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:45.757 UTC [49272][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43260 2024-03-14 14:09:45.759 UTC [49272][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:45.759 UTC [49272][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:45.768 UTC [49274][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43266 2024-03-14 14:09:45.771 UTC [49274][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-14 14:09:45.772 UTC [49274][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-14 14:09:45.776 UTC [49274][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-14 14:09:45.777 UTC [49274][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=43266 2024-03-14 14:09:45.780 UTC [49264][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:45.780 UTC [49264][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:45.781 UTC [49264][postmaster] LOG: background worker "logical replication launcher" (PID 49270) exited with exit code 1 2024-03-14 14:09:45.782 UTC [49265][checkpointer] LOG: shutting down 2024-03-14 14:09:45.782 UTC [49265][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:45.783 UTC [49265][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/4196E78, redo lsn=0/4196E78 2024-03-14 14:09:45.785 UTC [49264][postmaster] LOG: database system is shut down 2024-03-14 14:09:45.898 UTC [49276][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:45.898 UTC [49276][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:45.898 UTC [49276][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:45.900 UTC [49279][startup] LOG: database system was shut down at 2024-03-14 14:09:45 UTC 2024-03-14 14:09:45.903 UTC [49276][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:45.988 UTC [49284][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43282 2024-03-14 14:09:45.990 UTC [49284][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:45.990 UTC [49284][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:45.998 UTC [49286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43286 2024-03-14 14:09:46.001 UTC [49286][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-14 14:09:46.001 UTC [49286][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-14 14:09:46.006 UTC [49286][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-14 14:09:46.006 UTC [49286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43286 2024-03-14 14:09:46.010 UTC [49276][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:46.010 UTC [49276][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:46.012 UTC [49276][postmaster] LOG: background worker "logical replication launcher" (PID 49282) exited with exit code 1 2024-03-14 14:09:46.012 UTC [49277][checkpointer] LOG: shutting down 2024-03-14 14:09:46.012 UTC [49277][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:46.013 UTC [49277][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/4196EF0, redo lsn=0/4196EF0 2024-03-14 14:09:46.015 UTC [49276][postmaster] LOG: database system is shut down 2024-03-14 14:09:46.128 UTC [49288][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:46.128 UTC [49288][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:46.128 UTC [49288][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:46.130 UTC [49291][startup] LOG: database system was shut down at 2024-03-14 14:09:46 UTC 2024-03-14 14:09:46.133 UTC [49288][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:46.218 UTC [49296][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43298 2024-03-14 14:09:46.220 UTC [49296][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:46.220 UTC [49296][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:46.228 UTC [49298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43304 2024-03-14 14:09:46.231 UTC [49298][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-14 14:09:46.231 UTC [49298][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-14 14:09:46.236 UTC [49298][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-14 14:09:46.236 UTC [49298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=43304 2024-03-14 14:09:46.239 UTC [49288][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:46.239 UTC [49288][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:46.240 UTC [49288][postmaster] LOG: background worker "logical replication launcher" (PID 49294) exited with exit code 1 2024-03-14 14:09:46.240 UTC [49289][checkpointer] LOG: shutting down 2024-03-14 14:09:46.240 UTC [49289][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:46.241 UTC [49289][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/4196F68, redo lsn=0/4196F68 2024-03-14 14:09:46.243 UTC [49288][postmaster] LOG: database system is shut down 2024-03-14 14:09:46.356 UTC [49300][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:46.356 UTC [49300][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:46.356 UTC [49300][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:46.358 UTC [49303][startup] LOG: database system was shut down at 2024-03-14 14:09:46 UTC 2024-03-14 14:09:46.361 UTC [49300][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:46.447 UTC [49308][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43314 2024-03-14 14:09:46.448 UTC [49308][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:46.448 UTC [49308][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:46.456 UTC [49310][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43326 2024-03-14 14:09:46.459 UTC [49310][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-14 14:09:46.459 UTC [49310][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-14 14:09:46.464 UTC [49310][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-14 14:09:46.465 UTC [49310][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=43326 2024-03-14 14:09:46.468 UTC [49300][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:46.468 UTC [49300][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:46.470 UTC [49300][postmaster] LOG: background worker "logical replication launcher" (PID 49306) exited with exit code 1 2024-03-14 14:09:46.470 UTC [49301][checkpointer] LOG: shutting down 2024-03-14 14:09:46.470 UTC [49301][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:46.470 UTC [49301][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/4196FE0, redo lsn=0/4196FE0 2024-03-14 14:09:46.472 UTC [49300][postmaster] LOG: database system is shut down 2024-03-14 14:09:46.586 UTC [49312][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-14 14:09:46.586 UTC [49312][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57904 2024-03-14 14:09:46.586 UTC [49312][postmaster] LOG: listening on Unix socket "/tmp/nQ2O3KJXeQ/.s.PGSQL.57904" 2024-03-14 14:09:46.588 UTC [49315][startup] LOG: database system was shut down at 2024-03-14 14:09:46 UTC 2024-03-14 14:09:46.591 UTC [49312][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:46.676 UTC [49320][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43328 2024-03-14 14:09:46.677 UTC [49320][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:46.677 UTC [49320][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:46.685 UTC [49322][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=43338 2024-03-14 14:09:46.689 UTC [49322][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-14 14:09:46.689 UTC [49322][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-14 14:09:46.693 UTC [49322][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-14 14:09:46.694 UTC [49322][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=43338 2024-03-14 14:09:46.697 UTC [49312][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:46.697 UTC [49312][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:46.698 UTC [49312][postmaster] LOG: background worker "logical replication launcher" (PID 49318) exited with exit code 1 2024-03-14 14:09:46.698 UTC [49313][checkpointer] LOG: shutting down 2024-03-14 14:09:46.698 UTC [49313][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:46.699 UTC [49313][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/4197058, redo lsn=0/4197058 2024-03-14 14:09:46.701 UTC [49312][postmaster] LOG: database system is shut down 2024-03-14 14:09:46.809 UTC [49324][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-14 14:09:46.809 UTC [49324][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:09:46.901](0.206s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"