[08:40:40.061](0.050s) # setting up data directory
# Checking port 60607
# Found port 60607
Name: primary
Data directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/archives
Connection string: port=60607 host=/tmp/Dd6l5mXKVk
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[08:40:40.073](0.012s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/ssl/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 28786
[08:40:40.383](0.310s) ok 1 - ssl_library parameter
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29149
[08:40:41.855](1.473s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-26 08:40:40.291 UTC [28786][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:40.292 UTC [28786][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:40.309 UTC [28797][startup] LOG:  database system was shut down at 2024-03-26 08:39:06 UTC
2024-03-26 08:40:40.317 UTC [28786][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:40.359 UTC [28814][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.359 UTC [28814][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.359 UTC [28814][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.380 UTC [28814][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-26 08:40:40.384 UTC [28814][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.404 UTC [28830][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.405 UTC [28830][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.405 UTC [28830][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.410 UTC [28830][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-26 08:40:40.414 UTC [28830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.457 UTC [28853][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.458 UTC [28853][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.458 UTC [28853][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.459 UTC [28853][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-26 08:40:40.461 UTC [28853][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.505 UTC [28870][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.505 UTC [28870][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.505 UTC [28870][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.507 UTC [28870][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-26 08:40:40.509 UTC [28870][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.570 UTC [28880][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.572 UTC [28880][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.572 UTC [28880][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.576 UTC [28880][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-26 08:40:40.578 UTC [28880][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.623 UTC [28893][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.624 UTC [28893][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.624 UTC [28893][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.633 UTC [28893][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-26 08:40:40.685 UTC [28893][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.753 UTC [28933][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.753 UTC [28933][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.753 UTC [28933][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.755 UTC [28933][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-26 08:40:40.852 UTC [28933][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.107 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.020 UTC [29006][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.021 UTC [29006][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.021 UTC [29006][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.023 UTC [29006][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-26 08:40:41.060 UTC [29006][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.165 UTC [29026][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.165 UTC [29026][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.165 UTC [29026][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.167 UTC [29026][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-26 08:40:41.261 UTC [29026][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.105 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.281 UTC [29058][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.282 UTC [29058][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.282 UTC [29058][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.284 UTC [29058][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-26 08:40:41.335 UTC [29058][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.419 UTC [29094][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.420 UTC [29094][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.420 UTC [29094][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.422 UTC [29094][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-26 08:40:41.493 UTC [29094][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.546 UTC [28786][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:41.546 UTC [28786][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:41.551 UTC [28786][postmaster] LOG:  background worker "logical replication launcher" (PID 28803) exited with exit code 1
2024-03-26 08:40:41.553 UTC [28794][checkpointer] LOG:  shutting down
2024-03-26 08:40:41.553 UTC [28794][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:41.607 UTC [28794][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.053 s, sync=0.001 s, total=0.054 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414DB70, redo lsn=0/414DB70
2024-03-26 08:40:41.620 UTC [28786][postmaster] LOG:  database system is shut down
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:41.768 UTC [29176][startup] LOG:  database system was shut down at 2024-03-26 08:40:41 UTC
2024-03-26 08:40:41.778 UTC [29149][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:41.858 UTC [29149][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:41.858 UTC [29149][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:41.866 UTC [29149][postmaster] LOG:  background worker "logical replication launcher" (PID 29186) exited with exit code 1
2024-03-26 08:40:41.866 UTC [29174][checkpointer] LOG:  shutting down
2024-03-26 08:40:41.866 UTC [29174][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:41.867 UTC [29174][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/414DBE8, redo lsn=0/414DBE8
2024-03-26 08:40:41.872 UTC [29149][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.015 UTC [29223][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-26 08:40:42.015 UTC [29223][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[08:40:42.061](0.205s) ok 2 - restart fails with password-protected key file with wrong password
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29251
[08:40:42.283](0.223s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-26 08:40:40.291 UTC [28786][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:40.292 UTC [28786][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:40.309 UTC [28797][startup] LOG:  database system was shut down at 2024-03-26 08:39:06 UTC
2024-03-26 08:40:40.317 UTC [28786][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:40.359 UTC [28814][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.359 UTC [28814][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.359 UTC [28814][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.380 UTC [28814][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-26 08:40:40.384 UTC [28814][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.404 UTC [28830][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.405 UTC [28830][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.405 UTC [28830][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.410 UTC [28830][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-26 08:40:40.414 UTC [28830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.457 UTC [28853][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.458 UTC [28853][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.458 UTC [28853][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.459 UTC [28853][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-26 08:40:40.461 UTC [28853][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.505 UTC [28870][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.505 UTC [28870][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.505 UTC [28870][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.507 UTC [28870][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-26 08:40:40.509 UTC [28870][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.570 UTC [28880][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.572 UTC [28880][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.572 UTC [28880][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.576 UTC [28880][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-26 08:40:40.578 UTC [28880][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.623 UTC [28893][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.624 UTC [28893][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.624 UTC [28893][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.633 UTC [28893][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-26 08:40:40.685 UTC [28893][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.753 UTC [28933][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.753 UTC [28933][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.753 UTC [28933][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.755 UTC [28933][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-26 08:40:40.852 UTC [28933][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.107 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.020 UTC [29006][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.021 UTC [29006][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.021 UTC [29006][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.023 UTC [29006][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-26 08:40:41.060 UTC [29006][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.165 UTC [29026][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.165 UTC [29026][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.165 UTC [29026][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.167 UTC [29026][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-26 08:40:41.261 UTC [29026][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.105 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.281 UTC [29058][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.282 UTC [29058][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.282 UTC [29058][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.284 UTC [29058][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-26 08:40:41.335 UTC [29058][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.419 UTC [29094][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.420 UTC [29094][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.420 UTC [29094][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.422 UTC [29094][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-26 08:40:41.493 UTC [29094][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.546 UTC [28786][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:41.546 UTC [28786][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:41.551 UTC [28786][postmaster] LOG:  background worker "logical replication launcher" (PID 28803) exited with exit code 1
2024-03-26 08:40:41.553 UTC [28794][checkpointer] LOG:  shutting down
2024-03-26 08:40:41.553 UTC [28794][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:41.607 UTC [28794][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.053 s, sync=0.001 s, total=0.054 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414DB70, redo lsn=0/414DB70
2024-03-26 08:40:41.620 UTC [28786][postmaster] LOG:  database system is shut down
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:41.768 UTC [29176][startup] LOG:  database system was shut down at 2024-03-26 08:40:41 UTC
2024-03-26 08:40:41.778 UTC [29149][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:41.858 UTC [29149][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:41.858 UTC [29149][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:41.866 UTC [29149][postmaster] LOG:  background worker "logical replication launcher" (PID 29186) exited with exit code 1
2024-03-26 08:40:41.866 UTC [29174][checkpointer] LOG:  shutting down
2024-03-26 08:40:41.866 UTC [29174][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:41.867 UTC [29174][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/414DBE8, redo lsn=0/414DBE8
2024-03-26 08:40:41.872 UTC [29149][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.015 UTC [29223][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-26 08:40:42.015 UTC [29223][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.145 UTC [29251][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:42.145 UTC [29251][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:42.145 UTC [29251][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:42.160 UTC [29268][startup] LOG:  database system was shut down at 2024-03-26 08:40:41 UTC
2024-03-26 08:40:42.173 UTC [29251][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:42.298 UTC [29251][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:42.298 UTC [29251][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:42.303 UTC [29251][postmaster] LOG:  background worker "logical replication launcher" (PID 29276) exited with exit code 1
2024-03-26 08:40:42.312 UTC [29266][checkpointer] LOG:  shutting down
2024-03-26 08:40:42.312 UTC [29266][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:42.313 UTC [29266][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/414DC60, redo lsn=0/414DC60
2024-03-26 08:40:42.319 UTC [29251][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.446 UTC [29331][postmaster] FATAL:  could not set SSL protocol version range
2024-03-26 08:40:42.446 UTC [29331][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-26 08:40:42.446 UTC [29331][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[08:40:42.500](0.217s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29354
[08:40:42.607](0.107s) ok 5 - restart succeeds with correct SSL protocol bounds
[08:40:42.607](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29380
[08:40:42.984](0.377s) ok 6 - server doesn't accept non-SSL connections
[08:40:42.985](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[08:40:43.078](0.094s) ok 8 - connect without server root cert sslmode=require
[08:40:43.078](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[08:40:43.128](0.049s) ok 10 - connect without server root cert sslmode=verify-ca
[08:40:43.128](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[08:40:43.170](0.043s) ok 12 - connect without server root cert sslmode=verify-full
[08:40:43.171](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[08:40:43.218](0.048s) ok 14 - connect with wrong server root cert sslmode=require
[08:40:43.219](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[08:40:43.269](0.050s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[08:40:43.269](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[08:40:43.311](0.041s) ok 18 - connect with wrong server root cert sslmode=verify-full
[08:40:43.311](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[08:40:43.336](0.025s) ok 20 - connect with server CA cert, without root CA
[08:40:43.336](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[08:40:43.366](0.030s) ok 22 - connect with correct server CA cert file sslmode=require
[08:40:43.366](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[08:40:43.423](0.057s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[08:40:43.423](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[08:40:43.473](0.049s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[08:40:43.473](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[08:40:43.506](0.033s) ok 28 - cert root file that contains two certificates, order 1
[08:40:43.506](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[08:40:43.552](0.046s) ok 30 - cert root file that contains two certificates, order 2
[08:40:43.552](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[08:40:43.590](0.038s) ok 32 - connect with sslcertmode=disable
[08:40:43.590](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[08:40:43.664](0.074s) ok 34 - connect with sslcertmode=allow
[08:40:43.664](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[08:40:43.732](0.067s) ok 36 - connect with sslcertmode=require fails without a client certificate
[08:40:43.732](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[08:40:43.785](0.053s) ok 38 - sslcrl option with invalid file name
[08:40:43.786](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[08:40:43.833](0.047s) ok 40 - CRL belonging to a different CA
[08:40:43.833](0.000s) ok 41 - CRL belonging to a different CA: matches
[08:40:43.876](0.043s) ok 42 - directory CRL belonging to a different CA
[08:40:43.877](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[08:40:43.925](0.048s) ok 44 - CRL with a non-revoked cert
[08:40:43.925](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[08:40:43.952](0.028s) ok 46 - directory CRL with a non-revoked cert
[08:40:43.953](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[08:40:43.984](0.032s) ok 48 - mismatch between host name and server certificate sslmode=require
[08:40:43.984](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[08:40:44.029](0.044s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[08:40:44.029](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[08:40:44.074](0.045s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[08:40:44.075](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29607
[08:40:44.447](0.373s) ok 54 - IP address in the Common Name
[08:40:44.448](0.000s) ok 55 - IP address in the Common Name: no stderr
[08:40:44.475](0.028s) ok 56 - mismatch between host name and server certificate IP address
[08:40:44.476](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29686
[08:40:44.761](0.285s) ok 58 - IP address in a dNSName
[08:40:44.761](0.000s) ok 59 - IP address in a dNSName: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29734
[08:40:45.033](0.272s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[08:40:45.033](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[08:40:45.080](0.047s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[08:40:45.080](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[08:40:45.137](0.056s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[08:40:45.137](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[08:40:45.176](0.039s) ok 66 - host name not matching with X.509 Subject Alternative Names
[08:40:45.177](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[08:40:45.205](0.028s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[08:40:45.205](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29806
[08:40:45.621](0.416s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[08:40:45.622](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[08:40:45.667](0.046s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[08:40:45.668](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[08:40:45.707](0.039s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[08:40:45.707](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29836
[08:40:46.009](0.302s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[08:40:46.009](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[08:40:46.034](0.024s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[08:40:46.034](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[08:40:46.057](0.023s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[08:40:46.057](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[08:40:46.086](0.030s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[08:40:46.087](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[08:40:46.145](0.059s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[08:40:46.146](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[08:40:46.209](0.063s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[08:40:46.209](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[08:40:46.249](0.040s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[08:40:46.250](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[08:40:46.293](0.043s) ok 90 - IPv6 host with CIDR mask does not match
[08:40:46.293](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29895
[08:40:46.585](0.291s) ok 92 - certificate with both a CN and SANs 1
[08:40:46.585](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[08:40:46.624](0.039s) ok 94 - certificate with both a CN and SANs 2
[08:40:46.624](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[08:40:46.676](0.052s) ok 96 - certificate with both a CN and SANs ignores CN
[08:40:46.677](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 29954
[08:40:46.945](0.268s) ok 98 - certificate with both a CN and IP SANs matches CN
[08:40:46.945](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[08:40:46.991](0.046s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[08:40:46.991](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[08:40:47.038](0.047s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[08:40:47.038](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30012
[08:40:47.322](0.284s) ok 104 - certificate with both an IP CN and IP SANs 1
[08:40:47.322](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[08:40:47.372](0.050s) ok 106 - certificate with both an IP CN and IP SANs 2
[08:40:47.372](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[08:40:47.405](0.033s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[08:40:47.406](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30068
[08:40:47.681](0.276s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[08:40:47.682](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[08:40:47.753](0.071s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[08:40:47.753](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[08:40:47.787](0.034s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[08:40:47.787](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30136
[08:40:48.052](0.265s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[08:40:48.052](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[08:40:48.124](0.072s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[08:40:48.124](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30193
[08:40:48.470](0.346s) ok 120 - sslrootcert=system does not connect with private CA
[08:40:48.470](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[08:40:48.492](0.022s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[08:40:48.492](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[08:40:48.559](0.067s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[08:40:48.559](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[08:40:48.586](0.026s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[08:40:48.586](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30275
[08:40:48.857](0.271s) ok 128 - connects without client-side CRL
[08:40:48.857](0.000s) ok 129 - connects without client-side CRL: no stderr
[08:40:48.900](0.043s) ok 130 - does not connect with client-side CRL file
[08:40:48.900](0.000s) ok 131 - does not connect with client-side CRL file: matches
[08:40:48.934](0.033s) ok 132 - does not connect with client-side CRL directory
[08:40:48.934](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()
[08:40:48.983](0.049s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[08:40:48.983](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[08:40:48.983](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[08:40:49.024](0.041s) ok 137 - connection success with correct range of TLS protocol versions
[08:40:49.024](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[08:40:49.063](0.038s) ok 139 - connection failure with incorrect range of TLS protocol versions
[08:40:49.063](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[08:40:49.089](0.026s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[08:40:49.089](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[08:40:49.102](0.013s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[08:40:49.102](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[08:40:49.103](0.000s) # running server tests
[08:40:49.133](0.030s) ok 145 - certificate authorization fails without client cert
[08:40:49.133](0.000s) ok 146 - certificate authorization fails without client cert: matches
[08:40:49.224](0.090s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[08:40:49.224](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[08:40:49.282](0.058s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[08:40:49.282](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[08:40:49.314](0.032s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[08:40:49.314](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[08:40:49.357](0.043s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[08:40:49.357](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[08:40:49.417](0.060s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[08:40:49.417](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[08:40:49.465](0.048s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[08:40:49.465](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[08:40:49.498](0.032s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[08:40:49.498](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[08:40:49.539](0.041s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[08:40:49.539](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[08:40:49.598](0.059s) ok 163 - certificate authorization succeeds with DN mapping
[08:40:49.598](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[08:40:49.598](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[08:40:49.646](0.048s) ok 166 - certificate authorization succeeds with DN regex mapping
[08:40:49.646](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[08:40:49.710](0.063s) ok 168 - certificate authorization succeeds with CN mapping
[08:40:49.710](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[08:40:49.710](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[08:40:49.711](0.000s) not ok 171 # TODO & SKIP Need Pty support
[08:40:49.711](0.000s) not ok 172 # TODO & SKIP Need Pty support
[08:40:49.711](0.000s) not ok 173 # TODO & SKIP Need Pty support
[08:40:49.711](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/src/test/ssl/tmp_check/tmp_test_yaEB/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[08:40:49.755](0.044s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[08:40:49.755](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[08:40:49.755](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[08:40:49.785](0.030s) ok 178 - certificate authorization fails because of file permissions
[08:40:49.786](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[08:40:49.817](0.032s) ok 180 - certificate authorization fails with client cert belonging to another user
[08:40:49.818](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[08:40:49.855](0.038s) ok 182 - certificate authorization fails with revoked client cert
[08:40:49.856](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[08:40:49.856](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[08:40:49.913](0.057s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[08:40:49.913](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[08:40:49.913](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[08:40:49.972](0.059s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[08:40:49.972](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[08:40:49.973](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[08:40:50.033](0.061s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[08:40:50.033](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[08:40:50.034](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30530
[08:40:50.324](0.291s) ok 194 - intermediate client certificate is provided by client
[08:40:50.325](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[08:40:50.357](0.032s) ok 196 - intermediate client certificate is missing
[08:40:50.357](0.000s) ok 197 - intermediate client certificate is missing: matches
[08:40:50.397](0.040s) ok 198 - logged client certificate Subjects are truncated if they're too long
[08:40:50.397](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30599
[08:40:50.661](0.264s) ok 200 - intermediate client certificate is untrusted
[08:40:50.661](0.000s) ok 201 - intermediate client certificate is untrusted: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30659
[08:40:50.933](0.272s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[08:40:50.933](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[08:40:50.975](0.041s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[08:40:50.975](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30700
[08:40:51.221](0.246s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[08:40:51.221](0.000s) 
[08:40:51.221](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[08:40:51.221](0.000s) #          got: '2'
#     expected: '0'
[08:40:51.222](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[08:40:51.222](0.000s) 
[08:40:51.222](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[08:40:51.222](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 60607 failed: SSL error: ocsp callback failure'
#     expected: ''
[08:40:51.288](0.067s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[08:40:51.289](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30776
[08:40:51.554](0.265s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[08:40:51.599](0.045s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[08:40:51.599](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30838
[08:40:51.853](0.253s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[08:40:51.931](0.078s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[08:40:51.931](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30901
[08:40:52.183](0.252s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[08:40:52.230](0.047s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[08:40:52.230](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 30957
[08:40:52.478](0.247s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[08:40:52.478](0.000s) 
[08:40:52.478](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[08:40:52.478](0.000s) #          got: '2'
#     expected: '0'
[08:40:52.478](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[08:40:52.478](0.000s) 
[08:40:52.478](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[08:40:52.478](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 60607 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: ''
[08:40:52.542](0.064s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[08:40:52.542](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 31014
[08:40:52.812](0.270s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[08:40:52.881](0.069s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[08:40:52.881](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 31086
[08:40:53.158](0.277s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[08:40:53.222](0.064s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[08:40:53.223](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 31148
[08:40:53.482](0.260s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[08:40:53.523](0.041s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[08:40:53.523](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 31230
[08:40:53.787](0.264s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[08:40:53.845](0.059s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[08:40:53.846](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-26 08:40:40.291 UTC [28786][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:40.292 UTC [28786][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:40.309 UTC [28797][startup] LOG:  database system was shut down at 2024-03-26 08:39:06 UTC
2024-03-26 08:40:40.317 UTC [28786][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:40.359 UTC [28814][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.359 UTC [28814][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.359 UTC [28814][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.380 UTC [28814][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-26 08:40:40.384 UTC [28814][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.404 UTC [28830][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.405 UTC [28830][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.405 UTC [28830][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.410 UTC [28830][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-26 08:40:40.414 UTC [28830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.457 UTC [28853][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.458 UTC [28853][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.458 UTC [28853][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.459 UTC [28853][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-26 08:40:40.461 UTC [28853][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.505 UTC [28870][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.505 UTC [28870][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.505 UTC [28870][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.507 UTC [28870][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-26 08:40:40.509 UTC [28870][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.570 UTC [28880][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.572 UTC [28880][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.572 UTC [28880][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.576 UTC [28880][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-26 08:40:40.578 UTC [28880][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.623 UTC [28893][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.624 UTC [28893][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.624 UTC [28893][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.633 UTC [28893][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-26 08:40:40.685 UTC [28893][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=postgres database=postgres host=[local]
2024-03-26 08:40:40.753 UTC [28933][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:40.753 UTC [28933][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:40.753 UTC [28933][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:40.755 UTC [28933][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-26 08:40:40.852 UTC [28933][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.107 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.020 UTC [29006][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.021 UTC [29006][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.021 UTC [29006][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.023 UTC [29006][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-26 08:40:41.060 UTC [29006][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.165 UTC [29026][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.165 UTC [29026][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.165 UTC [29026][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.167 UTC [29026][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-26 08:40:41.261 UTC [29026][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.105 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.281 UTC [29058][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.282 UTC [29058][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.282 UTC [29058][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.284 UTC [29058][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-26 08:40:41.335 UTC [29058][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.419 UTC [29094][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 08:40:41.420 UTC [29094][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-26 08:40:41.420 UTC [29094][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-26 08:40:41.422 UTC [29094][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-26 08:40:41.493 UTC [29094][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-26 08:40:41.546 UTC [28786][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:41.546 UTC [28786][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:41.551 UTC [28786][postmaster] LOG:  background worker "logical replication launcher" (PID 28803) exited with exit code 1
2024-03-26 08:40:41.553 UTC [28794][checkpointer] LOG:  shutting down
2024-03-26 08:40:41.553 UTC [28794][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:41.607 UTC [28794][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.053 s, sync=0.001 s, total=0.054 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414DB70, redo lsn=0/414DB70
2024-03-26 08:40:41.620 UTC [28786][postmaster] LOG:  database system is shut down
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:41.738 UTC [29149][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:41.768 UTC [29176][startup] LOG:  database system was shut down at 2024-03-26 08:40:41 UTC
2024-03-26 08:40:41.778 UTC [29149][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:41.858 UTC [29149][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:41.858 UTC [29149][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:41.866 UTC [29149][postmaster] LOG:  background worker "logical replication launcher" (PID 29186) exited with exit code 1
2024-03-26 08:40:41.866 UTC [29174][checkpointer] LOG:  shutting down
2024-03-26 08:40:41.866 UTC [29174][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:41.867 UTC [29174][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/414DBE8, redo lsn=0/414DBE8
2024-03-26 08:40:41.872 UTC [29149][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.015 UTC [29223][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-26 08:40:42.015 UTC [29223][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.145 UTC [29251][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:42.145 UTC [29251][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:42.145 UTC [29251][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:42.160 UTC [29268][startup] LOG:  database system was shut down at 2024-03-26 08:40:41 UTC
2024-03-26 08:40:42.173 UTC [29251][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:42.298 UTC [29251][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:42.298 UTC [29251][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:42.303 UTC [29251][postmaster] LOG:  background worker "logical replication launcher" (PID 29276) exited with exit code 1
2024-03-26 08:40:42.312 UTC [29266][checkpointer] LOG:  shutting down
2024-03-26 08:40:42.312 UTC [29266][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:42.313 UTC [29266][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/414DC60, redo lsn=0/414DC60
2024-03-26 08:40:42.319 UTC [29251][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.446 UTC [29331][postmaster] FATAL:  could not set SSL protocol version range
2024-03-26 08:40:42.446 UTC [29331][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-26 08:40:42.446 UTC [29331][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.557 UTC [29354][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:42.558 UTC [29354][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:42.558 UTC [29354][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:42.570 UTC [29365][startup] LOG:  database system was shut down at 2024-03-26 08:40:42 UTC
2024-03-26 08:40:42.578 UTC [29354][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:42.627 UTC [29354][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:42.627 UTC [29354][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:42.632 UTC [29354][postmaster] LOG:  background worker "logical replication launcher" (PID 29370) exited with exit code 1
2024-03-26 08:40:42.632 UTC [29363][checkpointer] LOG:  shutting down
2024-03-26 08:40:42.632 UTC [29363][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:42.633 UTC [29363][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/414DCD8, redo lsn=0/414DCD8
2024-03-26 08:40:42.639 UTC [29354][postmaster] LOG:  database system is shut down
2024-03-26 08:40:42.801 UTC [29380][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:42.801 UTC [29380][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:42.801 UTC [29380][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:42.829 UTC [29387][startup] LOG:  database system was shut down at 2024-03-26 08:40:42 UTC
2024-03-26 08:40:42.837 UTC [29380][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:42.981 UTC [29409][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58802
2024-03-26 08:40:42.982 UTC [29409][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-26 08:40:42.982 UTC [29409][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-26 08:40:43.007 UTC [29412][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58806
2024-03-26 08:40:43.018 UTC [29412][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.018 UTC [29412][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-26 08:40:43.064 UTC [29412][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-26 08:40:43.075 UTC [29412][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.068 user=ssltestuser database=trustdb host=localhost port=58806
2024-03-26 08:40:43.106 UTC [29424][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58812
2024-03-26 08:40:43.108 UTC [29424][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-26 08:40:43.165 UTC [29431][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58818
2024-03-26 08:40:43.167 UTC [29431][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-26 08:40:43.210 UTC [29435][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58822
2024-03-26 08:40:43.215 UTC [29435][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:43.257 UTC [29440][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58838
2024-03-26 08:40:43.262 UTC [29440][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:43.302 UTC [29449][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58840
2024-03-26 08:40:43.312 UTC [29449][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:43.327 UTC [29456][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58848
2024-03-26 08:40:43.335 UTC [29456][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:43.352 UTC [29459][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58864
2024-03-26 08:40:43.361 UTC [29459][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.361 UTC [29459][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-26 08:40:43.362 UTC [29459][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-26 08:40:43.381 UTC [29459][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=58864
2024-03-26 08:40:43.403 UTC [29461][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58870
2024-03-26 08:40:43.418 UTC [29461][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.418 UTC [29461][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-26 08:40:43.419 UTC [29461][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-26 08:40:43.422 UTC [29461][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=58870
2024-03-26 08:40:43.453 UTC [29474][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58886
2024-03-26 08:40:43.462 UTC [29474][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.462 UTC [29474][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-26 08:40:43.469 UTC [29474][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-26 08:40:43.474 UTC [29474][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=58886
2024-03-26 08:40:43.488 UTC [29490][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58890
2024-03-26 08:40:43.496 UTC [29490][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.496 UTC [29490][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-26 08:40:43.498 UTC [29490][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-26 08:40:43.498 UTC [29490][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=58890
2024-03-26 08:40:43.535 UTC [29501][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58894
2024-03-26 08:40:43.546 UTC [29501][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.546 UTC [29501][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-26 08:40:43.548 UTC [29501][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-26 08:40:43.549 UTC [29501][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=58894
2024-03-26 08:40:43.564 UTC [29511][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58898
2024-03-26 08:40:43.584 UTC [29511][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.584 UTC [29511][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-26 08:40:43.586 UTC [29511][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-26 08:40:43.591 UTC [29511][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=58898
2024-03-26 08:40:43.652 UTC [29521][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58902
2024-03-26 08:40:43.658 UTC [29521][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.658 UTC [29521][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-26 08:40:43.660 UTC [29521][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-26 08:40:43.666 UTC [29521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=58902
2024-03-26 08:40:43.718 UTC [29526][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58908
2024-03-26 08:40:43.727 UTC [29526][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.727 UTC [29526][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-26 08:40:43.732 UTC [29526][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=58908
2024-03-26 08:40:43.762 UTC [29530][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58912
2024-03-26 08:40:43.768 UTC [29530][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.768 UTC [29530][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-26 08:40:43.771 UTC [29530][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-26 08:40:43.776 UTC [29530][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=58912
2024-03-26 08:40:43.813 UTC [29541][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58918
2024-03-26 08:40:43.823 UTC [29541][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:43.860 UTC [29546][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58928
2024-03-26 08:40:43.868 UTC [29546][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:43.912 UTC [29552][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58944
2024-03-26 08:40:43.919 UTC [29552][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.919 UTC [29552][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-26 08:40:43.921 UTC [29552][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-26 08:40:43.926 UTC [29552][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=58944
2024-03-26 08:40:43.941 UTC [29555][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58952
2024-03-26 08:40:43.947 UTC [29555][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.947 UTC [29555][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-26 08:40:43.949 UTC [29555][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-26 08:40:43.954 UTC [29555][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=58952
2024-03-26 08:40:43.972 UTC [29561][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58962
2024-03-26 08:40:43.979 UTC [29561][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:43.979 UTC [29561][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-26 08:40:43.980 UTC [29561][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-26 08:40:43.983 UTC [29561][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=58962
2024-03-26 08:40:44.007 UTC [29568][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58978
2024-03-26 08:40:44.022 UTC [29568][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:44.022 UTC [29568][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-26 08:40:44.025 UTC [29568][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-26 08:40:44.025 UTC [29568][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=58978
2024-03-26 08:40:44.065 UTC [29572][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58982
2024-03-26 08:40:44.080 UTC [29380][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:44.080 UTC [29380][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:44.088 UTC [29380][postmaster] LOG:  background worker "logical replication launcher" (PID 29395) exited with exit code 1
2024-03-26 08:40:44.089 UTC [29385][checkpointer] LOG:  shutting down
2024-03-26 08:40:44.089 UTC [29385][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:44.090 UTC [29385][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/414DD50, redo lsn=0/414DD50
2024-03-26 08:40:44.096 UTC [29380][postmaster] LOG:  database system is shut down
2024-03-26 08:40:44.279 UTC [29607][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:44.279 UTC [29607][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:44.279 UTC [29607][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:44.301 UTC [29615][startup] LOG:  database system was shut down at 2024-03-26 08:40:44 UTC
2024-03-26 08:40:44.309 UTC [29607][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:44.421 UTC [29651][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58994
2024-03-26 08:40:44.428 UTC [29651][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:44.428 UTC [29651][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-26 08:40:44.443 UTC [29651][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-26 08:40:44.449 UTC [29651][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=58994
2024-03-26 08:40:44.466 UTC [29655][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59008
2024-03-26 08:40:44.484 UTC [29607][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:44.484 UTC [29607][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:44.493 UTC [29607][postmaster] LOG:  background worker "logical replication launcher" (PID 29623) exited with exit code 1
2024-03-26 08:40:44.493 UTC [29613][checkpointer] LOG:  shutting down
2024-03-26 08:40:44.493 UTC [29613][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:44.494 UTC [29613][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/414DDC8, redo lsn=0/414DDC8
2024-03-26 08:40:44.500 UTC [29607][postmaster] LOG:  database system is shut down
2024-03-26 08:40:44.630 UTC [29686][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:44.630 UTC [29686][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:44.630 UTC [29686][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:44.640 UTC [29696][startup] LOG:  database system was shut down at 2024-03-26 08:40:44 UTC
2024-03-26 08:40:44.648 UTC [29686][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:44.719 UTC [29711][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59024
2024-03-26 08:40:44.727 UTC [29711][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:44.727 UTC [29711][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-26 08:40:44.757 UTC [29711][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-26 08:40:44.763 UTC [29711][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=ssltestuser database=trustdb host=localhost port=59024
2024-03-26 08:40:44.765 UTC [29686][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:44.765 UTC [29686][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:44.770 UTC [29686][postmaster] LOG:  background worker "logical replication launcher" (PID 29701) exited with exit code 1
2024-03-26 08:40:44.776 UTC [29694][checkpointer] LOG:  shutting down
2024-03-26 08:40:44.776 UTC [29694][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:44.777 UTC [29694][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/414DE40, redo lsn=0/414DE40
2024-03-26 08:40:44.784 UTC [29686][postmaster] LOG:  database system is shut down
2024-03-26 08:40:44.899 UTC [29734][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:44.899 UTC [29734][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:44.899 UTC [29734][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:44.919 UTC [29744][startup] LOG:  database system was shut down at 2024-03-26 08:40:44 UTC
2024-03-26 08:40:44.927 UTC [29734][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:44.999 UTC [29759][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49554
2024-03-26 08:40:45.016 UTC [29759][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:45.016 UTC [29759][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-26 08:40:45.029 UTC [29759][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-26 08:40:45.035 UTC [29759][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=49554
2024-03-26 08:40:45.063 UTC [29770][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49570
2024-03-26 08:40:45.069 UTC [29770][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:45.069 UTC [29770][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-26 08:40:45.071 UTC [29770][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-26 08:40:45.074 UTC [29770][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=49570
2024-03-26 08:40:45.125 UTC [29780][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49582
2024-03-26 08:40:45.131 UTC [29780][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:45.131 UTC [29780][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-26 08:40:45.133 UTC [29780][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-26 08:40:45.133 UTC [29780][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=49582
2024-03-26 08:40:45.165 UTC [29787][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49586
2024-03-26 08:40:45.196 UTC [29797][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49600
2024-03-26 08:40:45.213 UTC [29734][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:45.213 UTC [29734][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:45.230 UTC [29734][postmaster] LOG:  background worker "logical replication launcher" (PID 29752) exited with exit code 1
2024-03-26 08:40:45.230 UTC [29742][checkpointer] LOG:  shutting down
2024-03-26 08:40:45.230 UTC [29742][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:45.230 UTC [29742][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/414DEB8, redo lsn=0/414DEB8
2024-03-26 08:40:45.236 UTC [29734][postmaster] LOG:  database system is shut down
2024-03-26 08:40:45.379 UTC [29806][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:45.379 UTC [29806][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:45.379 UTC [29806][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:45.416 UTC [29811][startup] LOG:  database system was shut down at 2024-03-26 08:40:45 UTC
2024-03-26 08:40:45.438 UTC [29806][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:45.576 UTC [29823][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49602
2024-03-26 08:40:45.584 UTC [29823][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:45.584 UTC [29823][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-26 08:40:45.616 UTC [29823][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-26 08:40:45.623 UTC [29823][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=ssltestuser database=trustdb host=localhost port=49602
2024-03-26 08:40:45.648 UTC [29831][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49610
2024-03-26 08:40:45.693 UTC [29833][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49614
2024-03-26 08:40:45.734 UTC [29806][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:45.734 UTC [29806][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:45.746 UTC [29806][postmaster] LOG:  background worker "logical replication launcher" (PID 29815) exited with exit code 1
2024-03-26 08:40:45.746 UTC [29809][checkpointer] LOG:  shutting down
2024-03-26 08:40:45.746 UTC [29809][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:45.746 UTC [29809][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/414DF30, redo lsn=0/414DF30
2024-03-26 08:40:45.751 UTC [29806][postmaster] LOG:  database system is shut down
2024-03-26 08:40:45.881 UTC [29836][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:45.881 UTC [29836][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:45.881 UTC [29836][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:45.886 UTC [29841][startup] LOG:  database system was shut down at 2024-03-26 08:40:45 UTC
2024-03-26 08:40:45.894 UTC [29836][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:45.977 UTC [29849][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49628
2024-03-26 08:40:45.986 UTC [29849][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:45.986 UTC [29849][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-26 08:40:46.005 UTC [29849][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-26 08:40:46.007 UTC [29849][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=49628
2024-03-26 08:40:46.022 UTC [29853][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49640
2024-03-26 08:40:46.028 UTC [29853][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.028 UTC [29853][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-26 08:40:46.030 UTC [29853][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-26 08:40:46.033 UTC [29853][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49640
2024-03-26 08:40:46.048 UTC [29855][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49644
2024-03-26 08:40:46.074 UTC [29857][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49646
2024-03-26 08:40:46.081 UTC [29857][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.081 UTC [29857][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-26 08:40:46.082 UTC [29857][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-26 08:40:46.088 UTC [29857][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49646
2024-03-26 08:40:46.133 UTC [29861][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49648
2024-03-26 08:40:46.140 UTC [29861][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.140 UTC [29861][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-26 08:40:46.141 UTC [29861][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-26 08:40:46.145 UTC [29861][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49648
2024-03-26 08:40:46.197 UTC [29867][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49654
2024-03-26 08:40:46.203 UTC [29867][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.203 UTC [29867][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-26 08:40:46.205 UTC [29867][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-26 08:40:46.205 UTC [29867][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=49654
2024-03-26 08:40:46.241 UTC [29870][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49670
2024-03-26 08:40:46.283 UTC [29872][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49674
2024-03-26 08:40:46.303 UTC [29836][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:46.303 UTC [29836][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:46.312 UTC [29836][postmaster] LOG:  background worker "logical replication launcher" (PID 29844) exited with exit code 1
2024-03-26 08:40:46.312 UTC [29839][checkpointer] LOG:  shutting down
2024-03-26 08:40:46.312 UTC [29839][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:46.313 UTC [29839][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/414DFA8, redo lsn=0/414DFA8
2024-03-26 08:40:46.318 UTC [29836][postmaster] LOG:  database system is shut down
2024-03-26 08:40:46.468 UTC [29895][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:46.468 UTC [29895][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:46.468 UTC [29895][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:46.492 UTC [29906][startup] LOG:  database system was shut down at 2024-03-26 08:40:46 UTC
2024-03-26 08:40:46.500 UTC [29895][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:46.557 UTC [29917][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49676
2024-03-26 08:40:46.563 UTC [29917][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.563 UTC [29917][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-26 08:40:46.580 UTC [29917][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-26 08:40:46.581 UTC [29917][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=49676
2024-03-26 08:40:46.604 UTC [29926][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49692
2024-03-26 08:40:46.612 UTC [29926][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.612 UTC [29926][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-26 08:40:46.619 UTC [29926][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-26 08:40:46.626 UTC [29926][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=49692
2024-03-26 08:40:46.668 UTC [29930][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49700
2024-03-26 08:40:46.691 UTC [29895][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:46.691 UTC [29895][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:46.696 UTC [29895][postmaster] LOG:  background worker "logical replication launcher" (PID 29911) exited with exit code 1
2024-03-26 08:40:46.701 UTC [29904][checkpointer] LOG:  shutting down
2024-03-26 08:40:46.701 UTC [29904][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:46.702 UTC [29904][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/414E038, redo lsn=0/414E038
2024-03-26 08:40:46.708 UTC [29895][postmaster] LOG:  database system is shut down
2024-03-26 08:40:46.820 UTC [29954][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:46.820 UTC [29954][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:46.820 UTC [29954][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:46.837 UTC [29958][startup] LOG:  database system was shut down at 2024-03-26 08:40:46 UTC
2024-03-26 08:40:46.846 UTC [29954][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:46.906 UTC [29970][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49708
2024-03-26 08:40:46.920 UTC [29970][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.920 UTC [29970][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-26 08:40:46.941 UTC [29970][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-26 08:40:46.947 UTC [29970][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=trustdb host=localhost port=49708
2024-03-26 08:40:46.975 UTC [29976][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49718
2024-03-26 08:40:46.984 UTC [29976][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:46.984 UTC [29976][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-26 08:40:46.987 UTC [29976][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-26 08:40:46.993 UTC [29976][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49718
2024-03-26 08:40:47.019 UTC [29984][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49724
2024-03-26 08:40:47.032 UTC [29984][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:47.032 UTC [29984][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-26 08:40:47.034 UTC [29984][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-26 08:40:47.034 UTC [29984][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=49724
2024-03-26 08:40:47.045 UTC [29954][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:47.045 UTC [29954][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:47.054 UTC [29954][postmaster] LOG:  background worker "logical replication launcher" (PID 29962) exited with exit code 1
2024-03-26 08:40:47.054 UTC [29956][checkpointer] LOG:  shutting down
2024-03-26 08:40:47.054 UTC [29956][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:47.055 UTC [29956][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/414E0B0, redo lsn=0/414E0B0
2024-03-26 08:40:47.061 UTC [29954][postmaster] LOG:  database system is shut down
2024-03-26 08:40:47.223 UTC [30012][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:47.223 UTC [30012][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:47.223 UTC [30012][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:47.238 UTC [30020][startup] LOG:  database system was shut down at 2024-03-26 08:40:47 UTC
2024-03-26 08:40:47.247 UTC [30012][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:47.298 UTC [30036][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49732
2024-03-26 08:40:47.305 UTC [30036][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:47.305 UTC [30036][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-26 08:40:47.317 UTC [30036][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-26 08:40:47.324 UTC [30036][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=49732
2024-03-26 08:40:47.358 UTC [30045][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49740
2024-03-26 08:40:47.365 UTC [30045][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:47.365 UTC [30045][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-26 08:40:47.368 UTC [30045][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-26 08:40:47.376 UTC [30045][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49740
2024-03-26 08:40:47.396 UTC [30054][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49754
2024-03-26 08:40:47.424 UTC [30012][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:47.424 UTC [30012][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:47.432 UTC [30012][postmaster] LOG:  background worker "logical replication launcher" (PID 30027) exited with exit code 1
2024-03-26 08:40:47.433 UTC [30018][checkpointer] LOG:  shutting down
2024-03-26 08:40:47.433 UTC [30018][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:47.434 UTC [30018][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/414E128, redo lsn=0/414E128
2024-03-26 08:40:47.440 UTC [30012][postmaster] LOG:  database system is shut down
2024-03-26 08:40:47.560 UTC [30068][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:47.560 UTC [30068][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:47.560 UTC [30068][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:47.573 UTC [30077][startup] LOG:  database system was shut down at 2024-03-26 08:40:47 UTC
2024-03-26 08:40:47.599 UTC [30068][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:47.655 UTC [30090][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49760
2024-03-26 08:40:47.664 UTC [30090][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:47.664 UTC [30090][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-26 08:40:47.677 UTC [30090][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-26 08:40:47.683 UTC [30090][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=49760
2024-03-26 08:40:47.731 UTC [30096][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49762
2024-03-26 08:40:47.743 UTC [30096][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:47.743 UTC [30096][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-26 08:40:47.748 UTC [30096][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-26 08:40:47.755 UTC [30096][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=49762
2024-03-26 08:40:47.774 UTC [30110][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49778
2024-03-26 08:40:47.781 UTC [30110][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:47.781 UTC [30110][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-26 08:40:47.782 UTC [30110][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-26 08:40:47.783 UTC [30110][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49778
2024-03-26 08:40:47.803 UTC [30068][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:47.803 UTC [30068][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:47.808 UTC [30068][postmaster] LOG:  background worker "logical replication launcher" (PID 30085) exited with exit code 1
2024-03-26 08:40:47.813 UTC [30075][checkpointer] LOG:  shutting down
2024-03-26 08:40:47.814 UTC [30075][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:47.814 UTC [30075][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/414E1A0, redo lsn=0/414E1A0
2024-03-26 08:40:47.821 UTC [30068][postmaster] LOG:  database system is shut down
2024-03-26 08:40:47.949 UTC [30136][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:47.949 UTC [30136][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:47.949 UTC [30136][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:47.963 UTC [30150][startup] LOG:  database system was shut down at 2024-03-26 08:40:47 UTC
2024-03-26 08:40:47.971 UTC [30136][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:48.027 UTC [30163][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49792
2024-03-26 08:40:48.035 UTC [30163][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:48.035 UTC [30163][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-26 08:40:48.048 UTC [30163][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-26 08:40:48.054 UTC [30163][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=49792
2024-03-26 08:40:48.110 UTC [30180][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49804
2024-03-26 08:40:48.135 UTC [30136][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:48.135 UTC [30136][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:48.140 UTC [30136][postmaster] LOG:  background worker "logical replication launcher" (PID 30154) exited with exit code 1
2024-03-26 08:40:48.146 UTC [30148][checkpointer] LOG:  shutting down
2024-03-26 08:40:48.146 UTC [30148][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:48.147 UTC [30148][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/414E218, redo lsn=0/414E218
2024-03-26 08:40:48.153 UTC [30136][postmaster] LOG:  database system is shut down
2024-03-26 08:40:48.298 UTC [30193][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:48.299 UTC [30193][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:48.299 UTC [30193][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:48.327 UTC [30200][startup] LOG:  database system was shut down at 2024-03-26 08:40:48 UTC
2024-03-26 08:40:48.336 UTC [30193][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:48.454 UTC [30227][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49820
2024-03-26 08:40:48.462 UTC [30227][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-26 08:40:48.518 UTC [30234][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49824
2024-03-26 08:40:48.530 UTC [30234][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:48.530 UTC [30234][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-26 08:40:48.555 UTC [30234][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-26 08:40:48.562 UTC [30234][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.045 user=ssltestuser database=trustdb host=localhost port=49824
2024-03-26 08:40:48.576 UTC [30254][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49828
2024-03-26 08:40:48.600 UTC [30193][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:48.600 UTC [30193][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:48.610 UTC [30193][postmaster] LOG:  background worker "logical replication launcher" (PID 30205) exited with exit code 1
2024-03-26 08:40:48.613 UTC [30198][checkpointer] LOG:  shutting down
2024-03-26 08:40:48.613 UTC [30198][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:48.614 UTC [30198][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/414E290, redo lsn=0/414E290
2024-03-26 08:40:48.622 UTC [30193][postmaster] LOG:  database system is shut down
2024-03-26 08:40:48.730 UTC [30275][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:48.730 UTC [30275][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:48.730 UTC [30275][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:48.755 UTC [30291][startup] LOG:  database system was shut down at 2024-03-26 08:40:48 UTC
2024-03-26 08:40:48.763 UTC [30275][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:48.821 UTC [30307][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49842
2024-03-26 08:40:48.835 UTC [30307][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:48.835 UTC [30307][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-26 08:40:48.853 UTC [30307][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-26 08:40:48.858 UTC [30307][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=49842
2024-03-26 08:40:48.892 UTC [30317][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49844
2024-03-26 08:40:48.902 UTC [30317][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-26 08:40:48.920 UTC [30324][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49856
2024-03-26 08:40:48.933 UTC [30324][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-26 08:40:48.965 UTC [30331][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49872
2024-03-26 08:40:48.972 UTC [30331][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:48.972 UTC [30331][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-26 08:40:48.977 UTC [30331][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-26 08:40:48.985 UTC [30331][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=49872
2024-03-26 08:40:49.009 UTC [30338][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49886
2024-03-26 08:40:49.016 UTC [30338][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:49.016 UTC [30338][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-26 08:40:49.020 UTC [30338][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-26 08:40:49.024 UTC [30338][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49886
2024-03-26 08:40:49.117 UTC [30355][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49900
2024-03-26 08:40:49.128 UTC [30355][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-26 08:40:49.191 UTC [30361][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49902
2024-03-26 08:40:49.202 UTC [30361][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.202 UTC [30361][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-26 08:40:49.219 UTC [30361][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/src/test/ssl/tmp_check/tmp_test_yaEB/client.key$$
2024-03-26 08:40:49.226 UTC [30361][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=certdb host=localhost port=49902
2024-03-26 08:40:49.261 UTC [30368][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49904
2024-03-26 08:40:49.271 UTC [30368][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.271 UTC [30368][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-26 08:40:49.273 UTC [30368][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/src/test/ssl/tmp_check/tmp_test_yaEB/client-der.key$$
2024-03-26 08:40:49.277 UTC [30368][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=certdb host=localhost port=49904
2024-03-26 08:40:49.297 UTC [30386][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49912
2024-03-26 08:40:49.307 UTC [30386][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.307 UTC [30386][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-26 08:40:49.309 UTC [30386][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/src/test/ssl/tmp_check/tmp_test_yaEB/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-26 08:40:49.313 UTC [30386][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=certdb host=localhost port=49912
2024-03-26 08:40:49.336 UTC [30392][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49914
2024-03-26 08:40:49.345 UTC [30392][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.345 UTC [30392][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-26 08:40:49.347 UTC [30392][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/src/test/ssl/tmp_check/tmp_test_yaEB/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-26 08:40:49.350 UTC [30392][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=certdb host=localhost port=49914
2024-03-26 08:40:49.387 UTC [30397][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49928
2024-03-26 08:40:49.405 UTC [30397][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.405 UTC [30397][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-26 08:40:49.407 UTC [30397][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/src/test/ssl/tmp_check/tmp_test_yaEB/client.key$$
2024-03-26 08:40:49.411 UTC [30397][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=49928
2024-03-26 08:40:49.449 UTC [30406][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49940
2024-03-26 08:40:49.459 UTC [30406][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.459 UTC [30406][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-26 08:40:49.461 UTC [30406][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/src/test/ssl/tmp_check/tmp_test_yaEB/client.key$$
2024-03-26 08:40:49.467 UTC [30406][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=certdb host=localhost port=49940
2024-03-26 08:40:49.487 UTC [30410][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49944
2024-03-26 08:40:49.494 UTC [30410][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-26 08:40:49.527 UTC [30415][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49948
2024-03-26 08:40:49.533 UTC [30415][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-26 08:40:49.570 UTC [30420][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49952
2024-03-26 08:40:49.582 UTC [30420][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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8)
2024-03-26 08:40:49.582 UTC [30420][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-26 08:40:49.593 UTC [30420][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/src/test/ssl/tmp_check/tmp_test_yaEB/client-dn.key$$
2024-03-26 08:40:49.600 UTC [30420][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=certdb_dn host=localhost port=49952
2024-03-26 08:40:49.622 UTC [30428][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49960
2024-03-26 08:40:49.630 UTC [30428][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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9)
2024-03-26 08:40:49.630 UTC [30428][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-26 08:40:49.642 UTC [30428][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/src/test/ssl/tmp_check/tmp_test_yaEB/client-dn.key$$
2024-03-26 08:40:49.652 UTC [30428][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=certdb_dn_re host=localhost port=49960
2024-03-26 08:40:49.673 UTC [30437][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49962
2024-03-26 08:40:49.690 UTC [30437][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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10)
2024-03-26 08:40:49.690 UTC [30437][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-26 08:40:49.705 UTC [30437][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/src/test/ssl/tmp_check/tmp_test_yaEB/client-dn.key$$
2024-03-26 08:40:49.712 UTC [30437][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=certdb_cn host=localhost port=49962
2024-03-26 08:40:49.734 UTC [30449][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49972
2024-03-26 08:40:49.744 UTC [30449][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.744 UTC [30449][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-26 08:40:49.747 UTC [30449][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-26 08:40:49.758 UTC [30449][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=49972
2024-03-26 08:40:49.777 UTC [30460][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49976
2024-03-26 08:40:49.782 UTC [30460][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-26 08:40:49.799 UTC [30463][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49990
2024-03-26 08:40:49.811 UTC [30463][client backend] [[unknown]][15/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:49.811 UTC [30463][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-26 08:40:49.811 UTC [30463][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-26 08:40:49.811 UTC [30463][client backend] [[unknown]][15/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb          all             127.0.0.1/32            cert"
2024-03-26 08:40:49.844 UTC [30469][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=49996
2024-03-26 08:40:49.855 UTC [30469][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-26 08:40:49.855 UTC [30469][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-26 08:40:49.881 UTC [30478][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50002
2024-03-26 08:40:49.897 UTC [30478][client backend] [[unknown]][16/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4)
2024-03-26 08:40:49.897 UTC [30478][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-26 08:40:49.908 UTC [30478][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/src/test/ssl/tmp_check/tmp_test_yaEB/client.key$$
2024-03-26 08:40:49.914 UTC [30478][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=verifydb host=localhost port=50002
2024-03-26 08:40:49.952 UTC [30495][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50018
2024-03-26 08:40:49.968 UTC [30495][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-26 08:40:49.968 UTC [30495][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-26 08:40:49.968 UTC [30495][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-26 08:40:49.968 UTC [30495][client backend] [[unknown]][17/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-26 08:40:50.013 UTC [30504][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50026
2024-03-26 08:40:50.021 UTC [30504][client backend] [[unknown]][18/1:0] LOG:  connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6)
2024-03-26 08:40:50.021 UTC [30504][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-26 08:40:50.023 UTC [30504][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/src/test/ssl/tmp_check/tmp_test_yaEB/client.key$$
2024-03-26 08:40:50.027 UTC [30504][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=yetanotheruser database=verifydb host=localhost port=50026
2024-03-26 08:40:50.042 UTC [30275][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:50.042 UTC [30275][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:50.058 UTC [30275][postmaster] LOG:  background worker "logical replication launcher" (PID 30298) exited with exit code 1
2024-03-26 08:40:50.058 UTC [30289][checkpointer] LOG:  shutting down
2024-03-26 08:40:50.058 UTC [30289][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:50.059 UTC [30289][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/414E308, redo lsn=0/414E308
2024-03-26 08:40:50.065 UTC [30275][postmaster] LOG:  database system is shut down
2024-03-26 08:40:50.181 UTC [30530][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:50.181 UTC [30530][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:50.181 UTC [30530][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:50.217 UTC [30536][startup] LOG:  database system was shut down at 2024-03-26 08:40:50 UTC
2024-03-26 08:40:50.225 UTC [30530][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:50.272 UTC [30548][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50032
2024-03-26 08:40:50.284 UTC [30548][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-26 08:40:50.284 UTC [30548][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-26 08:40:50.320 UTC [30548][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/src/test/ssl/tmp_check/tmp_test_yaEB/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-26 08:40:50.327 UTC [30548][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.057 user=ssltestuser database=certdb host=localhost port=50032
2024-03-26 08:40:50.346 UTC [30563][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50034
2024-03-26 08:40:50.356 UTC [30563][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-26 08:40:50.356 UTC [30563][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-26 08:40:50.384 UTC [30569][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50036
2024-03-26 08:40:50.396 UTC [30569][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-26 08:40:50.396 UTC [30569][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-26 08:40:50.407 UTC [30530][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:50.407 UTC [30530][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:50.431 UTC [30530][postmaster] LOG:  background worker "logical replication launcher" (PID 30543) exited with exit code 1
2024-03-26 08:40:50.431 UTC [30534][checkpointer] LOG:  shutting down
2024-03-26 08:40:50.431 UTC [30534][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:50.432 UTC [30534][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/414E380, redo lsn=0/414E380
2024-03-26 08:40:50.444 UTC [30530][postmaster] LOG:  database system is shut down
2024-03-26 08:40:50.545 UTC [30599][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:50.545 UTC [30599][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:50.545 UTC [30599][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:50.569 UTC [30606][startup] LOG:  database system was shut down at 2024-03-26 08:40:50 UTC
2024-03-26 08:40:50.578 UTC [30599][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:50.651 UTC [30625][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50046
2024-03-26 08:40:50.662 UTC [30625][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-26 08:40:50.662 UTC [30625][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-26 08:40:50.675 UTC [30599][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:50.675 UTC [30599][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:50.681 UTC [30599][postmaster] LOG:  background worker "logical replication launcher" (PID 30612) exited with exit code 1
2024-03-26 08:40:50.686 UTC [30604][checkpointer] LOG:  shutting down
2024-03-26 08:40:50.686 UTC [30604][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:50.687 UTC [30604][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/414E3F8, redo lsn=0/414E3F8
2024-03-26 08:40:50.693 UTC [30599][postmaster] LOG:  database system is shut down
2024-03-26 08:40:50.825 UTC [30659][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:50.825 UTC [30659][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:50.825 UTC [30659][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:50.852 UTC [30669][startup] LOG:  database system was shut down at 2024-03-26 08:40:50 UTC
2024-03-26 08:40:50.861 UTC [30659][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:50.917 UTC [30681][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50058
2024-03-26 08:40:50.925 UTC [30681][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-26 08:40:50.925 UTC [30681][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-26 08:40:50.957 UTC [30686][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50066
2024-03-26 08:40:50.965 UTC [30686][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-26 08:40:50.965 UTC [30686][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-26 08:40:50.979 UTC [30659][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:50.979 UTC [30659][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:50.992 UTC [30659][postmaster] LOG:  background worker "logical replication launcher" (PID 30674) exited with exit code 1
2024-03-26 08:40:50.993 UTC [30667][checkpointer] LOG:  shutting down
2024-03-26 08:40:50.993 UTC [30667][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:50.993 UTC [30667][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/414E470, redo lsn=0/414E470
2024-03-26 08:40:50.999 UTC [30659][postmaster] LOG:  database system is shut down
2024-03-26 08:40:51.104 UTC [30700][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:51.104 UTC [30700][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:51.113 UTC [30700][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:51.132 UTC [30706][startup] LOG:  database system was shut down at 2024-03-26 08:40:50 UTC
2024-03-26 08:40:51.147 UTC [30700][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:51.210 UTC [30730][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50074
2024-03-26 08:40:51.213 UTC [30730][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:51.214 UTC [30730][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:51.226 UTC [30730][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-26 08:40:51.249 UTC [30740][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50076
2024-03-26 08:40:51.257 UTC [30740][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:51.257 UTC [30740][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-26 08:40:51.284 UTC [30740][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-26 08:40:51.288 UTC [30740][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=trustdb host=localhost port=50076
2024-03-26 08:40:51.306 UTC [30700][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:51.306 UTC [30700][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:51.315 UTC [30700][postmaster] LOG:  background worker "logical replication launcher" (PID 30712) exited with exit code 1
2024-03-26 08:40:51.315 UTC [30704][checkpointer] LOG:  shutting down
2024-03-26 08:40:51.315 UTC [30704][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:51.316 UTC [30704][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/414E4E8, redo lsn=0/414E4E8
2024-03-26 08:40:51.322 UTC [30700][postmaster] LOG:  database system is shut down
2024-03-26 08:40:51.441 UTC [30776][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:51.441 UTC [30776][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:51.441 UTC [30776][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:51.453 UTC [30787][startup] LOG:  database system was shut down at 2024-03-26 08:40:51 UTC
2024-03-26 08:40:51.466 UTC [30776][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:51.543 UTC [30808][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50088
2024-03-26 08:40:51.546 UTC [30808][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:51.547 UTC [30808][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:51.556 UTC [30808][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-26 08:40:51.573 UTC [30815][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50094
2024-03-26 08:40:51.580 UTC [30815][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:51.580 UTC [30815][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-26 08:40:51.594 UTC [30815][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-26 08:40:51.601 UTC [30815][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=50094
2024-03-26 08:40:51.610 UTC [30776][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:51.610 UTC [30776][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:51.615 UTC [30776][postmaster] LOG:  background worker "logical replication launcher" (PID 30793) exited with exit code 1
2024-03-26 08:40:51.625 UTC [30785][checkpointer] LOG:  shutting down
2024-03-26 08:40:51.625 UTC [30785][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:51.625 UTC [30785][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/414E560, redo lsn=0/414E560
2024-03-26 08:40:51.632 UTC [30776][postmaster] LOG:  database system is shut down
2024-03-26 08:40:51.739 UTC [30838][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:51.739 UTC [30838][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:51.739 UTC [30838][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:51.748 UTC [30846][startup] LOG:  database system was shut down at 2024-03-26 08:40:51 UTC
2024-03-26 08:40:51.756 UTC [30838][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:51.842 UTC [30863][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50108
2024-03-26 08:40:51.845 UTC [30863][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:51.845 UTC [30863][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-26 08:40:51.894 UTC [30876][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50118
2024-03-26 08:40:51.900 UTC [30876][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:51.900 UTC [30876][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-26 08:40:51.927 UTC [30876][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-26 08:40:51.933 UTC [30876][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=50118
2024-03-26 08:40:51.939 UTC [30838][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:51.939 UTC [30838][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:51.944 UTC [30838][postmaster] LOG:  background worker "logical replication launcher" (PID 30850) exited with exit code 1
2024-03-26 08:40:51.948 UTC [30844][checkpointer] LOG:  shutting down
2024-03-26 08:40:51.948 UTC [30844][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:51.949 UTC [30844][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/414E5D8, redo lsn=0/414E5D8
2024-03-26 08:40:51.954 UTC [30838][postmaster] LOG:  database system is shut down
2024-03-26 08:40:52.095 UTC [30901][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:52.095 UTC [30901][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:52.095 UTC [30901][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:52.108 UTC [30908][startup] LOG:  database system was shut down at 2024-03-26 08:40:51 UTC
2024-03-26 08:40:52.116 UTC [30901][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:52.161 UTC [30925][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50126
2024-03-26 08:40:52.170 UTC [30925][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:52.170 UTC [30925][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-26 08:40:52.206 UTC [30930][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50128
2024-03-26 08:40:52.213 UTC [30930][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:52.213 UTC [30930][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-26 08:40:52.226 UTC [30930][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-26 08:40:52.231 UTC [30930][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=50128
2024-03-26 08:40:52.234 UTC [30901][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:52.234 UTC [30901][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:52.243 UTC [30901][postmaster] LOG:  background worker "logical replication launcher" (PID 30915) exited with exit code 1
2024-03-26 08:40:52.244 UTC [30906][checkpointer] LOG:  shutting down
2024-03-26 08:40:52.244 UTC [30906][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:52.244 UTC [30906][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/414E650, redo lsn=0/414E650
2024-03-26 08:40:52.250 UTC [30901][postmaster] LOG:  database system is shut down
2024-03-26 08:40:52.378 UTC [30957][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:52.378 UTC [30957][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:52.378 UTC [30957][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:52.387 UTC [30963][startup] LOG:  database system was shut down at 2024-03-26 08:40:52 UTC
2024-03-26 08:40:52.396 UTC [30957][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:52.467 UTC [30982][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50134
2024-03-26 08:40:52.469 UTC [30982][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:52.469 UTC [30982][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-26 08:40:52.513 UTC [30991][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50136
2024-03-26 08:40:52.521 UTC [30991][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:52.521 UTC [30991][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-26 08:40:52.537 UTC [30991][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-26 08:40:52.543 UTC [30991][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=50136
2024-03-26 08:40:52.553 UTC [30957][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:52.553 UTC [30957][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:52.557 UTC [30957][postmaster] LOG:  background worker "logical replication launcher" (PID 30969) exited with exit code 1
2024-03-26 08:40:52.564 UTC [30961][checkpointer] LOG:  shutting down
2024-03-26 08:40:52.564 UTC [30961][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:52.565 UTC [30961][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/414E6C8, redo lsn=0/414E6C8
2024-03-26 08:40:52.571 UTC [30957][postmaster] LOG:  database system is shut down
2024-03-26 08:40:52.701 UTC [31014][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:52.702 UTC [31014][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:52.702 UTC [31014][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:52.714 UTC [31027][startup] LOG:  database system was shut down at 2024-03-26 08:40:52 UTC
2024-03-26 08:40:52.723 UTC [31014][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:52.801 UTC [31054][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50148
2024-03-26 08:40:52.803 UTC [31054][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:52.803 UTC [31054][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-26 08:40:52.845 UTC [31061][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50152
2024-03-26 08:40:52.851 UTC [31061][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:52.851 UTC [31061][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-26 08:40:52.877 UTC [31061][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-26 08:40:52.882 UTC [31061][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.045 user=ssltestuser database=trustdb host=localhost port=50152
2024-03-26 08:40:52.901 UTC [31014][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:52.901 UTC [31014][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:52.916 UTC [31014][postmaster] LOG:  background worker "logical replication launcher" (PID 31039) exited with exit code 1
2024-03-26 08:40:52.916 UTC [31025][checkpointer] LOG:  shutting down
2024-03-26 08:40:52.916 UTC [31025][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:52.917 UTC [31025][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/414E740, redo lsn=0/414E740
2024-03-26 08:40:52.923 UTC [31014][postmaster] LOG:  database system is shut down
2024-03-26 08:40:53.049 UTC [31086][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:53.049 UTC [31086][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:53.049 UTC [31086][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:53.060 UTC [31099][startup] LOG:  database system was shut down at 2024-03-26 08:40:52 UTC
2024-03-26 08:40:53.076 UTC [31086][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:53.150 UTC [31116][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50168
2024-03-26 08:40:53.152 UTC [31116][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:53.153 UTC [31116][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:53.160 UTC [31116][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-26 08:40:53.199 UTC [31124][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50172
2024-03-26 08:40:53.205 UTC [31124][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:53.205 UTC [31124][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-26 08:40:53.217 UTC [31124][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-26 08:40:53.224 UTC [31124][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=50172
2024-03-26 08:40:53.237 UTC [31086][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:53.237 UTC [31086][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:53.242 UTC [31086][postmaster] LOG:  background worker "logical replication launcher" (PID 31107) exited with exit code 1
2024-03-26 08:40:53.247 UTC [31097][checkpointer] LOG:  shutting down
2024-03-26 08:40:53.247 UTC [31097][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:53.247 UTC [31097][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/414E7B8, redo lsn=0/414E7B8
2024-03-26 08:40:53.255 UTC [31086][postmaster] LOG:  database system is shut down
2024-03-26 08:40:53.385 UTC [31148][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:53.385 UTC [31148][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:53.385 UTC [31148][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:53.396 UTC [31155][startup] LOG:  database system was shut down at 2024-03-26 08:40:53 UTC
2024-03-26 08:40:53.404 UTC [31148][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:53.473 UTC [31172][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50174
2024-03-26 08:40:53.475 UTC [31172][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:53.476 UTC [31172][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:53.482 UTC [31172][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-26 08:40:53.499 UTC [31180][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50182
2024-03-26 08:40:53.506 UTC [31180][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:53.506 UTC [31180][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-26 08:40:53.519 UTC [31180][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-26 08:40:53.524 UTC [31180][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=50182
2024-03-26 08:40:53.533 UTC [31148][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:53.533 UTC [31148][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:53.546 UTC [31148][postmaster] LOG:  background worker "logical replication launcher" (PID 31160) exited with exit code 1
2024-03-26 08:40:53.546 UTC [31152][checkpointer] LOG:  shutting down
2024-03-26 08:40:53.546 UTC [31152][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:53.547 UTC [31152][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/414E830, redo lsn=0/414E830
2024-03-26 08:40:53.554 UTC [31148][postmaster] LOG:  database system is shut down
2024-03-26 08:40:53.706 UTC [31230][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-26 08:40:53.706 UTC [31230][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60607
2024-03-26 08:40:53.706 UTC [31230][postmaster] LOG:  listening on Unix socket "/tmp/Dd6l5mXKVk/.s.PGSQL.60607"
2024-03-26 08:40:53.720 UTC [31237][startup] LOG:  database system was shut down at 2024-03-26 08:40:53 UTC
2024-03-26 08:40:53.730 UTC [31230][postmaster] LOG:  database system is ready to accept connections
2024-03-26 08:40:53.774 UTC [31259][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50184
2024-03-26 08:40:53.777 UTC [31259][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-26 08:40:53.777 UTC [31259][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-26 08:40:53.805 UTC [31263][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50192
2024-03-26 08:40:53.812 UTC [31263][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-26 08:40:53.812 UTC [31263][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-26 08:40:53.841 UTC [31263][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-26 08:40:53.845 UTC [31263][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.042 user=ssltestuser database=trustdb host=localhost port=50192
2024-03-26 08:40:53.859 UTC [31230][postmaster] LOG:  received fast shutdown request
2024-03-26 08:40:53.859 UTC [31230][postmaster] LOG:  aborting any active transactions
2024-03-26 08:40:53.869 UTC [31230][postmaster] LOG:  background worker "logical replication launcher" (PID 31250) exited with exit code 1
2024-03-26 08:40:53.869 UTC [31235][checkpointer] LOG:  shutting down
2024-03-26 08:40:53.869 UTC [31235][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 08:40:53.870 UTC [31235][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/414E8A8, redo lsn=0/414E8A8
2024-03-26 08:40:53.887 UTC [31230][postmaster] LOG:  database system is shut down
2024-03-26 08:40:53.983 UTC [31285][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-26 08:40:53.983 UTC [31285][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[08:40:54.061](0.216s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"