[05:40:32.516](0.038s) # setting up data directory
# Checking port 64409
# Found port 64409
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=64409 host=/tmp/pRWzRcXZrR
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[05:40:32.532](0.016s) # 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 32123
[05:40:32.922](0.390s) 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 32455
[05:40:34.587](1.665s) # 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-10 05:40:32.809 UTC [32123][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-10 05:40:32.809 UTC [32123][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:32.834 UTC [32128][startup] LOG:  database system was shut down at 2024-03-10 05:38:19 UTC
2024-03-10 05:40:32.842 UTC [32123][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:32.904 UTC [32136][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:32.905 UTC [32136][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-10 05:40:32.905 UTC [32136][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:32.919 UTC [32136][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-10 05:40:32.923 UTC [32136][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-10 05:40:32.940 UTC [32144][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:32.941 UTC [32144][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-10 05:40:32.941 UTC [32144][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:32.942 UTC [32144][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-10 05:40:32.944 UTC [32144][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.001 UTC [32149][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.002 UTC [32149][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-10 05:40:33.002 UTC [32149][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.005 UTC [32149][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-10 05:40:33.007 UTC [32149][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.049 UTC [32159][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.049 UTC [32159][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-10 05:40:33.049 UTC [32159][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.053 UTC [32159][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-10 05:40:33.055 UTC [32159][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.106 UTC [32176][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.107 UTC [32176][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-10 05:40:33.107 UTC [32176][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.109 UTC [32176][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-10 05:40:33.112 UTC [32176][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.148 UTC [32186][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.149 UTC [32186][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-10 05:40:33.149 UTC [32186][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.151 UTC [32186][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-10 05:40:33.209 UTC [32186][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.061 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.298 UTC [32209][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.299 UTC [32209][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-10 05:40:33.299 UTC [32209][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.301 UTC [32209][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-10 05:40:33.408 UTC [32209][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.110 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.582 UTC [32263][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.583 UTC [32263][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-10 05:40:33.583 UTC [32263][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.586 UTC [32263][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-10 05:40:33.656 UTC [32263][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.075 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.718 UTC [32282][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.719 UTC [32282][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-10 05:40:33.719 UTC [32282][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.724 UTC [32282][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-10 05:40:33.819 UTC [32282][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.102 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.841 UTC [32321][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.842 UTC [32321][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-10 05:40:33.842 UTC [32321][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.843 UTC [32321][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-10 05:40:33.879 UTC [32321][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.995 UTC [32376][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.996 UTC [32376][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-10 05:40:33.996 UTC [32376][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:34.006 UTC [32376][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-10 05:40:34.181 UTC [32376][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.186 user=postgres database=postgres host=[local]
2024-03-10 05:40:34.275 UTC [32123][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:34.275 UTC [32123][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:34.279 UTC [32123][postmaster] LOG:  background worker "logical replication launcher" (PID 32131) exited with exit code 1
2024-03-10 05:40:34.283 UTC [32126][checkpointer] LOG:  shutting down
2024-03-10 05:40:34.283 UTC [32126][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:34.432 UTC [32126][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.149 s, sync=0.001 s, total=0.150 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0
2024-03-10 05:40:34.473 UTC [32123][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.538 UTC [32455][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-10 05:40:34.538 UTC [32455][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:34.538 UTC [32455][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:34.570 UTC [32469][startup] LOG:  database system was shut down at 2024-03-10 05:40:34 UTC
2024-03-10 05:40:34.577 UTC [32455][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:34.591 UTC [32455][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:34.591 UTC [32455][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:34.595 UTC [32455][postmaster] LOG:  background worker "logical replication launcher" (PID 32478) exited with exit code 1
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  shutting down
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196268, redo lsn=0/4196268
2024-03-10 05:40:34.605 UTC [32455][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.739 UTC [32491][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-10 05:40:34.739 UTC [32491][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:40:34.804](0.217s) 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 32521
[05:40:35.018](0.214s) 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-10 05:40:32.809 UTC [32123][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-10 05:40:32.809 UTC [32123][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:32.834 UTC [32128][startup] LOG:  database system was shut down at 2024-03-10 05:38:19 UTC
2024-03-10 05:40:32.842 UTC [32123][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:32.904 UTC [32136][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:32.905 UTC [32136][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-10 05:40:32.905 UTC [32136][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:32.919 UTC [32136][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-10 05:40:32.923 UTC [32136][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-10 05:40:32.940 UTC [32144][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:32.941 UTC [32144][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-10 05:40:32.941 UTC [32144][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:32.942 UTC [32144][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-10 05:40:32.944 UTC [32144][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.001 UTC [32149][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.002 UTC [32149][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-10 05:40:33.002 UTC [32149][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.005 UTC [32149][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-10 05:40:33.007 UTC [32149][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.049 UTC [32159][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.049 UTC [32159][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-10 05:40:33.049 UTC [32159][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.053 UTC [32159][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-10 05:40:33.055 UTC [32159][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.106 UTC [32176][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.107 UTC [32176][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-10 05:40:33.107 UTC [32176][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.109 UTC [32176][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-10 05:40:33.112 UTC [32176][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.148 UTC [32186][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.149 UTC [32186][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-10 05:40:33.149 UTC [32186][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.151 UTC [32186][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-10 05:40:33.209 UTC [32186][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.061 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.298 UTC [32209][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.299 UTC [32209][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-10 05:40:33.299 UTC [32209][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.301 UTC [32209][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-10 05:40:33.408 UTC [32209][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.110 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.582 UTC [32263][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.583 UTC [32263][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-10 05:40:33.583 UTC [32263][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.586 UTC [32263][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-10 05:40:33.656 UTC [32263][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.075 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.718 UTC [32282][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.719 UTC [32282][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-10 05:40:33.719 UTC [32282][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.724 UTC [32282][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-10 05:40:33.819 UTC [32282][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.102 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.841 UTC [32321][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.842 UTC [32321][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-10 05:40:33.842 UTC [32321][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.843 UTC [32321][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-10 05:40:33.879 UTC [32321][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.995 UTC [32376][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.996 UTC [32376][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-10 05:40:33.996 UTC [32376][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:34.006 UTC [32376][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-10 05:40:34.181 UTC [32376][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.186 user=postgres database=postgres host=[local]
2024-03-10 05:40:34.275 UTC [32123][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:34.275 UTC [32123][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:34.279 UTC [32123][postmaster] LOG:  background worker "logical replication launcher" (PID 32131) exited with exit code 1
2024-03-10 05:40:34.283 UTC [32126][checkpointer] LOG:  shutting down
2024-03-10 05:40:34.283 UTC [32126][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:34.432 UTC [32126][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.149 s, sync=0.001 s, total=0.150 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0
2024-03-10 05:40:34.473 UTC [32123][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.538 UTC [32455][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-10 05:40:34.538 UTC [32455][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:34.538 UTC [32455][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:34.570 UTC [32469][startup] LOG:  database system was shut down at 2024-03-10 05:40:34 UTC
2024-03-10 05:40:34.577 UTC [32455][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:34.591 UTC [32455][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:34.591 UTC [32455][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:34.595 UTC [32455][postmaster] LOG:  background worker "logical replication launcher" (PID 32478) exited with exit code 1
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  shutting down
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196268, redo lsn=0/4196268
2024-03-10 05:40:34.605 UTC [32455][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.739 UTC [32491][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-10 05:40:34.739 UTC [32491][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.902 UTC [32521][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-10 05:40:34.902 UTC [32521][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:34.902 UTC [32521][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:34.922 UTC [32535][startup] LOG:  database system was shut down at 2024-03-10 05:40:34 UTC
2024-03-10 05:40:34.930 UTC [32521][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:35.037 UTC [32521][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:35.037 UTC [32521][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:35.042 UTC [32521][postmaster] LOG:  background worker "logical replication launcher" (PID 32542) exited with exit code 1
2024-03-10 05:40:35.042 UTC [32533][checkpointer] LOG:  shutting down
2024-03-10 05:40:35.042 UTC [32533][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:35.043 UTC [32533][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41962E0, redo lsn=0/41962E0
2024-03-10 05:40:35.049 UTC [32521][postmaster] LOG:  database system is shut down
2024-03-10 05:40:35.299 UTC [32606][postmaster] FATAL:  could not set SSL protocol version range
2024-03-10 05:40:35.299 UTC [32606][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-10 05:40:35.299 UTC [32606][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:40:35.343](0.324s) 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 32624
[05:40:35.706](0.364s) ok 5 - restart succeeds with correct SSL protocol bounds
[05:40:35.706](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 32672
[05:40:36.222](0.516s) ok 6 - server doesn't accept non-SSL connections
[05:40:36.222](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[05:40:36.339](0.117s) ok 8 - connect without server root cert sslmode=require
[05:40:36.340](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[05:40:36.400](0.061s) ok 10 - connect without server root cert sslmode=verify-ca
[05:40:36.400](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[05:40:36.467](0.067s) ok 12 - connect without server root cert sslmode=verify-full
[05:40:36.468](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[05:40:36.548](0.081s) ok 14 - connect with wrong server root cert sslmode=require
[05:40:36.548](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[05:40:36.618](0.070s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[05:40:36.618](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[05:40:36.693](0.075s) ok 18 - connect with wrong server root cert sslmode=verify-full
[05:40:36.693](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[05:40:36.781](0.088s) ok 20 - connect with server CA cert, without root CA
[05:40:36.782](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[05:40:36.889](0.107s) ok 22 - connect with correct server CA cert file sslmode=require
[05:40:36.889](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[05:40:36.975](0.086s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[05:40:36.975](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[05:40:37.033](0.058s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[05:40:37.033](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[05:40:37.092](0.059s) ok 28 - cert root file that contains two certificates, order 1
[05:40:37.092](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[05:40:37.159](0.066s) ok 30 - cert root file that contains two certificates, order 2
[05:40:37.159](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[05:40:37.224](0.065s) ok 32 - connect with sslcertmode=disable
[05:40:37.225](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[05:40:37.293](0.068s) ok 34 - connect with sslcertmode=allow
[05:40:37.293](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[05:40:37.354](0.060s) ok 36 - connect with sslcertmode=require fails without a client certificate
[05:40:37.381](0.027s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[05:40:37.410](0.030s) ok 38 - sslcrl option with invalid file name
[05:40:37.411](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[05:40:37.471](0.060s) ok 40 - CRL belonging to a different CA
[05:40:37.471](0.000s) ok 41 - CRL belonging to a different CA: matches
[05:40:37.515](0.044s) ok 42 - directory CRL belonging to a different CA
[05:40:37.515](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[05:40:37.556](0.041s) ok 44 - CRL with a non-revoked cert
[05:40:37.556](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[05:40:37.587](0.030s) ok 46 - directory CRL with a non-revoked cert
[05:40:37.587](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[05:40:37.650](0.063s) ok 48 - mismatch between host name and server certificate sslmode=require
[05:40:37.650](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[05:40:37.694](0.043s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[05:40:37.694](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[05:40:37.751](0.057s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[05:40:37.752](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 32934
[05:40:37.974](0.222s) ok 54 - IP address in the Common Name
[05:40:37.974](0.000s) ok 55 - IP address in the Common Name: no stderr
[05:40:38.000](0.026s) ok 56 - mismatch between host name and server certificate IP address
[05:40:38.000](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 32989
[05:40:38.336](0.337s) ok 58 - IP address in a dNSName
[05:40:38.336](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 33040
[05:40:38.754](0.417s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[05:40:38.754](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[05:40:38.800](0.047s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[05:40:38.801](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[05:40:38.850](0.049s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[05:40:38.850](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[05:40:38.895](0.045s) ok 66 - host name not matching with X.509 Subject Alternative Names
[05:40:38.895](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[05:40:38.928](0.033s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[05:40:38.928](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 33150
[05:40:39.139](0.210s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[05:40:39.139](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[05:40:39.187](0.048s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[05:40:39.187](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[05:40:39.251](0.064s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[05:40:39.251](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 33224
[05:40:39.598](0.348s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[05:40:39.599](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[05:40:39.657](0.059s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[05:40:39.658](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[05:40:39.723](0.066s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[05:40:39.724](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[05:40:39.785](0.062s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[05:40:39.786](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[05:40:39.836](0.050s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[05:40:39.836](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[05:40:39.883](0.047s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[05:40:39.883](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[05:40:39.930](0.047s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[05:40:39.930](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[05:40:39.987](0.057s) ok 90 - IPv6 host with CIDR mask does not match
[05:40:39.987](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 33357
[05:40:40.368](0.381s) ok 92 - certificate with both a CN and SANs 1
[05:40:40.368](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[05:40:40.416](0.047s) ok 94 - certificate with both a CN and SANs 2
[05:40:40.416](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[05:40:40.438](0.022s) ok 96 - certificate with both a CN and SANs ignores CN
[05:40:40.438](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/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 33447
[05:40:40.715](0.277s) ok 98 - certificate with both a CN and IP SANs matches CN
[05:40:40.716](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[05:40:40.766](0.051s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[05:40:40.767](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[05:40:40.798](0.031s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[05:40:40.798](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 33508
[05:40:41.046](0.248s) ok 104 - certificate with both an IP CN and IP SANs 1
[05:40:41.046](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[05:40:41.086](0.040s) ok 106 - certificate with both an IP CN and IP SANs 2
[05:40:41.086](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[05:40:41.109](0.023s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[05:40:41.109](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 33635
[05:40:41.527](0.419s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[05:40:41.528](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[05:40:41.600](0.073s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[05:40:41.601](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[05:40:41.691](0.090s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[05:40:41.691](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 33692
[05:40:42.125](0.434s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[05:40:42.125](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[05:40:42.179](0.054s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[05:40:42.179](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 33755
[05:40:42.474](0.295s) ok 120 - sslrootcert=system does not connect with private CA
[05:40:42.475](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[05:40:42.537](0.062s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[05:40:42.537](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[05:40:42.690](0.153s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[05:40:42.690](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[05:40:42.749](0.059s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[05:40:42.749](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 33832
[05:40:43.030](0.281s) ok 128 - connects without client-side CRL
[05:40:43.030](0.000s) ok 129 - connects without client-side CRL: no stderr
[05:40:43.096](0.066s) ok 130 - does not connect with client-side CRL file
[05:40:43.096](0.000s) ok 131 - does not connect with client-side CRL file: matches
[05:40:43.168](0.072s) ok 132 - does not connect with client-side CRL directory
[05:40:43.168](0.000s) ok 133 - does not connect with client-side CRL directory: matches
# Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[05:40:43.243](0.075s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[05:40:43.243](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[05:40:43.243](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[05:40:43.375](0.132s) ok 137 - connection success with correct range of TLS protocol versions
[05:40:43.376](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[05:40:43.426](0.050s) ok 139 - connection failure with incorrect range of TLS protocol versions
[05:40:43.426](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[05:40:43.472](0.046s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[05:40:43.473](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[05:40:43.536](0.063s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[05:40:43.536](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[05:40:43.537](0.000s) # running server tests
[05:40:43.624](0.087s) ok 145 - certificate authorization fails without client cert
[05:40:43.624](0.000s) ok 146 - certificate authorization fails without client cert: matches
[05:40:43.738](0.114s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[05:40:43.738](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[05:40:43.814](0.076s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[05:40:43.814](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[05:40:43.894](0.080s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[05:40:43.895](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[05:40:43.969](0.075s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[05:40:43.970](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[05:40:44.041](0.072s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[05:40:44.041](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[05:40:44.108](0.067s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[05:40:44.109](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[05:40:44.161](0.053s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[05:40:44.162](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[05:40:44.222](0.060s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[05:40:44.222](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[05:40:44.301](0.078s) ok 163 - certificate authorization succeeds with DN mapping
[05:40:44.301](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[05:40:44.301](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[05:40:44.387](0.086s) ok 166 - certificate authorization succeeds with DN regex mapping
[05:40:44.388](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[05:40:44.447](0.060s) ok 168 - certificate authorization succeeds with CN mapping
[05:40:44.447](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[05:40:44.448](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[05:40:44.448](0.000s) not ok 171 # TODO & SKIP Need Pty support
[05:40:44.448](0.000s) not ok 172 # TODO & SKIP Need Pty support
[05:40:44.448](0.000s) not ok 173 # TODO & SKIP Need Pty support
[05:40:44.448](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_Cv6v/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[05:40:44.495](0.047s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[05:40:44.495](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[05:40:44.496](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[05:40:44.541](0.046s) ok 178 - certificate authorization fails because of file permissions
[05:40:44.542](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[05:40:44.597](0.055s) ok 180 - certificate authorization fails with client cert belonging to another user
[05:40:44.597](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[05:40:44.644](0.047s) ok 182 - certificate authorization fails with revoked client cert
[05:40:44.645](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[05:40:44.645](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[05:40:44.725](0.081s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[05:40:44.726](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[05:40:44.726](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[05:40:44.792](0.066s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[05:40:44.792](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[05:40:44.792](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[05:40:44.856](0.063s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[05:40:44.856](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[05:40:44.856](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 34185
[05:40:45.285](0.429s) ok 194 - intermediate client certificate is provided by client
[05:40:45.286](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[05:40:45.341](0.055s) ok 196 - intermediate client certificate is missing
[05:40:45.341](0.000s) ok 197 - intermediate client certificate is missing: matches
[05:40:45.384](0.043s) ok 198 - logged client certificate Subjects are truncated if they're too long
[05:40:45.384](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 34251
[05:40:45.537](0.152s) ok 200 - intermediate client certificate is untrusted
[05:40:45.537](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 34293
[05:40:45.788](0.251s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[05:40:45.788](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[05:40:45.814](0.026s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[05:40:45.814](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 34360
[05:40:46.064](0.250s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[05:40:46.064](0.000s) 
[05:40:46.064](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[05:40:46.064](0.000s) #          got: '2'
#     expected: '0'
[05:40:46.064](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[05:40:46.065](0.000s) 
[05:40:46.065](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[05:40:46.065](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 64409 failed: SSL error: ocsp callback failure'
#     expected: ''
[05:40:46.134](0.069s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[05:40:46.134](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 34419
[05:40:46.387](0.253s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[05:40:46.442](0.054s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[05:40:46.442](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 34485
[05:40:46.744](0.303s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[05:40:46.835](0.090s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[05:40:46.835](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 34523
[05:40:47.101](0.266s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[05:40:47.169](0.069s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[05:40:47.170](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 34579
[05:40:47.535](0.365s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[05:40:47.535](0.000s) 
[05:40:47.535](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[05:40:47.535](0.000s) #          got: '2'
#     expected: '0'
[05:40:47.535](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[05:40:47.536](0.000s) 
[05:40:47.536](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[05:40:47.536](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 64409 failed: server closed the connection unexpectedly
# 	This probably means the server terminated abnormally
# 	before or while processing the request.
# SSL SYSCALL error: Connection reset by peer'
#     expected: ''
[05:40:47.614](0.078s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[05:40:47.614](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 34652
[05:40:47.796](0.183s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[05:40:47.878](0.082s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[05:40:47.879](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 34696
[05:40:48.144](0.265s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[05:40:48.229](0.086s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[05:40:48.230](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 34785
[05:40:48.484](0.255s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[05:40:48.566](0.081s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[05:40:48.566](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 34844
[05:40:48.823](0.257s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[05:40:48.893](0.070s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[05:40:48.893](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-10 05:40:32.809 UTC [32123][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-10 05:40:32.809 UTC [32123][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:32.834 UTC [32128][startup] LOG:  database system was shut down at 2024-03-10 05:38:19 UTC
2024-03-10 05:40:32.842 UTC [32123][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:32.904 UTC [32136][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:32.905 UTC [32136][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-10 05:40:32.905 UTC [32136][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:32.919 UTC [32136][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-10 05:40:32.923 UTC [32136][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-10 05:40:32.940 UTC [32144][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:32.941 UTC [32144][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-10 05:40:32.941 UTC [32144][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:32.942 UTC [32144][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-10 05:40:32.944 UTC [32144][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.001 UTC [32149][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.002 UTC [32149][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-10 05:40:33.002 UTC [32149][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.005 UTC [32149][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-10 05:40:33.007 UTC [32149][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.049 UTC [32159][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.049 UTC [32159][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-10 05:40:33.049 UTC [32159][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.053 UTC [32159][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-10 05:40:33.055 UTC [32159][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.106 UTC [32176][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.107 UTC [32176][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-10 05:40:33.107 UTC [32176][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.109 UTC [32176][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-10 05:40:33.112 UTC [32176][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.148 UTC [32186][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.149 UTC [32186][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-10 05:40:33.149 UTC [32186][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.151 UTC [32186][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-10 05:40:33.209 UTC [32186][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.061 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.298 UTC [32209][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.299 UTC [32209][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-10 05:40:33.299 UTC [32209][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.301 UTC [32209][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-10 05:40:33.408 UTC [32209][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.110 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.582 UTC [32263][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.583 UTC [32263][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-10 05:40:33.583 UTC [32263][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.586 UTC [32263][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-10 05:40:33.656 UTC [32263][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.075 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.718 UTC [32282][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.719 UTC [32282][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-10 05:40:33.719 UTC [32282][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.724 UTC [32282][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-10 05:40:33.819 UTC [32282][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.102 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.841 UTC [32321][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.842 UTC [32321][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-10 05:40:33.842 UTC [32321][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:33.843 UTC [32321][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-10 05:40:33.879 UTC [32321][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local]
2024-03-10 05:40:33.995 UTC [32376][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-10 05:40:33.996 UTC [32376][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-10 05:40:33.996 UTC [32376][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-10 05:40:34.006 UTC [32376][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-10 05:40:34.181 UTC [32376][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.186 user=postgres database=postgres host=[local]
2024-03-10 05:40:34.275 UTC [32123][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:34.275 UTC [32123][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:34.279 UTC [32123][postmaster] LOG:  background worker "logical replication launcher" (PID 32131) exited with exit code 1
2024-03-10 05:40:34.283 UTC [32126][checkpointer] LOG:  shutting down
2024-03-10 05:40:34.283 UTC [32126][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:34.432 UTC [32126][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.149 s, sync=0.001 s, total=0.150 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0
2024-03-10 05:40:34.473 UTC [32123][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.538 UTC [32455][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-10 05:40:34.538 UTC [32455][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:34.538 UTC [32455][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:34.570 UTC [32469][startup] LOG:  database system was shut down at 2024-03-10 05:40:34 UTC
2024-03-10 05:40:34.577 UTC [32455][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:34.591 UTC [32455][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:34.591 UTC [32455][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:34.595 UTC [32455][postmaster] LOG:  background worker "logical replication launcher" (PID 32478) exited with exit code 1
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  shutting down
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:34.599 UTC [32467][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196268, redo lsn=0/4196268
2024-03-10 05:40:34.605 UTC [32455][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.739 UTC [32491][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-10 05:40:34.739 UTC [32491][postmaster] LOG:  database system is shut down
2024-03-10 05:40:34.902 UTC [32521][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-10 05:40:34.902 UTC [32521][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:34.902 UTC [32521][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:34.922 UTC [32535][startup] LOG:  database system was shut down at 2024-03-10 05:40:34 UTC
2024-03-10 05:40:34.930 UTC [32521][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:35.037 UTC [32521][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:35.037 UTC [32521][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:35.042 UTC [32521][postmaster] LOG:  background worker "logical replication launcher" (PID 32542) exited with exit code 1
2024-03-10 05:40:35.042 UTC [32533][checkpointer] LOG:  shutting down
2024-03-10 05:40:35.042 UTC [32533][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:35.043 UTC [32533][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41962E0, redo lsn=0/41962E0
2024-03-10 05:40:35.049 UTC [32521][postmaster] LOG:  database system is shut down
2024-03-10 05:40:35.299 UTC [32606][postmaster] FATAL:  could not set SSL protocol version range
2024-03-10 05:40:35.299 UTC [32606][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-10 05:40:35.299 UTC [32606][postmaster] LOG:  database system is shut down
2024-03-10 05:40:35.567 UTC [32624][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-10 05:40:35.567 UTC [32624][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:35.567 UTC [32624][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:35.627 UTC [32642][startup] LOG:  database system was shut down at 2024-03-10 05:40:35 UTC
2024-03-10 05:40:35.635 UTC [32624][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:35.725 UTC [32624][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:35.725 UTC [32624][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:35.734 UTC [32624][postmaster] LOG:  background worker "logical replication launcher" (PID 32649) exited with exit code 1
2024-03-10 05:40:35.734 UTC [32640][checkpointer] LOG:  shutting down
2024-03-10 05:40:35.734 UTC [32640][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:35.754 UTC [32640][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.021 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196358, redo lsn=0/4196358
2024-03-10 05:40:35.760 UTC [32624][postmaster] LOG:  database system is shut down
2024-03-10 05:40:35.969 UTC [32672][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-10 05:40:35.969 UTC [32672][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:35.969 UTC [32672][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:36.017 UTC [32683][startup] LOG:  database system was shut down at 2024-03-10 05:40:35 UTC
2024-03-10 05:40:36.043 UTC [32672][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:36.213 UTC [32710][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46090
2024-03-10 05:40:36.214 UTC [32710][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-10 05:40:36.214 UTC [32710][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-10 05:40:36.267 UTC [32714][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46100
2024-03-10 05:40:36.302 UTC [32714][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-10 05:40:36.302 UTC [32714][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-10 05:40:36.335 UTC [32714][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-10 05:40:36.343 UTC [32714][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=ssltestuser database=trustdb host=localhost port=46100
2024-03-10 05:40:36.373 UTC [32728][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46110
2024-03-10 05:40:36.394 UTC [32728][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-10 05:40:36.458 UTC [32738][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46120
2024-03-10 05:40:36.462 UTC [32738][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-10 05:40:36.533 UTC [32756][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46136
2024-03-10 05:40:36.550 UTC [32756][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:36.602 UTC [32762][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46144
2024-03-10 05:40:36.619 UTC [32762][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:36.665 UTC [32771][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46150
2024-03-10 05:40:36.677 UTC [32771][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:36.735 UTC [32779][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46158
2024-03-10 05:40:36.747 UTC [32779][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:36.855 UTC [32793][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46160
2024-03-10 05:40:36.865 UTC [32793][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-10 05:40:36.865 UTC [32793][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-10 05:40:36.872 UTC [32793][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-10 05:40:36.876 UTC [32793][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=46160
2024-03-10 05:40:36.953 UTC [32803][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46162
2024-03-10 05:40:36.963 UTC [32803][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-10 05:40:36.964 UTC [32803][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-10 05:40:36.970 UTC [32803][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-10 05:40:36.985 UTC [32803][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=localhost port=46162
2024-03-10 05:40:37.008 UTC [32809][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46172
2024-03-10 05:40:37.020 UTC [32809][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-10 05:40:37.020 UTC [32809][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-10 05:40:37.029 UTC [32809][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-10 05:40:37.031 UTC [32809][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=46172
2024-03-10 05:40:37.076 UTC [32812][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46180
2024-03-10 05:40:37.084 UTC [32812][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-10 05:40:37.084 UTC [32812][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-10 05:40:37.088 UTC [32812][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-10 05:40:37.091 UTC [32812][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=46180
2024-03-10 05:40:37.129 UTC [32819][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46190
2024-03-10 05:40:37.147 UTC [32819][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-10 05:40:37.147 UTC [32819][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-10 05:40:37.154 UTC [32819][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-10 05:40:37.161 UTC [32819][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=46190
2024-03-10 05:40:37.189 UTC [32833][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46200
2024-03-10 05:40:37.210 UTC [32833][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-10 05:40:37.210 UTC [32833][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-10 05:40:37.212 UTC [32833][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-10 05:40:37.215 UTC [32833][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=46200
2024-03-10 05:40:37.267 UTC [32840][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46202
2024-03-10 05:40:37.285 UTC [32840][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-10 05:40:37.285 UTC [32840][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-10 05:40:37.288 UTC [32840][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-10 05:40:37.295 UTC [32840][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=46202
2024-03-10 05:40:37.320 UTC [32847][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46214
2024-03-10 05:40:37.338 UTC [32847][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-10 05:40:37.338 UTC [32847][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-10 05:40:37.341 UTC [32847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=46214
2024-03-10 05:40:37.399 UTC [32864][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46216
2024-03-10 05:40:37.405 UTC [32864][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-10 05:40:37.405 UTC [32864][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-10 05:40:37.407 UTC [32864][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-10 05:40:37.410 UTC [32864][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=46216
2024-03-10 05:40:37.454 UTC [32874][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46226
2024-03-10 05:40:37.463 UTC [32874][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:37.494 UTC [32883][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46228
2024-03-10 05:40:37.505 UTC [32883][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:37.539 UTC [32886][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46230
2024-03-10 05:40:37.550 UTC [32886][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-10 05:40:37.550 UTC [32886][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-10 05:40:37.552 UTC [32886][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-10 05:40:37.558 UTC [32886][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=46230
2024-03-10 05:40:37.575 UTC [32895][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46234
2024-03-10 05:40:37.582 UTC [32895][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-10 05:40:37.582 UTC [32895][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-10 05:40:37.583 UTC [32895][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-10 05:40:37.588 UTC [32895][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=46234
2024-03-10 05:40:37.610 UTC [32900][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46240
2024-03-10 05:40:37.629 UTC [32900][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-10 05:40:37.629 UTC [32900][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-10 05:40:37.630 UTC [32900][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-10 05:40:37.631 UTC [32900][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=46240
2024-03-10 05:40:37.679 UTC [32912][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46256
2024-03-10 05:40:37.686 UTC [32912][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-10 05:40:37.688 UTC [32912][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-10 05:40:37.690 UTC [32912][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-10 05:40:37.693 UTC [32912][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=46256
2024-03-10 05:40:37.738 UTC [32926][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46262
2024-03-10 05:40:37.761 UTC [32672][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:37.761 UTC [32672][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:37.773 UTC [32672][postmaster] LOG:  background worker "logical replication launcher" (PID 32690) exited with exit code 1
2024-03-10 05:40:37.773 UTC [32680][checkpointer] LOG:  shutting down
2024-03-10 05:40:37.774 UTC [32680][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:37.774 UTC [32680][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41963D0, redo lsn=0/41963D0
2024-03-10 05:40:37.780 UTC [32672][postmaster] LOG:  database system is shut down
2024-03-10 05:40:37.845 UTC [32934][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-10 05:40:37.845 UTC [32934][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:37.845 UTC [32934][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:37.871 UTC [32942][startup] LOG:  database system was shut down at 2024-03-10 05:40:37 UTC
2024-03-10 05:40:37.892 UTC [32934][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:37.927 UTC [32957][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46264
2024-03-10 05:40:37.945 UTC [32957][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-10 05:40:37.945 UTC [32957][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-10 05:40:37.970 UTC [32957][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-10 05:40:37.975 UTC [32957][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=46264
2024-03-10 05:40:37.991 UTC [32968][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46274
2024-03-10 05:40:38.007 UTC [32934][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:38.007 UTC [32934][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:38.016 UTC [32934][postmaster] LOG:  background worker "logical replication launcher" (PID 32949) exited with exit code 1
2024-03-10 05:40:38.016 UTC [32940][checkpointer] LOG:  shutting down
2024-03-10 05:40:38.016 UTC [32940][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:38.017 UTC [32940][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196448, redo lsn=0/4196448
2024-03-10 05:40:38.023 UTC [32934][postmaster] LOG:  database system is shut down
2024-03-10 05:40:38.159 UTC [32989][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-10 05:40:38.159 UTC [32989][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:38.159 UTC [32989][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:38.181 UTC [32996][startup] LOG:  database system was shut down at 2024-03-10 05:40:38 UTC
2024-03-10 05:40:38.188 UTC [32989][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:38.259 UTC [33012][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46280
2024-03-10 05:40:38.281 UTC [33012][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-10 05:40:38.281 UTC [33012][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-10 05:40:38.332 UTC [33012][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-10 05:40:38.340 UTC [33012][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.083 user=ssltestuser database=trustdb host=localhost port=46280
2024-03-10 05:40:38.344 UTC [32989][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:38.344 UTC [32989][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:38.349 UTC [32989][postmaster] LOG:  background worker "logical replication launcher" (PID 33002) exited with exit code 1
2024-03-10 05:40:38.353 UTC [32994][checkpointer] LOG:  shutting down
2024-03-10 05:40:38.353 UTC [32994][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:38.354 UTC [32994][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41964C0, redo lsn=0/41964C0
2024-03-10 05:40:38.360 UTC [32989][postmaster] LOG:  database system is shut down
2024-03-10 05:40:38.535 UTC [33040][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-10 05:40:38.535 UTC [33040][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:38.535 UTC [33040][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:38.556 UTC [33060][startup] LOG:  database system was shut down at 2024-03-10 05:40:38 UTC
2024-03-10 05:40:38.566 UTC [33040][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:38.686 UTC [33087][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46290
2024-03-10 05:40:38.696 UTC [33087][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-10 05:40:38.696 UTC [33087][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-10 05:40:38.749 UTC [33087][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-10 05:40:38.756 UTC [33087][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.071 user=ssltestuser database=trustdb host=localhost port=46290
2024-03-10 05:40:38.783 UTC [33107][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46294
2024-03-10 05:40:38.792 UTC [33107][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-10 05:40:38.792 UTC [33107][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-10 05:40:38.796 UTC [33107][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-10 05:40:38.800 UTC [33107][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=46294
2024-03-10 05:40:38.830 UTC [33118][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46302
2024-03-10 05:40:38.844 UTC [33118][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-10 05:40:38.844 UTC [33118][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-10 05:40:38.846 UTC [33118][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-10 05:40:38.848 UTC [33118][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=46302
2024-03-10 05:40:38.880 UTC [33128][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46308
2024-03-10 05:40:38.917 UTC [33136][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46312
2024-03-10 05:40:38.940 UTC [33040][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:38.940 UTC [33040][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:38.945 UTC [33040][postmaster] LOG:  background worker "logical replication launcher" (PID 33068) exited with exit code 1
2024-03-10 05:40:38.949 UTC [33057][checkpointer] LOG:  shutting down
2024-03-10 05:40:38.949 UTC [33057][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:38.950 UTC [33057][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196538, redo lsn=0/4196538
2024-03-10 05:40:38.956 UTC [33040][postmaster] LOG:  database system is shut down
2024-03-10 05:40:39.037 UTC [33150][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-10 05:40:39.037 UTC [33150][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:39.037 UTC [33150][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:39.054 UTC [33162][startup] LOG:  database system was shut down at 2024-03-10 05:40:38 UTC
2024-03-10 05:40:39.062 UTC [33150][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:39.108 UTC [33169][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46326
2024-03-10 05:40:39.114 UTC [33169][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-10 05:40:39.114 UTC [33169][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-10 05:40:39.135 UTC [33169][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-10 05:40:39.144 UTC [33169][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=46326
2024-03-10 05:40:39.174 UTC [33190][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46330
2024-03-10 05:40:39.218 UTC [33199][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46342
2024-03-10 05:40:39.301 UTC [33150][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:39.301 UTC [33150][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:39.318 UTC [33150][postmaster] LOG:  background worker "logical replication launcher" (PID 33165) exited with exit code 1
2024-03-10 05:40:39.318 UTC [33159][checkpointer] LOG:  shutting down
2024-03-10 05:40:39.318 UTC [33159][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:39.319 UTC [33159][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41965B0, redo lsn=0/41965B0
2024-03-10 05:40:39.325 UTC [33150][postmaster] LOG:  database system is shut down
2024-03-10 05:40:39.467 UTC [33224][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-10 05:40:39.467 UTC [33224][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:39.467 UTC [33224][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:39.492 UTC [33238][startup] LOG:  database system was shut down at 2024-03-10 05:40:39 UTC
2024-03-10 05:40:39.500 UTC [33224][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:39.537 UTC [33250][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46358
2024-03-10 05:40:39.554 UTC [33250][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-10 05:40:39.554 UTC [33250][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-10 05:40:39.594 UTC [33250][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-10 05:40:39.600 UTC [33250][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=ssltestuser database=trustdb host=localhost port=46358
2024-03-10 05:40:39.621 UTC [33261][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46364
2024-03-10 05:40:39.643 UTC [33261][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-10 05:40:39.643 UTC [33261][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-10 05:40:39.645 UTC [33261][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-10 05:40:39.646 UTC [33261][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=46364
2024-03-10 05:40:39.697 UTC [33268][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46376
2024-03-10 05:40:39.766 UTC [33285][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46388
2024-03-10 05:40:39.779 UTC [33285][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-10 05:40:39.779 UTC [33285][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-10 05:40:39.781 UTC [33285][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-10 05:40:39.785 UTC [33285][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=46388
2024-03-10 05:40:39.812 UTC [33292][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46400
2024-03-10 05:40:39.819 UTC [33292][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-10 05:40:39.819 UTC [33292][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-10 05:40:39.828 UTC [33292][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-10 05:40:39.832 UTC [33292][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=46400
2024-03-10 05:40:39.866 UTC [33304][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46414
2024-03-10 05:40:39.877 UTC [33304][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-10 05:40:39.877 UTC [33304][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-10 05:40:39.878 UTC [33304][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-10 05:40:39.885 UTC [33304][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=46414
2024-03-10 05:40:39.920 UTC [33319][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46416
2024-03-10 05:40:39.973 UTC [33328][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46420
2024-03-10 05:40:39.998 UTC [33224][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:39.998 UTC [33224][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:40.012 UTC [33224][postmaster] LOG:  background worker "logical replication launcher" (PID 33245) exited with exit code 1
2024-03-10 05:40:40.016 UTC [33235][checkpointer] LOG:  shutting down
2024-03-10 05:40:40.016 UTC [33235][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:40.017 UTC [33235][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196628, redo lsn=0/4196628
2024-03-10 05:40:40.023 UTC [33224][postmaster] LOG:  database system is shut down
2024-03-10 05:40:40.162 UTC [33357][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-10 05:40:40.162 UTC [33357][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:40.162 UTC [33357][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:40.185 UTC [33366][startup] LOG:  database system was shut down at 2024-03-10 05:40:40 UTC
2024-03-10 05:40:40.202 UTC [33357][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:40.329 UTC [33405][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46436
2024-03-10 05:40:40.336 UTC [33405][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-10 05:40:40.336 UTC [33405][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-10 05:40:40.364 UTC [33405][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-10 05:40:40.370 UTC [33405][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=46436
2024-03-10 05:40:40.403 UTC [33414][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46444
2024-03-10 05:40:40.410 UTC [33414][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-10 05:40:40.410 UTC [33414][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-10 05:40:40.411 UTC [33414][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-10 05:40:40.418 UTC [33414][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=46444
2024-03-10 05:40:40.430 UTC [33426][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46458
2024-03-10 05:40:40.459 UTC [33357][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:40.459 UTC [33357][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:40.474 UTC [33357][postmaster] LOG:  background worker "logical replication launcher" (PID 33377) exited with exit code 1
2024-03-10 05:40:40.474 UTC [33364][checkpointer] LOG:  shutting down
2024-03-10 05:40:40.474 UTC [33364][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:40.475 UTC [33364][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41966A0, redo lsn=0/41966A0
2024-03-10 05:40:40.480 UTC [33357][postmaster] LOG:  database system is shut down
2024-03-10 05:40:40.605 UTC [33447][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-10 05:40:40.605 UTC [33447][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:40.605 UTC [33447][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:40.626 UTC [33457][startup] LOG:  database system was shut down at 2024-03-10 05:40:40 UTC
2024-03-10 05:40:40.635 UTC [33447][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:40.683 UTC [33463][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46468
2024-03-10 05:40:40.698 UTC [33463][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-10 05:40:40.698 UTC [33463][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-10 05:40:40.711 UTC [33463][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-10 05:40:40.712 UTC [33463][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=46468
2024-03-10 05:40:40.754 UTC [33477][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46476
2024-03-10 05:40:40.760 UTC [33477][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-10 05:40:40.760 UTC [33477][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-10 05:40:40.762 UTC [33477][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-10 05:40:40.766 UTC [33477][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=46476
2024-03-10 05:40:40.786 UTC [33482][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46488
2024-03-10 05:40:40.792 UTC [33482][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-10 05:40:40.792 UTC [33482][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-10 05:40:40.794 UTC [33482][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-10 05:40:40.799 UTC [33482][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=46488
2024-03-10 05:40:40.808 UTC [33447][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:40.808 UTC [33447][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:40.819 UTC [33447][postmaster] LOG:  background worker "logical replication launcher" (PID 33460) exited with exit code 1
2024-03-10 05:40:40.819 UTC [33453][checkpointer] LOG:  shutting down
2024-03-10 05:40:40.820 UTC [33453][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:40.820 UTC [33453][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196718, redo lsn=0/4196718
2024-03-10 05:40:40.827 UTC [33447][postmaster] LOG:  database system is shut down
2024-03-10 05:40:40.941 UTC [33508][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-10 05:40:40.941 UTC [33508][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:40.942 UTC [33508][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:40.957 UTC [33515][startup] LOG:  database system was shut down at 2024-03-10 05:40:40 UTC
2024-03-10 05:40:40.965 UTC [33508][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:41.020 UTC [33533][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46496
2024-03-10 05:40:41.027 UTC [33533][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-10 05:40:41.027 UTC [33533][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-10 05:40:41.041 UTC [33533][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-10 05:40:41.047 UTC [33533][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=46496
2024-03-10 05:40:41.067 UTC [33550][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46498
2024-03-10 05:40:41.074 UTC [33550][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-10 05:40:41.074 UTC [33550][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-10 05:40:41.076 UTC [33550][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-10 05:40:41.077 UTC [33550][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=46498
2024-03-10 05:40:41.100 UTC [33566][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46508
2024-03-10 05:40:41.113 UTC [33508][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:41.113 UTC [33508][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:41.117 UTC [33508][postmaster] LOG:  background worker "logical replication launcher" (PID 33521) exited with exit code 1
2024-03-10 05:40:41.122 UTC [33513][checkpointer] LOG:  shutting down
2024-03-10 05:40:41.122 UTC [33513][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:41.123 UTC [33513][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196790, redo lsn=0/4196790
2024-03-10 05:40:41.129 UTC [33508][postmaster] LOG:  database system is shut down
2024-03-10 05:40:41.359 UTC [33635][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-10 05:40:41.359 UTC [33635][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:41.359 UTC [33635][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:41.390 UTC [33648][startup] LOG:  database system was shut down at 2024-03-10 05:40:41 UTC
2024-03-10 05:40:41.399 UTC [33635][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:41.462 UTC [33660][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46520
2024-03-10 05:40:41.478 UTC [33660][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-10 05:40:41.478 UTC [33660][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-10 05:40:41.523 UTC [33660][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-10 05:40:41.530 UTC [33660][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=ssltestuser database=trustdb host=localhost port=46520
2024-03-10 05:40:41.578 UTC [33669][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46530
2024-03-10 05:40:41.587 UTC [33669][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-10 05:40:41.587 UTC [33669][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-10 05:40:41.596 UTC [33669][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-10 05:40:41.602 UTC [33669][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=46530
2024-03-10 05:40:41.667 UTC [33679][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46546
2024-03-10 05:40:41.680 UTC [33679][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-10 05:40:41.681 UTC [33679][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-10 05:40:41.686 UTC [33679][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-10 05:40:41.692 UTC [33679][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=46546
2024-03-10 05:40:41.705 UTC [33635][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:41.705 UTC [33635][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:41.714 UTC [33635][postmaster] LOG:  background worker "logical replication launcher" (PID 33653) exited with exit code 1
2024-03-10 05:40:41.714 UTC [33646][checkpointer] LOG:  shutting down
2024-03-10 05:40:41.714 UTC [33646][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:41.715 UTC [33646][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196808, redo lsn=0/4196808
2024-03-10 05:40:41.721 UTC [33635][postmaster] LOG:  database system is shut down
2024-03-10 05:40:41.904 UTC [33692][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-10 05:40:41.904 UTC [33692][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:41.905 UTC [33692][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:41.946 UTC [33711][startup] LOG:  database system was shut down at 2024-03-10 05:40:41 UTC
2024-03-10 05:40:41.953 UTC [33692][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:42.080 UTC [33738][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40716
2024-03-10 05:40:42.095 UTC [33738][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-10 05:40:42.095 UTC [33738][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-10 05:40:42.120 UTC [33738][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-10 05:40:42.126 UTC [33738][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=40716
2024-03-10 05:40:42.164 UTC [33747][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40720
2024-03-10 05:40:42.196 UTC [33692][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:42.196 UTC [33692][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:42.208 UTC [33692][postmaster] LOG:  background worker "logical replication launcher" (PID 33715) exited with exit code 1
2024-03-10 05:40:42.208 UTC [33706][checkpointer] LOG:  shutting down
2024-03-10 05:40:42.208 UTC [33706][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:42.209 UTC [33706][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196880, redo lsn=0/4196880
2024-03-10 05:40:42.215 UTC [33692][postmaster] LOG:  database system is shut down
2024-03-10 05:40:42.315 UTC [33755][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-10 05:40:42.315 UTC [33755][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:42.316 UTC [33755][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:42.376 UTC [33771][startup] LOG:  database system was shut down at 2024-03-10 05:40:42 UTC
2024-03-10 05:40:42.389 UTC [33755][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:42.465 UTC [33793][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40722
2024-03-10 05:40:42.480 UTC [33793][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-10 05:40:42.606 UTC [33816][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40738
2024-03-10 05:40:42.627 UTC [33816][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-10 05:40:42.627 UTC [33816][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-10 05:40:42.686 UTC [33816][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-10 05:40:42.692 UTC [33816][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.087 user=ssltestuser database=trustdb host=localhost port=40738
2024-03-10 05:40:42.722 UTC [33825][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40744
2024-03-10 05:40:42.762 UTC [33755][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:42.762 UTC [33755][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:42.774 UTC [33755][postmaster] LOG:  background worker "logical replication launcher" (PID 33780) exited with exit code 1
2024-03-10 05:40:42.774 UTC [33769][checkpointer] LOG:  shutting down
2024-03-10 05:40:42.774 UTC [33769][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:42.775 UTC [33769][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41968F8, redo lsn=0/41968F8
2024-03-10 05:40:42.781 UTC [33755][postmaster] LOG:  database system is shut down
2024-03-10 05:40:42.845 UTC [33832][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-10 05:40:42.845 UTC [33832][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:42.845 UTC [33832][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:42.893 UTC [33844][startup] LOG:  database system was shut down at 2024-03-10 05:40:42 UTC
2024-03-10 05:40:42.901 UTC [33832][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:42.998 UTC [33873][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40752
2024-03-10 05:40:43.005 UTC [33873][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-10 05:40:43.005 UTC [33873][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-10 05:40:43.026 UTC [33873][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-10 05:40:43.032 UTC [33873][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=40752
2024-03-10 05:40:43.070 UTC [33882][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40768
2024-03-10 05:40:43.088 UTC [33882][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-10 05:40:43.136 UTC [33894][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40780
2024-03-10 05:40:43.147 UTC [33894][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-10 05:40:43.212 UTC [33908][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40788
2024-03-10 05:40:43.232 UTC [33908][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-10 05:40:43.232 UTC [33908][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-10 05:40:43.234 UTC [33908][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-10 05:40:43.242 UTC [33908][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=40788
2024-03-10 05:40:43.348 UTC [33934][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40800
2024-03-10 05:40:43.366 UTC [33934][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-10 05:40:43.366 UTC [33934][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-10 05:40:43.371 UTC [33934][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-10 05:40:43.375 UTC [33934][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=40800
2024-03-10 05:40:43.595 UTC [33979][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40816
2024-03-10 05:40:43.616 UTC [33979][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-10 05:40:43.681 UTC [33986][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40826
2024-03-10 05:40:43.697 UTC [33986][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-10 05:40:43.697 UTC [33986][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-10 05:40:43.734 UTC [33986][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_Cv6v/client.key$$
2024-03-10 05:40:43.741 UTC [33986][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.071 user=ssltestuser database=certdb host=localhost port=40826
2024-03-10 05:40:43.784 UTC [34003][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40840
2024-03-10 05:40:43.798 UTC [34003][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-10 05:40:43.798 UTC [34003][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-10 05:40:43.807 UTC [34003][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_Cv6v/client-der.key$$
2024-03-10 05:40:43.809 UTC [34003][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=certdb host=localhost port=40840
2024-03-10 05:40:43.846 UTC [34018][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40850
2024-03-10 05:40:43.876 UTC [34018][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-10 05:40:43.876 UTC [34018][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-10 05:40:43.878 UTC [34018][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_Cv6v/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-10 05:40:43.879 UTC [34018][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=ssltestuser database=certdb host=localhost port=40850
2024-03-10 05:40:43.948 UTC [34037][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40866
2024-03-10 05:40:43.964 UTC [34037][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-10 05:40:43.964 UTC [34037][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-10 05:40:43.965 UTC [34037][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_Cv6v/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-10 05:40:43.966 UTC [34037][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=40866
2024-03-10 05:40:44.009 UTC [34047][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40876
2024-03-10 05:40:44.026 UTC [34047][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-10 05:40:44.026 UTC [34047][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-10 05:40:44.028 UTC [34047][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_Cv6v/client.key$$
2024-03-10 05:40:44.031 UTC [34047][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=certdb host=localhost port=40876
2024-03-10 05:40:44.069 UTC [34057][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40878
2024-03-10 05:40:44.095 UTC [34057][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-10 05:40:44.095 UTC [34057][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-10 05:40:44.097 UTC [34057][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_Cv6v/client.key$$
2024-03-10 05:40:44.100 UTC [34057][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=certdb host=localhost port=40878
2024-03-10 05:40:44.142 UTC [34063][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40890
2024-03-10 05:40:44.157 UTC [34063][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-10 05:40:44.197 UTC [34069][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40904
2024-03-10 05:40:44.209 UTC [34069][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-10 05:40:44.261 UTC [34076][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40914
2024-03-10 05:40:44.278 UTC [34076][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-10 05:40:44.278 UTC [34076][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-10 05:40:44.295 UTC [34076][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_Cv6v/client-dn.key$$
2024-03-10 05:40:44.297 UTC [34076][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=certdb_dn host=localhost port=40914
2024-03-10 05:40:44.337 UTC [34090][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40928
2024-03-10 05:40:44.355 UTC [34090][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-10 05:40:44.355 UTC [34090][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-10 05:40:44.383 UTC [34090][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_Cv6v/client-dn.key$$
2024-03-10 05:40:44.389 UTC [34090][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=ssltestuser database=certdb_dn_re host=localhost port=40928
2024-03-10 05:40:44.413 UTC [34100][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40940
2024-03-10 05:40:44.423 UTC [34100][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-10 05:40:44.423 UTC [34100][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-10 05:40:44.443 UTC [34100][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_Cv6v/client-dn.key$$
2024-03-10 05:40:44.449 UTC [34100][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=certdb_cn host=localhost port=40940
2024-03-10 05:40:44.469 UTC [34106][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40944
2024-03-10 05:40:44.479 UTC [34106][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-10 05:40:44.479 UTC [34106][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-10 05:40:44.489 UTC [34106][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-10 05:40:44.497 UTC [34106][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=certdb host=localhost port=40944
2024-03-10 05:40:44.535 UTC [34114][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40952
2024-03-10 05:40:44.544 UTC [34114][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-10 05:40:44.585 UTC [34122][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40964
2024-03-10 05:40:44.593 UTC [34122][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-10 05:40:44.593 UTC [34122][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-10 05:40:44.593 UTC [34122][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-10 05:40:44.593 UTC [34122][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-10 05:40:44.629 UTC [34134][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40976
2024-03-10 05:40:44.636 UTC [34134][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-10 05:40:44.636 UTC [34134][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-10 05:40:44.678 UTC [34144][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40984
2024-03-10 05:40:44.693 UTC [34144][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-10 05:40:44.693 UTC [34144][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-10 05:40:44.721 UTC [34144][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_Cv6v/client.key$$
2024-03-10 05:40:44.732 UTC [34144][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.055 user=ssltestuser database=verifydb host=localhost port=40984
2024-03-10 05:40:44.764 UTC [34160][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=40988
2024-03-10 05:40:44.779 UTC [34160][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-10 05:40:44.779 UTC [34160][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-10 05:40:44.779 UTC [34160][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-10 05:40:44.779 UTC [34160][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-10 05:40:44.828 UTC [34167][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41000
2024-03-10 05:40:44.850 UTC [34167][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-10 05:40:44.850 UTC [34167][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-10 05:40:44.851 UTC [34167][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_Cv6v/client.key$$
2024-03-10 05:40:44.852 UTC [34167][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=yetanotheruser database=verifydb host=localhost port=41000
2024-03-10 05:40:44.867 UTC [33832][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:44.867 UTC [33832][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:44.876 UTC [33832][postmaster] LOG:  background worker "logical replication launcher" (PID 33849) exited with exit code 1
2024-03-10 05:40:44.876 UTC [33840][checkpointer] LOG:  shutting down
2024-03-10 05:40:44.876 UTC [33840][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:44.877 UTC [33840][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196970, redo lsn=0/4196970
2024-03-10 05:40:44.882 UTC [33832][postmaster] LOG:  database system is shut down
2024-03-10 05:40:45.071 UTC [34185][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-10 05:40:45.071 UTC [34185][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:45.071 UTC [34185][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:45.110 UTC [34211][startup] LOG:  database system was shut down at 2024-03-10 05:40:44 UTC
2024-03-10 05:40:45.118 UTC [34185][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:45.221 UTC [34228][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41008
2024-03-10 05:40:45.241 UTC [34228][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-10 05:40:45.241 UTC [34228][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-10 05:40:45.281 UTC [34228][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_Cv6v/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-10 05:40:45.287 UTC [34228][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.067 user=ssltestuser database=certdb host=localhost port=41008
2024-03-10 05:40:45.325 UTC [34239][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41010
2024-03-10 05:40:45.340 UTC [34239][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-10 05:40:45.340 UTC [34239][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-10 05:40:45.368 UTC [34243][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41016
2024-03-10 05:40:45.376 UTC [34243][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-10 05:40:45.376 UTC [34243][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-10 05:40:45.388 UTC [34185][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:45.388 UTC [34185][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:45.401 UTC [34185][postmaster] LOG:  background worker "logical replication launcher" (PID 34218) exited with exit code 1
2024-03-10 05:40:45.401 UTC [34208][checkpointer] LOG:  shutting down
2024-03-10 05:40:45.401 UTC [34208][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:45.402 UTC [34208][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41969E8, redo lsn=0/41969E8
2024-03-10 05:40:45.408 UTC [34185][postmaster] LOG:  database system is shut down
2024-03-10 05:40:45.454 UTC [34251][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-10 05:40:45.454 UTC [34251][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:45.454 UTC [34251][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:45.464 UTC [34261][startup] LOG:  database system was shut down at 2024-03-10 05:40:45 UTC
2024-03-10 05:40:45.485 UTC [34251][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:45.523 UTC [34266][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41020
2024-03-10 05:40:45.530 UTC [34266][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-10 05:40:45.530 UTC [34266][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-10 05:40:45.543 UTC [34251][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:45.543 UTC [34251][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:45.555 UTC [34251][postmaster] LOG:  background worker "logical replication launcher" (PID 34264) exited with exit code 1
2024-03-10 05:40:45.555 UTC [34259][checkpointer] LOG:  shutting down
2024-03-10 05:40:45.555 UTC [34259][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:45.556 UTC [34259][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196A60, redo lsn=0/4196A60
2024-03-10 05:40:45.562 UTC [34251][postmaster] LOG:  database system is shut down
2024-03-10 05:40:45.671 UTC [34293][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-10 05:40:45.671 UTC [34293][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:45.671 UTC [34293][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:45.679 UTC [34302][startup] LOG:  database system was shut down at 2024-03-10 05:40:45 UTC
2024-03-10 05:40:45.688 UTC [34293][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:45.777 UTC [34323][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41032
2024-03-10 05:40:45.787 UTC [34323][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-10 05:40:45.787 UTC [34323][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-10 05:40:45.803 UTC [34326][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41036
2024-03-10 05:40:45.815 UTC [34326][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-10 05:40:45.815 UTC [34326][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-10 05:40:45.823 UTC [34293][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:45.823 UTC [34293][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:45.836 UTC [34293][postmaster] LOG:  background worker "logical replication launcher" (PID 34308) exited with exit code 1
2024-03-10 05:40:45.836 UTC [34300][checkpointer] LOG:  shutting down
2024-03-10 05:40:45.836 UTC [34300][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:45.837 UTC [34300][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196AD8, redo lsn=0/4196AD8
2024-03-10 05:40:45.843 UTC [34293][postmaster] LOG:  database system is shut down
2024-03-10 05:40:45.954 UTC [34360][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-10 05:40:45.954 UTC [34360][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:45.955 UTC [34360][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:45.980 UTC [34370][startup] LOG:  database system was shut down at 2024-03-10 05:40:45 UTC
2024-03-10 05:40:45.988 UTC [34360][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:46.055 UTC [34392][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41052
2024-03-10 05:40:46.057 UTC [34392][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:46.058 UTC [34392][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:46.063 UTC [34392][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-10 05:40:46.099 UTC [34397][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41056
2024-03-10 05:40:46.106 UTC [34397][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-10 05:40:46.106 UTC [34397][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-10 05:40:46.129 UTC [34397][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-10 05:40:46.135 UTC [34397][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=41056
2024-03-10 05:40:46.143 UTC [34360][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:46.144 UTC [34360][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:46.149 UTC [34360][postmaster] LOG:  background worker "logical replication launcher" (PID 34380) exited with exit code 1
2024-03-10 05:40:46.156 UTC [34368][checkpointer] LOG:  shutting down
2024-03-10 05:40:46.156 UTC [34368][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:46.157 UTC [34368][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196B50, redo lsn=0/4196B50
2024-03-10 05:40:46.163 UTC [34360][postmaster] LOG:  database system is shut down
2024-03-10 05:40:46.294 UTC [34419][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-10 05:40:46.294 UTC [34419][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:46.294 UTC [34419][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:46.314 UTC [34429][startup] LOG:  database system was shut down at 2024-03-10 05:40:46 UTC
2024-03-10 05:40:46.324 UTC [34419][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:46.375 UTC [34464][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41070
2024-03-10 05:40:46.379 UTC [34464][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:46.380 UTC [34464][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:46.405 UTC [34469][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41080
2024-03-10 05:40:46.411 UTC [34469][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-10 05:40:46.411 UTC [34469][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-10 05:40:46.437 UTC [34469][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-10 05:40:46.443 UTC [34469][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=trustdb host=localhost port=41080
2024-03-10 05:40:46.451 UTC [34419][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:46.451 UTC [34419][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:46.463 UTC [34419][postmaster] LOG:  background worker "logical replication launcher" (PID 34453) exited with exit code 1
2024-03-10 05:40:46.463 UTC [34427][checkpointer] LOG:  shutting down
2024-03-10 05:40:46.463 UTC [34427][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:46.463 UTC [34427][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/4196BC8, redo lsn=0/4196BC8
2024-03-10 05:40:46.470 UTC [34419][postmaster] LOG:  database system is shut down
2024-03-10 05:40:46.612 UTC [34485][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-10 05:40:46.612 UTC [34485][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:46.612 UTC [34485][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:46.632 UTC [34489][startup] LOG:  database system was shut down at 2024-03-10 05:40:46 UTC
2024-03-10 05:40:46.642 UTC [34485][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:46.734 UTC [34499][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41088
2024-03-10 05:40:46.736 UTC [34499][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:46.737 UTC [34499][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:46.774 UTC [34505][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41090
2024-03-10 05:40:46.790 UTC [34505][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-10 05:40:46.790 UTC [34505][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-10 05:40:46.830 UTC [34505][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-10 05:40:46.832 UTC [34505][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.058 user=ssltestuser database=trustdb host=localhost port=41090
2024-03-10 05:40:46.848 UTC [34485][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:46.848 UTC [34485][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:46.853 UTC [34485][postmaster] LOG:  background worker "logical replication launcher" (PID 34495) exited with exit code 1
2024-03-10 05:40:46.857 UTC [34487][checkpointer] LOG:  shutting down
2024-03-10 05:40:46.857 UTC [34487][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:46.858 UTC [34487][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196C40, redo lsn=0/4196C40
2024-03-10 05:40:46.864 UTC [34485][postmaster] LOG:  database system is shut down
2024-03-10 05:40:47.011 UTC [34523][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-10 05:40:47.011 UTC [34523][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:47.011 UTC [34523][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:47.031 UTC [34529][startup] LOG:  database system was shut down at 2024-03-10 05:40:46 UTC
2024-03-10 05:40:47.039 UTC [34523][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:47.083 UTC [34542][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41102
2024-03-10 05:40:47.087 UTC [34542][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:47.087 UTC [34542][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:47.133 UTC [34550][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41104
2024-03-10 05:40:47.145 UTC [34550][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-10 05:40:47.145 UTC [34550][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-10 05:40:47.166 UTC [34550][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-10 05:40:47.176 UTC [34550][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.047 user=ssltestuser database=trustdb host=localhost port=41104
2024-03-10 05:40:47.195 UTC [34523][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:47.195 UTC [34523][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:47.200 UTC [34523][postmaster] LOG:  background worker "logical replication launcher" (PID 34533) exited with exit code 1
2024-03-10 05:40:47.200 UTC [34527][checkpointer] LOG:  shutting down
2024-03-10 05:40:47.200 UTC [34527][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:47.201 UTC [34527][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196CB8, redo lsn=0/4196CB8
2024-03-10 05:40:47.207 UTC [34523][postmaster] LOG:  database system is shut down
2024-03-10 05:40:47.355 UTC [34579][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-10 05:40:47.358 UTC [34579][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:47.374 UTC [34579][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:47.409 UTC [34598][startup] LOG:  database system was shut down at 2024-03-10 05:40:47 UTC
2024-03-10 05:40:47.430 UTC [34579][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:47.524 UTC [34630][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41120
2024-03-10 05:40:47.527 UTC [34630][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:47.527 UTC [34630][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:47.569 UTC [34643][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41126
2024-03-10 05:40:47.585 UTC [34643][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-10 05:40:47.585 UTC [34643][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-10 05:40:47.609 UTC [34643][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-10 05:40:47.615 UTC [34643][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.054 user=ssltestuser database=trustdb host=localhost port=41126
2024-03-10 05:40:47.631 UTC [34579][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:47.631 UTC [34579][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:47.640 UTC [34579][postmaster] LOG:  background worker "logical replication launcher" (PID 34607) exited with exit code 1
2024-03-10 05:40:47.640 UTC [34594][checkpointer] LOG:  shutting down
2024-03-10 05:40:47.640 UTC [34594][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:47.641 UTC [34594][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196D30, redo lsn=0/4196D30
2024-03-10 05:40:47.647 UTC [34579][postmaster] LOG:  database system is shut down
2024-03-10 05:40:47.710 UTC [34652][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-10 05:40:47.710 UTC [34652][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:47.710 UTC [34652][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:47.741 UTC [34657][startup] LOG:  database system was shut down at 2024-03-10 05:40:47 UTC
2024-03-10 05:40:47.749 UTC [34652][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:47.786 UTC [34665][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41138
2024-03-10 05:40:47.789 UTC [34665][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:47.789 UTC [34665][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:47.839 UTC [34668][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41152
2024-03-10 05:40:47.847 UTC [34668][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-10 05:40:47.847 UTC [34668][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-10 05:40:47.874 UTC [34668][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-10 05:40:47.875 UTC [34668][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=trustdb host=localhost port=41152
2024-03-10 05:40:47.886 UTC [34652][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:47.887 UTC [34652][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:47.892 UTC [34652][postmaster] LOG:  background worker "logical replication launcher" (PID 34661) exited with exit code 1
2024-03-10 05:40:47.896 UTC [34655][checkpointer] LOG:  shutting down
2024-03-10 05:40:47.896 UTC [34655][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:47.896 UTC [34655][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196DA8, redo lsn=0/4196DA8
2024-03-10 05:40:47.903 UTC [34652][postmaster] LOG:  database system is shut down
2024-03-10 05:40:48.062 UTC [34696][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-10 05:40:48.062 UTC [34696][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:48.063 UTC [34696][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:48.073 UTC [34727][startup] LOG:  database system was shut down at 2024-03-10 05:40:47 UTC
2024-03-10 05:40:48.080 UTC [34696][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:48.133 UTC [34736][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41164
2024-03-10 05:40:48.135 UTC [34736][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:48.135 UTC [34736][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:48.184 UTC [34747][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41172
2024-03-10 05:40:48.193 UTC [34747][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-10 05:40:48.193 UTC [34747][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-10 05:40:48.225 UTC [34747][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-10 05:40:48.231 UTC [34747][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=ssltestuser database=trustdb host=localhost port=41172
2024-03-10 05:40:48.233 UTC [34696][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:48.233 UTC [34696][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:48.238 UTC [34696][postmaster] LOG:  background worker "logical replication launcher" (PID 34733) exited with exit code 1
2024-03-10 05:40:48.243 UTC [34725][checkpointer] LOG:  shutting down
2024-03-10 05:40:48.244 UTC [34725][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:48.244 UTC [34725][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196E20, redo lsn=0/4196E20
2024-03-10 05:40:48.253 UTC [34696][postmaster] LOG:  database system is shut down
2024-03-10 05:40:48.393 UTC [34785][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-10 05:40:48.393 UTC [34785][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:48.393 UTC [34785][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:48.413 UTC [34791][startup] LOG:  database system was shut down at 2024-03-10 05:40:48 UTC
2024-03-10 05:40:48.421 UTC [34785][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:48.471 UTC [34802][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41184
2024-03-10 05:40:48.476 UTC [34802][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:48.477 UTC [34802][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:48.525 UTC [34807][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41192
2024-03-10 05:40:48.532 UTC [34807][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-10 05:40:48.532 UTC [34807][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-10 05:40:48.560 UTC [34807][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-10 05:40:48.568 UTC [34807][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=trustdb host=localhost port=41192
2024-03-10 05:40:48.580 UTC [34785][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:48.580 UTC [34785][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:48.589 UTC [34785][postmaster] LOG:  background worker "logical replication launcher" (PID 34795) exited with exit code 1
2024-03-10 05:40:48.590 UTC [34789][checkpointer] LOG:  shutting down
2024-03-10 05:40:48.590 UTC [34789][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:48.591 UTC [34789][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196E98, redo lsn=0/4196E98
2024-03-10 05:40:48.597 UTC [34785][postmaster] LOG:  database system is shut down
2024-03-10 05:40:48.749 UTC [34844][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-10 05:40:48.749 UTC [34844][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64409
2024-03-10 05:40:48.749 UTC [34844][postmaster] LOG:  listening on Unix socket "/tmp/pRWzRcXZrR/.s.PGSQL.64409"
2024-03-10 05:40:48.765 UTC [34853][startup] LOG:  database system was shut down at 2024-03-10 05:40:48 UTC
2024-03-10 05:40:48.773 UTC [34844][postmaster] LOG:  database system is ready to accept connections
2024-03-10 05:40:48.812 UTC [34863][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41196
2024-03-10 05:40:48.814 UTC [34863][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-10 05:40:48.814 UTC [34863][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-10 05:40:48.860 UTC [34866][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=41198
2024-03-10 05:40:48.868 UTC [34866][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-10 05:40:48.868 UTC [34866][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-10 05:40:48.889 UTC [34866][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-10 05:40:48.896 UTC [34866][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.042 user=ssltestuser database=trustdb host=localhost port=41198
2024-03-10 05:40:48.914 UTC [34844][postmaster] LOG:  received fast shutdown request
2024-03-10 05:40:48.914 UTC [34844][postmaster] LOG:  aborting any active transactions
2024-03-10 05:40:48.922 UTC [34844][postmaster] LOG:  background worker "logical replication launcher" (PID 34857) exited with exit code 1
2024-03-10 05:40:48.923 UTC [34851][checkpointer] LOG:  shutting down
2024-03-10 05:40:48.923 UTC [34851][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-10 05:40:48.923 UTC [34851][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196F10, redo lsn=0/4196F10
2024-03-10 05:40:48.929 UTC [34844][postmaster] LOG:  database system is shut down
2024-03-10 05:40:48.951 UTC [34880][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-10 05:40:48.951 UTC [34880][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:40:49.035](0.141s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"