[10:49:16.340](0.038s) # setting up data directory
# Checking port 57378
# Found port 57378
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=57378 host=/tmp/jOxIR0rWH5
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[10:49:16.347](0.007s) # 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 29679
[10:49:16.618](0.271s) 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 29973
[10:49:18.264](1.646s) # 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-24 10:49:16.512 UTC [29679][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-24 10:49:16.512 UTC [29679][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:16.527 UTC [29689][startup] LOG:  database system was shut down at 2024-03-24 10:47:26 UTC
2024-03-24 10:49:16.534 UTC [29679][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:16.594 UTC [29701][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.595 UTC [29701][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-24 10:49:16.595 UTC [29701][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.615 UTC [29701][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-24 10:49:16.619 UTC [29701][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.630 UTC [29709][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.631 UTC [29709][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-24 10:49:16.631 UTC [29709][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.632 UTC [29709][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-24 10:49:16.634 UTC [29709][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.684 UTC [29724][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.688 UTC [29724][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-24 10:49:16.688 UTC [29724][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.701 UTC [29724][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-24 10:49:16.702 UTC [29724][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.747 UTC [29737][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.748 UTC [29737][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-24 10:49:16.748 UTC [29737][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.750 UTC [29737][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-24 10:49:16.752 UTC [29737][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.797 UTC [29747][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.798 UTC [29747][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-24 10:49:16.798 UTC [29747][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.800 UTC [29747][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-24 10:49:16.802 UTC [29747][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.848 UTC [29757][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.849 UTC [29757][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-24 10:49:16.849 UTC [29757][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.854 UTC [29757][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-24 10:49:16.913 UTC [29757][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.976 UTC [29771][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.977 UTC [29771][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-24 10:49:16.977 UTC [29771][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.980 UTC [29771][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-24 10:49:17.068 UTC [29771][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.092 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.117 UTC [29792][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.118 UTC [29792][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-24 10:49:17.118 UTC [29792][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.125 UTC [29792][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-24 10:49:17.229 UTC [29792][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.114 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.407 UTC [29846][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.408 UTC [29846][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-24 10:49:17.408 UTC [29846][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.414 UTC [29846][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-24 10:49:17.535 UTC [29846][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.130 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.694 UTC [29898][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.695 UTC [29898][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-24 10:49:17.695 UTC [29898][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.696 UTC [29898][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-24 10:49:17.757 UTC [29898][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.826 UTC [29925][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.829 UTC [29925][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-24 10:49:17.829 UTC [29925][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.833 UTC [29925][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-24 10:49:17.923 UTC [29925][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.097 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.950 UTC [29679][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:17.950 UTC [29679][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:17.954 UTC [29679][postmaster] LOG:  background worker "logical replication launcher" (PID 29693) exited with exit code 1
2024-03-24 10:49:17.960 UTC [29685][checkpointer] LOG:  shutting down
2024-03-24 10:49:17.960 UTC [29685][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.063 UTC [29685][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.102 s, sync=0.001 s, total=0.103 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100
2024-03-24 10:49:18.076 UTC [29679][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.221 UTC [29973][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-24 10:49:18.221 UTC [29973][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:18.221 UTC [29973][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:18.244 UTC [29979][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:18.251 UTC [29973][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:18.271 UTC [29973][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:18.271 UTC [29973][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:18.275 UTC [29973][postmaster] LOG:  background worker "logical replication launcher" (PID 29983) exited with exit code 1
2024-03-24 10:49:18.277 UTC [29977][checkpointer] LOG:  shutting down
2024-03-24 10:49:18.277 UTC [29977][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.278 UTC [29977][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/414F178, redo lsn=0/414F178
2024-03-24 10:49:18.282 UTC [29973][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.418 UTC [29994][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-24 10:49:18.418 UTC [29994][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[10:49:18.473](0.210s) 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 30010
[10:49:18.587](0.114s) 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-24 10:49:16.512 UTC [29679][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-24 10:49:16.512 UTC [29679][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:16.527 UTC [29689][startup] LOG:  database system was shut down at 2024-03-24 10:47:26 UTC
2024-03-24 10:49:16.534 UTC [29679][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:16.594 UTC [29701][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.595 UTC [29701][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-24 10:49:16.595 UTC [29701][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.615 UTC [29701][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-24 10:49:16.619 UTC [29701][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.630 UTC [29709][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.631 UTC [29709][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-24 10:49:16.631 UTC [29709][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.632 UTC [29709][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-24 10:49:16.634 UTC [29709][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.684 UTC [29724][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.688 UTC [29724][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-24 10:49:16.688 UTC [29724][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.701 UTC [29724][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-24 10:49:16.702 UTC [29724][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.747 UTC [29737][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.748 UTC [29737][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-24 10:49:16.748 UTC [29737][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.750 UTC [29737][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-24 10:49:16.752 UTC [29737][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.797 UTC [29747][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.798 UTC [29747][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-24 10:49:16.798 UTC [29747][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.800 UTC [29747][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-24 10:49:16.802 UTC [29747][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.848 UTC [29757][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.849 UTC [29757][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-24 10:49:16.849 UTC [29757][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.854 UTC [29757][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-24 10:49:16.913 UTC [29757][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.976 UTC [29771][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.977 UTC [29771][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-24 10:49:16.977 UTC [29771][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.980 UTC [29771][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-24 10:49:17.068 UTC [29771][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.092 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.117 UTC [29792][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.118 UTC [29792][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-24 10:49:17.118 UTC [29792][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.125 UTC [29792][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-24 10:49:17.229 UTC [29792][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.114 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.407 UTC [29846][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.408 UTC [29846][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-24 10:49:17.408 UTC [29846][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.414 UTC [29846][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-24 10:49:17.535 UTC [29846][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.130 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.694 UTC [29898][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.695 UTC [29898][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-24 10:49:17.695 UTC [29898][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.696 UTC [29898][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-24 10:49:17.757 UTC [29898][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.826 UTC [29925][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.829 UTC [29925][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-24 10:49:17.829 UTC [29925][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.833 UTC [29925][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-24 10:49:17.923 UTC [29925][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.097 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.950 UTC [29679][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:17.950 UTC [29679][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:17.954 UTC [29679][postmaster] LOG:  background worker "logical replication launcher" (PID 29693) exited with exit code 1
2024-03-24 10:49:17.960 UTC [29685][checkpointer] LOG:  shutting down
2024-03-24 10:49:17.960 UTC [29685][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.063 UTC [29685][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.102 s, sync=0.001 s, total=0.103 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100
2024-03-24 10:49:18.076 UTC [29679][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.221 UTC [29973][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-24 10:49:18.221 UTC [29973][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:18.221 UTC [29973][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:18.244 UTC [29979][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:18.251 UTC [29973][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:18.271 UTC [29973][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:18.271 UTC [29973][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:18.275 UTC [29973][postmaster] LOG:  background worker "logical replication launcher" (PID 29983) exited with exit code 1
2024-03-24 10:49:18.277 UTC [29977][checkpointer] LOG:  shutting down
2024-03-24 10:49:18.277 UTC [29977][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.278 UTC [29977][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/414F178, redo lsn=0/414F178
2024-03-24 10:49:18.282 UTC [29973][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.418 UTC [29994][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-24 10:49:18.418 UTC [29994][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.525 UTC [30010][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-24 10:49:18.525 UTC [30010][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:18.525 UTC [30010][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:18.561 UTC [30021][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:18.568 UTC [30010][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:18.596 UTC [30010][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:18.596 UTC [30010][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:18.600 UTC [30010][postmaster] LOG:  background worker "logical replication launcher" (PID 30029) exited with exit code 1
2024-03-24 10:49:18.608 UTC [30019][checkpointer] LOG:  shutting down
2024-03-24 10:49:18.608 UTC [30019][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.608 UTC [30019][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/414F1F0, redo lsn=0/414F1F0
2024-03-24 10:49:18.615 UTC [30010][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.744 UTC [30049][postmaster] FATAL:  could not set SSL protocol version range
2024-03-24 10:49:18.744 UTC [30049][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-24 10:49:18.744 UTC [30049][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[10:49:18.799](0.212s) 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 30064
[10:49:18.905](0.106s) ok 5 - restart succeeds with correct SSL protocol bounds
[10:49:18.905](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 30094
[10:49:19.148](0.243s) ok 6 - server doesn't accept non-SSL connections
[10:49:19.149](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[10:49:19.231](0.082s) ok 8 - connect without server root cert sslmode=require
[10:49:19.231](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[10:49:19.280](0.049s) ok 10 - connect without server root cert sslmode=verify-ca
[10:49:19.280](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[10:49:19.319](0.039s) ok 12 - connect without server root cert sslmode=verify-full
[10:49:19.319](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[10:49:19.380](0.061s) ok 14 - connect with wrong server root cert sslmode=require
[10:49:19.381](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[10:49:19.408](0.027s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[10:49:19.408](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[10:49:19.428](0.020s) ok 18 - connect with wrong server root cert sslmode=verify-full
[10:49:19.429](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[10:49:19.459](0.030s) ok 20 - connect with server CA cert, without root CA
[10:49:19.459](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[10:49:19.504](0.045s) ok 22 - connect with correct server CA cert file sslmode=require
[10:49:19.504](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[10:49:19.551](0.047s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[10:49:19.551](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[10:49:19.583](0.032s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[10:49:19.583](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[10:49:19.622](0.039s) ok 28 - cert root file that contains two certificates, order 1
[10:49:19.622](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[10:49:19.663](0.041s) ok 30 - cert root file that contains two certificates, order 2
[10:49:19.663](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[10:49:19.691](0.028s) ok 32 - connect with sslcertmode=disable
[10:49:19.691](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[10:49:19.755](0.064s) ok 34 - connect with sslcertmode=allow
[10:49:19.756](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[10:49:19.817](0.061s) ok 36 - connect with sslcertmode=require fails without a client certificate
[10:49:19.817](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[10:49:19.870](0.053s) ok 38 - sslcrl option with invalid file name
[10:49:19.870](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[10:49:19.924](0.054s) ok 40 - CRL belonging to a different CA
[10:49:19.924](0.000s) ok 41 - CRL belonging to a different CA: matches
[10:49:19.957](0.032s) ok 42 - directory CRL belonging to a different CA
[10:49:19.957](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[10:49:19.990](0.033s) ok 44 - CRL with a non-revoked cert
[10:49:19.990](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[10:49:20.032](0.042s) ok 46 - directory CRL with a non-revoked cert
[10:49:20.032](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[10:49:20.110](0.078s) ok 48 - mismatch between host name and server certificate sslmode=require
[10:49:20.110](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[10:49:20.166](0.055s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[10:49:20.166](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[10:49:20.222](0.055s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[10:49:20.222](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 30273
[10:49:20.476](0.254s) ok 54 - IP address in the Common Name
[10:49:20.476](0.000s) ok 55 - IP address in the Common Name: no stderr
[10:49:20.504](0.028s) ok 56 - mismatch between host name and server certificate IP address
[10:49:20.505](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 30311
[10:49:20.821](0.316s) ok 58 - IP address in a dNSName
[10:49:20.821](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 30346
[10:49:21.102](0.281s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[10:49:21.102](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[10:49:21.189](0.087s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[10:49:21.189](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[10:49:21.245](0.055s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[10:49:21.245](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[10:49:21.282](0.037s) ok 66 - host name not matching with X.509 Subject Alternative Names
[10:49:21.282](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[10:49:21.310](0.027s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[10:49:21.310](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 30428
[10:49:21.595](0.285s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[10:49:21.595](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[10:49:21.627](0.032s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[10:49:21.627](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[10:49:21.667](0.040s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[10:49:21.668](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 30494
[10:49:21.960](0.292s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[10:49:21.960](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[10:49:21.993](0.033s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[10:49:21.993](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[10:49:22.050](0.057s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[10:49:22.050](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[10:49:22.109](0.059s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[10:49:22.109](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[10:49:22.170](0.061s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[10:49:22.170](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[10:49:22.223](0.052s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[10:49:22.223](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[10:49:22.259](0.036s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[10:49:22.259](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[10:49:22.310](0.051s) ok 90 - IPv6 host with CIDR mask does not match
[10:49:22.310](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 30651
[10:49:22.504](0.194s) ok 92 - certificate with both a CN and SANs 1
[10:49:22.505](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[10:49:22.550](0.045s) ok 94 - certificate with both a CN and SANs 2
[10:49:22.550](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[10:49:22.608](0.058s) ok 96 - certificate with both a CN and SANs ignores CN
[10:49:22.608](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 30706
[10:49:22.877](0.269s) ok 98 - certificate with both a CN and IP SANs matches CN
[10:49:22.878](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[10:49:22.927](0.050s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[10:49:22.927](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[10:49:22.984](0.057s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[10:49:22.984](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 30787
[10:49:23.375](0.391s) ok 104 - certificate with both an IP CN and IP SANs 1
[10:49:23.375](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[10:49:23.426](0.050s) ok 106 - certificate with both an IP CN and IP SANs 2
[10:49:23.426](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[10:49:23.484](0.058s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[10:49:23.484](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 30845
[10:49:23.676](0.192s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[10:49:23.676](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[10:49:23.716](0.040s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[10:49:23.716](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[10:49:23.745](0.029s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[10:49:23.745](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 30933
[10:49:24.260](0.515s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[10:49:24.261](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[10:49:24.331](0.070s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[10:49:24.331](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 30990
[10:49:24.723](0.392s) ok 120 - sslrootcert=system does not connect with private CA
[10:49:24.724](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[10:49:24.773](0.049s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[10:49:24.773](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[10:49:24.850](0.077s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[10:49:24.850](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[10:49:24.905](0.054s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[10:49:24.905](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 31037
[10:49:25.149](0.244s) ok 128 - connects without client-side CRL
[10:49:25.149](0.000s) ok 129 - connects without client-side CRL: no stderr
[10:49:25.218](0.069s) ok 130 - does not connect with client-side CRL file
[10:49:25.218](0.000s) ok 131 - does not connect with client-side CRL file: matches
[10:49:25.275](0.057s) ok 132 - does not connect with client-side CRL directory
[10:49:25.275](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()
[10:49:25.350](0.075s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[10:49:25.350](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[10:49:25.351](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[10:49:25.418](0.067s) ok 137 - connection success with correct range of TLS protocol versions
[10:49:25.418](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[10:49:25.458](0.040s) ok 139 - connection failure with incorrect range of TLS protocol versions
[10:49:25.458](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[10:49:25.506](0.048s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[10:49:25.506](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[10:49:25.536](0.030s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[10:49:25.536](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[10:49:25.536](0.000s) # running server tests
[10:49:25.579](0.043s) ok 145 - certificate authorization fails without client cert
[10:49:25.580](0.000s) ok 146 - certificate authorization fails without client cert: matches
[10:49:25.651](0.071s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[10:49:25.651](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[10:49:25.705](0.054s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[10:49:25.706](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[10:49:25.770](0.064s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[10:49:25.770](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[10:49:25.813](0.043s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[10:49:25.813](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[10:49:25.869](0.056s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[10:49:25.869](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[10:49:25.946](0.077s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[10:49:25.947](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[10:49:26.010](0.063s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[10:49:26.010](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[10:49:26.041](0.031s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[10:49:26.041](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[10:49:26.128](0.087s) ok 163 - certificate authorization succeeds with DN mapping
[10:49:26.128](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[10:49:26.128](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[10:49:26.176](0.047s) ok 166 - certificate authorization succeeds with DN regex mapping
[10:49:26.176](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[10:49:26.225](0.050s) ok 168 - certificate authorization succeeds with CN mapping
[10:49:26.226](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[10:49:26.226](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[10:49:26.226](0.000s) not ok 171 # TODO & SKIP Need Pty support
[10:49:26.226](0.000s) not ok 172 # TODO & SKIP Need Pty support
[10:49:26.226](0.000s) not ok 173 # TODO & SKIP Need Pty support
[10:49:26.226](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_t4nG/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[10:49:26.299](0.072s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[10:49:26.299](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[10:49:26.299](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[10:49:26.324](0.024s) ok 178 - certificate authorization fails because of file permissions
[10:49:26.324](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[10:49:26.371](0.048s) ok 180 - certificate authorization fails with client cert belonging to another user
[10:49:26.372](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[10:49:26.424](0.053s) ok 182 - certificate authorization fails with revoked client cert
[10:49:26.425](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[10:49:26.425](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[10:49:26.490](0.065s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[10:49:26.490](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[10:49:26.490](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[10:49:26.537](0.046s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[10:49:26.537](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[10:49:26.537](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[10:49:26.588](0.051s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[10:49:26.588](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[10:49:26.589](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 31304
[10:49:26.992](0.404s) ok 194 - intermediate client certificate is provided by client
[10:49:26.993](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[10:49:27.072](0.079s) ok 196 - intermediate client certificate is missing
[10:49:27.072](0.000s) ok 197 - intermediate client certificate is missing: matches
[10:49:27.154](0.082s) ok 198 - logged client certificate Subjects are truncated if they're too long
[10:49:27.154](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 31380
[10:49:27.433](0.278s) ok 200 - intermediate client certificate is untrusted
[10:49:27.433](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 31416
[10:49:27.821](0.388s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[10:49:27.822](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[10:49:27.914](0.092s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[10:49:27.914](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 31451
[10:49:28.084](0.170s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[10:49:28.084](0.000s) 
[10:49:28.084](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[10:49:28.084](0.000s) #          got: '2'
#     expected: '0'
[10:49:28.084](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[10:49:28.085](0.000s) 
[10:49:28.085](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[10:49:28.085](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 57378 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: ''
[10:49:28.129](0.044s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[10:49:28.129](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 31513
[10:49:28.552](0.423s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[10:49:28.615](0.063s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[10:49:28.615](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 31551
[10:49:28.884](0.269s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[10:49:28.976](0.091s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[10:49:28.976](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 31631
[10:49:29.247](0.271s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[10:49:29.329](0.083s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[10:49:29.330](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 31683
[10:49:29.598](0.268s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[10:49:29.598](0.000s) 
[10:49:29.598](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[10:49:29.599](0.000s) #          got: '2'
#     expected: '0'
[10:49:29.599](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[10:49:29.599](0.000s) 
[10:49:29.599](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[10:49:29.599](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 57378 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[10:49:29.703](0.104s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[10:49:29.703](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 31739
[10:49:29.959](0.256s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[10:49:30.005](0.046s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[10:49:30.006](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 31789
[10:49:30.255](0.249s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[10:49:30.328](0.074s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[10:49:30.329](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 31844
[10:49:30.587](0.258s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[10:49:30.641](0.054s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[10:49:30.641](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 31919
[10:49:31.013](0.372s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[10:49:31.090](0.077s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[10:49:31.090](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-24 10:49:16.512 UTC [29679][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-24 10:49:16.512 UTC [29679][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:16.527 UTC [29689][startup] LOG:  database system was shut down at 2024-03-24 10:47:26 UTC
2024-03-24 10:49:16.534 UTC [29679][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:16.594 UTC [29701][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.595 UTC [29701][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-24 10:49:16.595 UTC [29701][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.615 UTC [29701][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-24 10:49:16.619 UTC [29701][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.630 UTC [29709][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.631 UTC [29709][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-24 10:49:16.631 UTC [29709][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.632 UTC [29709][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-24 10:49:16.634 UTC [29709][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.684 UTC [29724][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.688 UTC [29724][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-24 10:49:16.688 UTC [29724][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.701 UTC [29724][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-24 10:49:16.702 UTC [29724][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.747 UTC [29737][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.748 UTC [29737][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-24 10:49:16.748 UTC [29737][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.750 UTC [29737][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-24 10:49:16.752 UTC [29737][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.797 UTC [29747][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.798 UTC [29747][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-24 10:49:16.798 UTC [29747][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.800 UTC [29747][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-24 10:49:16.802 UTC [29747][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.848 UTC [29757][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.849 UTC [29757][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-24 10:49:16.849 UTC [29757][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.854 UTC [29757][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-24 10:49:16.913 UTC [29757][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-24 10:49:16.976 UTC [29771][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:16.977 UTC [29771][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-24 10:49:16.977 UTC [29771][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:16.980 UTC [29771][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-24 10:49:17.068 UTC [29771][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.092 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.117 UTC [29792][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.118 UTC [29792][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-24 10:49:17.118 UTC [29792][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.125 UTC [29792][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-24 10:49:17.229 UTC [29792][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.114 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.407 UTC [29846][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.408 UTC [29846][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-24 10:49:17.408 UTC [29846][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.414 UTC [29846][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-24 10:49:17.535 UTC [29846][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.130 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.694 UTC [29898][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.695 UTC [29898][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-24 10:49:17.695 UTC [29898][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.696 UTC [29898][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-24 10:49:17.757 UTC [29898][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.826 UTC [29925][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-24 10:49:17.829 UTC [29925][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-24 10:49:17.829 UTC [29925][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-24 10:49:17.833 UTC [29925][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-24 10:49:17.923 UTC [29925][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.097 user=postgres database=postgres host=[local]
2024-03-24 10:49:17.950 UTC [29679][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:17.950 UTC [29679][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:17.954 UTC [29679][postmaster] LOG:  background worker "logical replication launcher" (PID 29693) exited with exit code 1
2024-03-24 10:49:17.960 UTC [29685][checkpointer] LOG:  shutting down
2024-03-24 10:49:17.960 UTC [29685][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.063 UTC [29685][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.102 s, sync=0.001 s, total=0.103 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100
2024-03-24 10:49:18.076 UTC [29679][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.221 UTC [29973][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-24 10:49:18.221 UTC [29973][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:18.221 UTC [29973][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:18.244 UTC [29979][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:18.251 UTC [29973][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:18.271 UTC [29973][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:18.271 UTC [29973][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:18.275 UTC [29973][postmaster] LOG:  background worker "logical replication launcher" (PID 29983) exited with exit code 1
2024-03-24 10:49:18.277 UTC [29977][checkpointer] LOG:  shutting down
2024-03-24 10:49:18.277 UTC [29977][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.278 UTC [29977][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/414F178, redo lsn=0/414F178
2024-03-24 10:49:18.282 UTC [29973][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.418 UTC [29994][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-24 10:49:18.418 UTC [29994][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.525 UTC [30010][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-24 10:49:18.525 UTC [30010][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:18.525 UTC [30010][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:18.561 UTC [30021][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:18.568 UTC [30010][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:18.596 UTC [30010][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:18.596 UTC [30010][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:18.600 UTC [30010][postmaster] LOG:  background worker "logical replication launcher" (PID 30029) exited with exit code 1
2024-03-24 10:49:18.608 UTC [30019][checkpointer] LOG:  shutting down
2024-03-24 10:49:18.608 UTC [30019][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.608 UTC [30019][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/414F1F0, redo lsn=0/414F1F0
2024-03-24 10:49:18.615 UTC [30010][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.744 UTC [30049][postmaster] FATAL:  could not set SSL protocol version range
2024-03-24 10:49:18.744 UTC [30049][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-24 10:49:18.744 UTC [30049][postmaster] LOG:  database system is shut down
2024-03-24 10:49:18.846 UTC [30064][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-24 10:49:18.846 UTC [30064][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:18.846 UTC [30064][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:18.872 UTC [30072][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:18.880 UTC [30064][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:18.923 UTC [30064][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:18.923 UTC [30064][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:18.928 UTC [30064][postmaster] LOG:  background worker "logical replication launcher" (PID 30078) exited with exit code 1
2024-03-24 10:49:18.931 UTC [30070][checkpointer] LOG:  shutting down
2024-03-24 10:49:18.932 UTC [30070][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:18.932 UTC [30070][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/414F268, redo lsn=0/414F268
2024-03-24 10:49:18.940 UTC [30064][postmaster] LOG:  database system is shut down
2024-03-24 10:49:19.074 UTC [30094][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-24 10:49:19.074 UTC [30094][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:19.074 UTC [30094][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:19.082 UTC [30111][startup] LOG:  database system was shut down at 2024-03-24 10:49:18 UTC
2024-03-24 10:49:19.089 UTC [30094][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:19.145 UTC [30127][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51216
2024-03-24 10:49:19.146 UTC [30127][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-24 10:49:19.146 UTC [30127][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-24 10:49:19.168 UTC [30130][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51222
2024-03-24 10:49:19.174 UTC [30130][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-24 10:49:19.174 UTC [30130][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-24 10:49:19.227 UTC [30130][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-24 10:49:19.233 UTC [30130][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.066 user=ssltestuser database=trustdb host=localhost port=51222
2024-03-24 10:49:19.275 UTC [30145][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51228
2024-03-24 10:49:19.279 UTC [30145][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:19.313 UTC [30148][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51238
2024-03-24 10:49:19.316 UTC [30148][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:19.362 UTC [30156][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51252
2024-03-24 10:49:19.371 UTC [30156][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:19.394 UTC [30161][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51268
2024-03-24 10:49:19.400 UTC [30161][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:19.419 UTC [30163][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51274
2024-03-24 10:49:19.428 UTC [30163][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:19.446 UTC [30167][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51276
2024-03-24 10:49:19.451 UTC [30167][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:19.481 UTC [30174][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51280
2024-03-24 10:49:19.494 UTC [30174][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-24 10:49:19.494 UTC [30174][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-24 10:49:19.495 UTC [30174][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-24 10:49:19.498 UTC [30174][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=51280
2024-03-24 10:49:19.537 UTC [30176][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51288
2024-03-24 10:49:19.543 UTC [30176][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-24 10:49:19.543 UTC [30176][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-24 10:49:19.545 UTC [30176][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-24 10:49:19.549 UTC [30176][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=51288
2024-03-24 10:49:19.570 UTC [30181][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51290
2024-03-24 10:49:19.578 UTC [30181][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-24 10:49:19.578 UTC [30181][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-24 10:49:19.579 UTC [30181][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-24 10:49:19.582 UTC [30181][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=51290
2024-03-24 10:49:19.602 UTC [30187][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51306
2024-03-24 10:49:19.616 UTC [30187][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-24 10:49:19.617 UTC [30187][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-24 10:49:19.618 UTC [30187][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-24 10:49:19.619 UTC [30187][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=51306
2024-03-24 10:49:19.651 UTC [30194][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51318
2024-03-24 10:49:19.658 UTC [30194][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-24 10:49:19.658 UTC [30194][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-24 10:49:19.660 UTC [30194][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-24 10:49:19.665 UTC [30194][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=51318
2024-03-24 10:49:19.680 UTC [30200][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51324
2024-03-24 10:49:19.686 UTC [30200][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-24 10:49:19.686 UTC [30200][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-24 10:49:19.688 UTC [30200][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-24 10:49:19.692 UTC [30200][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=51324
2024-03-24 10:49:19.739 UTC [30205][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51334
2024-03-24 10:49:19.746 UTC [30205][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-24 10:49:19.746 UTC [30205][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-24 10:49:19.748 UTC [30205][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-24 10:49:19.757 UTC [30205][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=51334
2024-03-24 10:49:19.801 UTC [30212][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51336
2024-03-24 10:49:19.812 UTC [30212][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-24 10:49:19.812 UTC [30212][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-24 10:49:19.819 UTC [30212][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=51336
2024-03-24 10:49:19.850 UTC [30216][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51348
2024-03-24 10:49:19.856 UTC [30216][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-24 10:49:19.857 UTC [30216][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-24 10:49:19.859 UTC [30216][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-24 10:49:19.862 UTC [30216][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=51348
2024-03-24 10:49:19.906 UTC [30226][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51350
2024-03-24 10:49:19.925 UTC [30226][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:19.946 UTC [30233][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51364
2024-03-24 10:49:19.958 UTC [30233][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:19.975 UTC [30239][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51370
2024-03-24 10:49:19.983 UTC [30239][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-24 10:49:19.983 UTC [30239][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-24 10:49:19.985 UTC [30239][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-24 10:49:19.989 UTC [30239][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=51370
2024-03-24 10:49:20.007 UTC [30243][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51380
2024-03-24 10:49:20.015 UTC [30243][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-24 10:49:20.015 UTC [30243][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-24 10:49:20.017 UTC [30243][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-24 10:49:20.034 UTC [30243][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=51380
2024-03-24 10:49:20.093 UTC [30252][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51386
2024-03-24 10:49:20.103 UTC [30252][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-24 10:49:20.103 UTC [30252][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-24 10:49:20.106 UTC [30252][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-24 10:49:20.112 UTC [30252][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=51386
2024-03-24 10:49:20.149 UTC [30258][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51400
2024-03-24 10:49:20.158 UTC [30258][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-24 10:49:20.158 UTC [30258][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-24 10:49:20.162 UTC [30258][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-24 10:49:20.168 UTC [30258][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=51400
2024-03-24 10:49:20.199 UTC [30260][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51408
2024-03-24 10:49:20.228 UTC [30094][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:20.228 UTC [30094][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:20.232 UTC [30094][postmaster] LOG:  background worker "logical replication launcher" (PID 30115) exited with exit code 1
2024-03-24 10:49:20.235 UTC [30109][checkpointer] LOG:  shutting down
2024-03-24 10:49:20.235 UTC [30109][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:20.236 UTC [30109][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/414F2E0, redo lsn=0/414F2E0
2024-03-24 10:49:20.241 UTC [30094][postmaster] LOG:  database system is shut down
2024-03-24 10:49:20.390 UTC [30273][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-24 10:49:20.390 UTC [30273][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:20.390 UTC [30273][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:20.397 UTC [30281][startup] LOG:  database system was shut down at 2024-03-24 10:49:20 UTC
2024-03-24 10:49:20.405 UTC [30273][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:20.452 UTC [30290][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51412
2024-03-24 10:49:20.459 UTC [30290][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-24 10:49:20.459 UTC [30290][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-24 10:49:20.472 UTC [30290][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-24 10:49:20.480 UTC [30290][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=51412
2024-03-24 10:49:20.496 UTC [30295][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51428
2024-03-24 10:49:20.512 UTC [30273][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:20.512 UTC [30273][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:20.520 UTC [30273][postmaster] LOG:  background worker "logical replication launcher" (PID 30284) exited with exit code 1
2024-03-24 10:49:20.520 UTC [30278][checkpointer] LOG:  shutting down
2024-03-24 10:49:20.520 UTC [30278][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:20.521 UTC [30278][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/414F358, redo lsn=0/414F358
2024-03-24 10:49:20.526 UTC [30273][postmaster] LOG:  database system is shut down
2024-03-24 10:49:20.662 UTC [30311][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-24 10:49:20.662 UTC [30311][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:20.662 UTC [30311][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:20.684 UTC [30316][startup] LOG:  database system was shut down at 2024-03-24 10:49:20 UTC
2024-03-24 10:49:20.691 UTC [30311][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:20.770 UTC [30329][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51434
2024-03-24 10:49:20.788 UTC [30329][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-24 10:49:20.788 UTC [30329][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-24 10:49:20.817 UTC [30329][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-24 10:49:20.820 UTC [30329][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.056 user=ssltestuser database=trustdb host=localhost port=51434
2024-03-24 10:49:20.834 UTC [30311][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:20.834 UTC [30311][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:20.838 UTC [30311][postmaster] LOG:  background worker "logical replication launcher" (PID 30320) exited with exit code 1
2024-03-24 10:49:20.841 UTC [30314][checkpointer] LOG:  shutting down
2024-03-24 10:49:20.841 UTC [30314][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:20.842 UTC [30314][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/414F3D0, redo lsn=0/414F3D0
2024-03-24 10:49:20.847 UTC [30311][postmaster] LOG:  database system is shut down
2024-03-24 10:49:20.976 UTC [30346][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-24 10:49:20.976 UTC [30346][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:20.976 UTC [30346][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:20.993 UTC [30358][startup] LOG:  database system was shut down at 2024-03-24 10:49:20 UTC
2024-03-24 10:49:21.001 UTC [30346][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:21.067 UTC [30374][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51436
2024-03-24 10:49:21.074 UTC [30374][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-24 10:49:21.074 UTC [30374][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-24 10:49:21.098 UTC [30374][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-24 10:49:21.103 UTC [30374][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=51436
2024-03-24 10:49:21.169 UTC [30385][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51446
2024-03-24 10:49:21.182 UTC [30385][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-24 10:49:21.182 UTC [30385][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-24 10:49:21.185 UTC [30385][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-24 10:49:21.191 UTC [30385][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=51446
2024-03-24 10:49:21.233 UTC [30389][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51454
2024-03-24 10:49:21.239 UTC [30389][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-24 10:49:21.239 UTC [30389][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-24 10:49:21.241 UTC [30389][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-24 10:49:21.241 UTC [30389][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=51454
2024-03-24 10:49:21.268 UTC [30394][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51466
2024-03-24 10:49:21.300 UTC [30403][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51468
2024-03-24 10:49:21.318 UTC [30346][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:21.318 UTC [30346][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:21.323 UTC [30346][postmaster] LOG:  background worker "logical replication launcher" (PID 30365) exited with exit code 1
2024-03-24 10:49:21.326 UTC [30356][checkpointer] LOG:  shutting down
2024-03-24 10:49:21.326 UTC [30356][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:21.327 UTC [30356][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/414F448, redo lsn=0/414F448
2024-03-24 10:49:21.335 UTC [30346][postmaster] LOG:  database system is shut down
2024-03-24 10:49:21.496 UTC [30428][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-24 10:49:21.496 UTC [30428][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:21.496 UTC [30428][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:21.514 UTC [30438][startup] LOG:  database system was shut down at 2024-03-24 10:49:21 UTC
2024-03-24 10:49:21.523 UTC [30428][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:21.554 UTC [30450][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51484
2024-03-24 10:49:21.567 UTC [30450][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-24 10:49:21.567 UTC [30450][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-24 10:49:21.591 UTC [30450][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-24 10:49:21.597 UTC [30450][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=ssltestuser database=trustdb host=localhost port=51484
2024-03-24 10:49:21.618 UTC [30461][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51494
2024-03-24 10:49:21.655 UTC [30467][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51510
2024-03-24 10:49:21.699 UTC [30428][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:21.699 UTC [30428][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:21.704 UTC [30428][postmaster] LOG:  background worker "logical replication launcher" (PID 30443) exited with exit code 1
2024-03-24 10:49:21.709 UTC [30436][checkpointer] LOG:  shutting down
2024-03-24 10:49:21.709 UTC [30436][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:21.709 UTC [30436][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/414F4C0, redo lsn=0/414F4C0
2024-03-24 10:49:21.715 UTC [30428][postmaster] LOG:  database system is shut down
2024-03-24 10:49:21.845 UTC [30494][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-24 10:49:21.845 UTC [30494][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:21.845 UTC [30494][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:21.872 UTC [30504][startup] LOG:  database system was shut down at 2024-03-24 10:49:21 UTC
2024-03-24 10:49:21.888 UTC [30494][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:21.931 UTC [30525][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51514
2024-03-24 10:49:21.940 UTC [30525][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-24 10:49:21.940 UTC [30525][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-24 10:49:21.956 UTC [30525][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-24 10:49:21.961 UTC [30525][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=51514
2024-03-24 10:49:21.982 UTC [30543][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51522
2024-03-24 10:49:21.988 UTC [30543][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-24 10:49:21.988 UTC [30543][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-24 10:49:21.990 UTC [30543][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-24 10:49:21.994 UTC [30543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=51522
2024-03-24 10:49:22.041 UTC [30552][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51536
2024-03-24 10:49:22.085 UTC [30567][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51550
2024-03-24 10:49:22.091 UTC [30567][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-24 10:49:22.091 UTC [30567][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-24 10:49:22.105 UTC [30567][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-24 10:49:22.111 UTC [30567][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=51550
2024-03-24 10:49:22.148 UTC [30577][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51566
2024-03-24 10:49:22.164 UTC [30577][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-24 10:49:22.164 UTC [30577][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-24 10:49:22.166 UTC [30577][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-24 10:49:22.171 UTC [30577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=51566
2024-03-24 10:49:22.211 UTC [30628][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51572
2024-03-24 10:49:22.217 UTC [30628][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-24 10:49:22.217 UTC [30628][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-24 10:49:22.218 UTC [30628][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-24 10:49:22.222 UTC [30628][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=51572
2024-03-24 10:49:22.250 UTC [30639][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51578
2024-03-24 10:49:22.297 UTC [30643][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51580
2024-03-24 10:49:22.319 UTC [30494][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:22.319 UTC [30494][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:22.333 UTC [30494][postmaster] LOG:  background worker "logical replication launcher" (PID 30513) exited with exit code 1
2024-03-24 10:49:22.333 UTC [30501][checkpointer] LOG:  shutting down
2024-03-24 10:49:22.333 UTC [30501][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:22.334 UTC [30501][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/414F538, redo lsn=0/414F538
2024-03-24 10:49:22.339 UTC [30494][postmaster] LOG:  database system is shut down
2024-03-24 10:49:22.403 UTC [30651][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-24 10:49:22.403 UTC [30651][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:22.403 UTC [30651][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:22.417 UTC [30658][startup] LOG:  database system was shut down at 2024-03-24 10:49:22 UTC
2024-03-24 10:49:22.436 UTC [30651][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:22.479 UTC [30671][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51596
2024-03-24 10:49:22.485 UTC [30671][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-24 10:49:22.485 UTC [30671][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-24 10:49:22.500 UTC [30671][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-24 10:49:22.506 UTC [30671][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=51596
2024-03-24 10:49:22.530 UTC [30677][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51604
2024-03-24 10:49:22.545 UTC [30677][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-24 10:49:22.545 UTC [30677][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-24 10:49:22.546 UTC [30677][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-24 10:49:22.547 UTC [30677][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=51604
2024-03-24 10:49:22.584 UTC [30685][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51612
2024-03-24 10:49:22.630 UTC [30651][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:22.630 UTC [30651][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:22.638 UTC [30651][postmaster] LOG:  background worker "logical replication launcher" (PID 30665) exited with exit code 1
2024-03-24 10:49:22.638 UTC [30656][checkpointer] LOG:  shutting down
2024-03-24 10:49:22.638 UTC [30656][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:22.639 UTC [30656][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/414F5B0, redo lsn=0/414F5B0
2024-03-24 10:49:22.645 UTC [30651][postmaster] LOG:  database system is shut down
2024-03-24 10:49:22.780 UTC [30706][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-24 10:49:22.780 UTC [30706][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:22.780 UTC [30706][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:22.800 UTC [30725][startup] LOG:  database system was shut down at 2024-03-24 10:49:22 UTC
2024-03-24 10:49:22.809 UTC [30706][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:22.850 UTC [30744][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51614
2024-03-24 10:49:22.857 UTC [30744][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-24 10:49:22.857 UTC [30744][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-24 10:49:22.873 UTC [30744][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-24 10:49:22.879 UTC [30744][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=51614
2024-03-24 10:49:22.909 UTC [30757][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51624
2024-03-24 10:49:22.922 UTC [30757][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-24 10:49:22.922 UTC [30757][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-24 10:49:22.923 UTC [30757][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-24 10:49:22.928 UTC [30757][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=51624
2024-03-24 10:49:22.965 UTC [30769][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51630
2024-03-24 10:49:22.978 UTC [30769][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-24 10:49:22.978 UTC [30769][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-24 10:49:22.981 UTC [30769][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-24 10:49:22.983 UTC [30769][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=51630
2024-03-24 10:49:22.993 UTC [30706][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:22.994 UTC [30706][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:22.998 UTC [30706][postmaster] LOG:  background worker "logical replication launcher" (PID 30731) exited with exit code 1
2024-03-24 10:49:23.003 UTC [30722][checkpointer] LOG:  shutting down
2024-03-24 10:49:23.003 UTC [30722][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:23.003 UTC [30722][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/414F628, redo lsn=0/414F628
2024-03-24 10:49:23.010 UTC [30706][postmaster] LOG:  database system is shut down
2024-03-24 10:49:23.171 UTC [30787][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-24 10:49:23.171 UTC [30787][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:23.171 UTC [30787][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:23.200 UTC [30798][startup] LOG:  database system was shut down at 2024-03-24 10:49:22 UTC
2024-03-24 10:49:23.210 UTC [30787][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:23.334 UTC [30819][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51636
2024-03-24 10:49:23.344 UTC [30819][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-24 10:49:23.344 UTC [30819][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-24 10:49:23.371 UTC [30819][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-24 10:49:23.377 UTC [30819][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=ssltestuser database=trustdb host=localhost port=51636
2024-03-24 10:49:23.408 UTC [30827][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51652
2024-03-24 10:49:23.417 UTC [30827][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-24 10:49:23.417 UTC [30827][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-24 10:49:23.422 UTC [30827][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-24 10:49:23.425 UTC [30827][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=51652
2024-03-24 10:49:23.456 UTC [30834][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51662
2024-03-24 10:49:23.496 UTC [30787][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:23.496 UTC [30787][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:23.508 UTC [30787][postmaster] LOG:  background worker "logical replication launcher" (PID 30804) exited with exit code 1
2024-03-24 10:49:23.508 UTC [30796][checkpointer] LOG:  shutting down
2024-03-24 10:49:23.508 UTC [30796][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:23.509 UTC [30796][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/414F6A0, redo lsn=0/414F6A0
2024-03-24 10:49:23.514 UTC [30787][postmaster] LOG:  database system is shut down
2024-03-24 10:49:23.562 UTC [30845][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-24 10:49:23.562 UTC [30845][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:23.562 UTC [30845][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:23.577 UTC [30850][startup] LOG:  database system was shut down at 2024-03-24 10:49:23 UTC
2024-03-24 10:49:23.587 UTC [30845][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:23.649 UTC [30872][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51676
2024-03-24 10:49:23.656 UTC [30872][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-24 10:49:23.656 UTC [30872][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-24 10:49:23.672 UTC [30872][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-24 10:49:23.678 UTC [30872][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=51676
2024-03-24 10:49:23.704 UTC [30882][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51678
2024-03-24 10:49:23.710 UTC [30882][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-24 10:49:23.710 UTC [30882][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-24 10:49:23.712 UTC [30882][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-24 10:49:23.715 UTC [30882][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=51678
2024-03-24 10:49:23.733 UTC [30888][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51684
2024-03-24 10:49:23.739 UTC [30888][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-24 10:49:23.739 UTC [30888][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-24 10:49:23.741 UTC [30888][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-24 10:49:23.744 UTC [30888][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=51684
2024-03-24 10:49:23.756 UTC [30845][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:23.756 UTC [30845][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:23.761 UTC [30845][postmaster] LOG:  background worker "logical replication launcher" (PID 30860) exited with exit code 1
2024-03-24 10:49:23.766 UTC [30848][checkpointer] LOG:  shutting down
2024-03-24 10:49:23.766 UTC [30848][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:23.766 UTC [30848][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/414F718, redo lsn=0/414F718
2024-03-24 10:49:23.772 UTC [30845][postmaster] LOG:  database system is shut down
2024-03-24 10:49:24.008 UTC [30933][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-24 10:49:24.008 UTC [30933][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:24.008 UTC [30933][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:24.052 UTC [30942][startup] LOG:  database system was shut down at 2024-03-24 10:49:23 UTC
2024-03-24 10:49:24.092 UTC [30933][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:24.189 UTC [30953][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51690
2024-03-24 10:49:24.221 UTC [30953][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-24 10:49:24.221 UTC [30953][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-24 10:49:24.256 UTC [30953][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-24 10:49:24.264 UTC [30953][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.076 user=ssltestuser database=trustdb host=localhost port=51690
2024-03-24 10:49:24.303 UTC [30975][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51702
2024-03-24 10:49:24.344 UTC [30933][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:24.344 UTC [30933][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:24.352 UTC [30933][postmaster] LOG:  background worker "logical replication launcher" (PID 30948) exited with exit code 1
2024-03-24 10:49:24.352 UTC [30940][checkpointer] LOG:  shutting down
2024-03-24 10:49:24.352 UTC [30940][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:24.353 UTC [30940][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/414F790, redo lsn=0/414F790
2024-03-24 10:49:24.359 UTC [30933][postmaster] LOG:  database system is shut down
2024-03-24 10:49:24.553 UTC [30990][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-24 10:49:24.554 UTC [30990][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:24.554 UTC [30990][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:24.624 UTC [31000][startup] LOG:  database system was shut down at 2024-03-24 10:49:24 UTC
2024-03-24 10:49:24.630 UTC [30990][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:24.710 UTC [31007][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51706
2024-03-24 10:49:24.725 UTC [31007][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-24 10:49:24.809 UTC [31017][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51710
2024-03-24 10:49:24.822 UTC [31017][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-24 10:49:24.822 UTC [31017][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-24 10:49:24.846 UTC [31017][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-24 10:49:24.852 UTC [31017][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.047 user=ssltestuser database=trustdb host=localhost port=51710
2024-03-24 10:49:24.874 UTC [31027][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51714
2024-03-24 10:49:24.916 UTC [30990][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:24.916 UTC [30990][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:24.927 UTC [30990][postmaster] LOG:  background worker "logical replication launcher" (PID 31003) exited with exit code 1
2024-03-24 10:49:24.929 UTC [30994][checkpointer] LOG:  shutting down
2024-03-24 10:49:24.929 UTC [30994][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:24.930 UTC [30994][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/414F808, redo lsn=0/414F808
2024-03-24 10:49:24.936 UTC [30990][postmaster] LOG:  database system is shut down
2024-03-24 10:49:25.011 UTC [31037][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-24 10:49:25.011 UTC [31037][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:25.011 UTC [31037][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:25.033 UTC [31050][startup] LOG:  database system was shut down at 2024-03-24 10:49:24 UTC
2024-03-24 10:49:25.040 UTC [31037][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:25.097 UTC [31060][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51722
2024-03-24 10:49:25.105 UTC [31060][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-24 10:49:25.105 UTC [31060][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-24 10:49:25.145 UTC [31060][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-24 10:49:25.150 UTC [31060][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.061 user=ssltestuser database=trustdb host=localhost port=51722
2024-03-24 10:49:25.185 UTC [31076][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51728
2024-03-24 10:49:25.204 UTC [31076][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-24 10:49:25.266 UTC [31086][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51740
2024-03-24 10:49:25.272 UTC [31086][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-24 10:49:25.318 UTC [31090][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51754
2024-03-24 10:49:25.326 UTC [31090][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-24 10:49:25.326 UTC [31090][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-24 10:49:25.345 UTC [31090][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-24 10:49:25.352 UTC [31090][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=trustdb host=localhost port=51754
2024-03-24 10:49:25.398 UTC [31097][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51770
2024-03-24 10:49:25.411 UTC [31097][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-24 10:49:25.411 UTC [31097][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-24 10:49:25.414 UTC [31097][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-24 10:49:25.415 UTC [31097][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=51770
2024-03-24 10:49:25.553 UTC [31108][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51780
2024-03-24 10:49:25.575 UTC [31108][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-24 10:49:25.610 UTC [31112][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51782
2024-03-24 10:49:25.624 UTC [31112][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-24 10:49:25.624 UTC [31112][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-24 10:49:25.647 UTC [31112][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_t4nG/client.key$$
2024-03-24 10:49:25.648 UTC [31112][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=certdb host=localhost port=51782
2024-03-24 10:49:25.688 UTC [31117][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51784
2024-03-24 10:49:25.697 UTC [31117][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-24 10:49:25.697 UTC [31117][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-24 10:49:25.702 UTC [31117][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_t4nG/client-der.key$$
2024-03-24 10:49:25.703 UTC [31117][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=certdb host=localhost port=51784
2024-03-24 10:49:25.744 UTC [31124][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51798
2024-03-24 10:49:25.760 UTC [31124][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-24 10:49:25.760 UTC [31124][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-24 10:49:25.764 UTC [31124][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_t4nG/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-24 10:49:25.767 UTC [31124][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=51798
2024-03-24 10:49:25.798 UTC [31132][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51814
2024-03-24 10:49:25.806 UTC [31132][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-24 10:49:25.806 UTC [31132][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-24 10:49:25.808 UTC [31132][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_t4nG/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-24 10:49:25.812 UTC [31132][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=certdb host=localhost port=51814
2024-03-24 10:49:25.850 UTC [31139][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51820
2024-03-24 10:49:25.861 UTC [31139][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-24 10:49:25.861 UTC [31139][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-24 10:49:25.865 UTC [31139][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_t4nG/client.key$$
2024-03-24 10:49:25.870 UTC [31139][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb host=localhost port=51820
2024-03-24 10:49:25.921 UTC [31148][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51836
2024-03-24 10:49:25.939 UTC [31148][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-24 10:49:25.939 UTC [31148][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-24 10:49:25.942 UTC [31148][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_t4nG/client.key$$
2024-03-24 10:49:25.943 UTC [31148][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=51836
2024-03-24 10:49:25.977 UTC [31157][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51844
2024-03-24 10:49:25.997 UTC [31157][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-24 10:49:26.035 UTC [31165][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51858
2024-03-24 10:49:26.041 UTC [31165][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:26.081 UTC [31169][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51868
2024-03-24 10:49:26.090 UTC [31169][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-24 10:49:26.090 UTC [31169][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-24 10:49:26.124 UTC [31169][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_t4nG/client-dn.key$$
2024-03-24 10:49:26.130 UTC [31169][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.055 user=ssltestuser database=certdb_dn host=localhost port=51868
2024-03-24 10:49:26.147 UTC [31184][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51878
2024-03-24 10:49:26.157 UTC [31184][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-24 10:49:26.157 UTC [31184][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-24 10:49:26.171 UTC [31184][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_t4nG/client-dn.key$$
2024-03-24 10:49:26.175 UTC [31184][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=certdb_dn_re host=localhost port=51878
2024-03-24 10:49:26.194 UTC [31194][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51890
2024-03-24 10:49:26.202 UTC [31194][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-24 10:49:26.202 UTC [31194][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-24 10:49:26.222 UTC [31194][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_t4nG/client-dn.key$$
2024-03-24 10:49:26.227 UTC [31194][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=certdb_cn host=localhost port=51890
2024-03-24 10:49:26.277 UTC [31208][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51892
2024-03-24 10:49:26.292 UTC [31208][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-24 10:49:26.292 UTC [31208][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-24 10:49:26.294 UTC [31208][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-24 10:49:26.303 UTC [31208][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=certdb host=localhost port=51892
2024-03-24 10:49:26.318 UTC [31213][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51894
2024-03-24 10:49:26.333 UTC [31213][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:26.350 UTC [31216][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51910
2024-03-24 10:49:26.367 UTC [31216][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-24 10:49:26.368 UTC [31216][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-24 10:49:26.368 UTC [31216][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-24 10:49:26.368 UTC [31216][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-24 10:49:26.402 UTC [31227][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51922
2024-03-24 10:49:26.418 UTC [31227][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-24 10:49:26.418 UTC [31227][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-24 10:49:26.448 UTC [31237][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51930
2024-03-24 10:49:26.466 UTC [31237][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-24 10:49:26.466 UTC [31237][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-24 10:49:26.486 UTC [31237][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_t4nG/client.key$$
2024-03-24 10:49:26.492 UTC [31237][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=verifydb host=localhost port=51930
2024-03-24 10:49:26.518 UTC [31264][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51932
2024-03-24 10:49:26.527 UTC [31264][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-24 10:49:26.527 UTC [31264][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-24 10:49:26.527 UTC [31264][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-24 10:49:26.527 UTC [31264][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-24 10:49:26.567 UTC [31272][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51948
2024-03-24 10:49:26.582 UTC [31272][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-24 10:49:26.582 UTC [31272][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-24 10:49:26.584 UTC [31272][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_t4nG/client.key$$
2024-03-24 10:49:26.584 UTC [31272][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=yetanotheruser database=verifydb host=localhost port=51948
2024-03-24 10:49:26.595 UTC [31037][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:26.595 UTC [31037][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:26.605 UTC [31037][postmaster] LOG:  background worker "logical replication launcher" (PID 31056) exited with exit code 1
2024-03-24 10:49:26.605 UTC [31048][checkpointer] LOG:  shutting down
2024-03-24 10:49:26.605 UTC [31048][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:26.606 UTC [31048][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/414F880, redo lsn=0/414F880
2024-03-24 10:49:26.611 UTC [31037][postmaster] LOG:  database system is shut down
2024-03-24 10:49:26.770 UTC [31304][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-24 10:49:26.770 UTC [31304][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:26.770 UTC [31304][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:26.794 UTC [31321][startup] LOG:  database system was shut down at 2024-03-24 10:49:26 UTC
2024-03-24 10:49:26.801 UTC [31304][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:26.928 UTC [31340][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51964
2024-03-24 10:49:26.951 UTC [31340][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-24 10:49:26.951 UTC [31340][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-24 10:49:26.988 UTC [31340][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_t4nG/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-24 10:49:26.995 UTC [31340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.067 user=ssltestuser database=certdb host=localhost port=51964
2024-03-24 10:49:27.042 UTC [31357][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51970
2024-03-24 10:49:27.054 UTC [31357][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-24 10:49:27.054 UTC [31357][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-24 10:49:27.114 UTC [31367][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51974
2024-03-24 10:49:27.143 UTC [31367][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-24 10:49:27.143 UTC [31367][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-24 10:49:27.163 UTC [31304][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:27.164 UTC [31304][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:27.172 UTC [31304][postmaster] LOG:  background worker "logical replication launcher" (PID 31325) exited with exit code 1
2024-03-24 10:49:27.172 UTC [31318][checkpointer] LOG:  shutting down
2024-03-24 10:49:27.172 UTC [31318][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:27.173 UTC [31318][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/414F8F8, redo lsn=0/414F8F8
2024-03-24 10:49:27.179 UTC [31304][postmaster] LOG:  database system is shut down
2024-03-24 10:49:27.311 UTC [31380][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-24 10:49:27.311 UTC [31380][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:27.311 UTC [31380][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:27.347 UTC [31383][startup] LOG:  database system was shut down at 2024-03-24 10:49:27 UTC
2024-03-24 10:49:27.355 UTC [31380][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:27.393 UTC [31394][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51986
2024-03-24 10:49:27.419 UTC [31394][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-24 10:49:27.419 UTC [31394][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-24 10:49:27.442 UTC [31380][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:27.442 UTC [31380][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:27.447 UTC [31380][postmaster] LOG:  background worker "logical replication launcher" (PID 31388) exited with exit code 1
2024-03-24 10:49:27.450 UTC [31381][checkpointer] LOG:  shutting down
2024-03-24 10:49:27.450 UTC [31381][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:27.450 UTC [31381][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/414F970, redo lsn=0/414F970
2024-03-24 10:49:27.456 UTC [31380][postmaster] LOG:  database system is shut down
2024-03-24 10:49:27.641 UTC [31416][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-24 10:49:27.641 UTC [31416][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:27.641 UTC [31416][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:27.671 UTC [31424][startup] LOG:  database system was shut down at 2024-03-24 10:49:27 UTC
2024-03-24 10:49:27.679 UTC [31416][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:27.789 UTC [31434][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51998
2024-03-24 10:49:27.815 UTC [31434][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-24 10:49:27.815 UTC [31434][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-24 10:49:27.869 UTC [31444][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52010
2024-03-24 10:49:27.902 UTC [31444][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-24 10:49:27.902 UTC [31444][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-24 10:49:27.923 UTC [31416][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:27.923 UTC [31416][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:27.929 UTC [31416][postmaster] LOG:  background worker "logical replication launcher" (PID 31428) exited with exit code 1
2024-03-24 10:49:27.930 UTC [31421][checkpointer] LOG:  shutting down
2024-03-24 10:49:27.930 UTC [31421][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:27.931 UTC [31421][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/414F9E8, redo lsn=0/414F9E8
2024-03-24 10:49:27.936 UTC [31416][postmaster] LOG:  database system is shut down
2024-03-24 10:49:28.000 UTC [31451][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-24 10:49:28.000 UTC [31451][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:28.000 UTC [31451][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:28.026 UTC [31463][startup] LOG:  database system was shut down at 2024-03-24 10:49:27 UTC
2024-03-24 10:49:28.035 UTC [31451][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:28.073 UTC [31471][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52016
2024-03-24 10:49:28.076 UTC [31471][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:28.076 UTC [31471][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-24 10:49:28.105 UTC [31478][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52020
2024-03-24 10:49:28.112 UTC [31478][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-24 10:49:28.112 UTC [31478][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-24 10:49:28.125 UTC [31478][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-24 10:49:28.126 UTC [31478][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=52020
2024-03-24 10:49:28.154 UTC [31451][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:28.154 UTC [31451][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:28.160 UTC [31451][postmaster] LOG:  background worker "logical replication launcher" (PID 31468) exited with exit code 1
2024-03-24 10:49:28.160 UTC [31461][checkpointer] LOG:  shutting down
2024-03-24 10:49:28.160 UTC [31461][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:28.236 UTC [31461][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.076 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FA60, redo lsn=0/414FA60
2024-03-24 10:49:28.314 UTC [31451][postmaster] LOG:  database system is shut down
2024-03-24 10:49:28.440 UTC [31513][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-24 10:49:28.440 UTC [31513][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:28.440 UTC [31513][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:28.467 UTC [31516][startup] LOG:  database system was shut down at 2024-03-24 10:49:28 UTC
2024-03-24 10:49:28.477 UTC [31513][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:28.539 UTC [31529][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52028
2024-03-24 10:49:28.546 UTC [31529][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:28.547 UTC [31529][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:28.553 UTC [31529][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-24 10:49:28.586 UTC [31536][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52030
2024-03-24 10:49:28.598 UTC [31536][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-24 10:49:28.598 UTC [31536][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-24 10:49:28.611 UTC [31536][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-24 10:49:28.616 UTC [31536][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=52030
2024-03-24 10:49:28.628 UTC [31513][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:28.628 UTC [31513][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:28.634 UTC [31513][postmaster] LOG:  background worker "logical replication launcher" (PID 31520) exited with exit code 1
2024-03-24 10:49:28.634 UTC [31514][checkpointer] LOG:  shutting down
2024-03-24 10:49:28.634 UTC [31514][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:28.635 UTC [31514][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/414FAD8, redo lsn=0/414FAD8
2024-03-24 10:49:28.642 UTC [31513][postmaster] LOG:  database system is shut down
2024-03-24 10:49:28.779 UTC [31551][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-24 10:49:28.791 UTC [31551][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:28.792 UTC [31551][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:28.802 UTC [31565][startup] LOG:  database system was shut down at 2024-03-24 10:49:28 UTC
2024-03-24 10:49:28.811 UTC [31551][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:28.869 UTC [31583][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37528
2024-03-24 10:49:28.876 UTC [31583][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:28.876 UTC [31583][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-24 10:49:28.917 UTC [31591][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37532
2024-03-24 10:49:28.927 UTC [31591][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-24 10:49:28.927 UTC [31591][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-24 10:49:28.971 UTC [31591][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-24 10:49:28.977 UTC [31591][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.066 user=ssltestuser database=trustdb host=localhost port=37532
2024-03-24 10:49:28.993 UTC [31551][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:28.993 UTC [31551][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:29.005 UTC [31551][postmaster] LOG:  background worker "logical replication launcher" (PID 31571) exited with exit code 1
2024-03-24 10:49:29.007 UTC [31563][checkpointer] LOG:  shutting down
2024-03-24 10:49:29.007 UTC [31563][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:29.008 UTC [31563][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/414FB50, redo lsn=0/414FB50
2024-03-24 10:49:29.015 UTC [31551][postmaster] LOG:  database system is shut down
2024-03-24 10:49:29.154 UTC [31631][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-24 10:49:29.155 UTC [31631][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:29.155 UTC [31631][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:29.184 UTC [31645][startup] LOG:  database system was shut down at 2024-03-24 10:49:29 UTC
2024-03-24 10:49:29.195 UTC [31631][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:29.231 UTC [31652][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37548
2024-03-24 10:49:29.237 UTC [31652][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:29.237 UTC [31652][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-24 10:49:29.289 UTC [31656][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37558
2024-03-24 10:49:29.296 UTC [31656][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-24 10:49:29.296 UTC [31656][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-24 10:49:29.325 UTC [31656][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-24 10:49:29.331 UTC [31656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=37558
2024-03-24 10:49:29.343 UTC [31631][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:29.343 UTC [31631][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:29.348 UTC [31631][postmaster] LOG:  background worker "logical replication launcher" (PID 31649) exited with exit code 1
2024-03-24 10:49:29.352 UTC [31643][checkpointer] LOG:  shutting down
2024-03-24 10:49:29.352 UTC [31643][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:29.353 UTC [31643][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/414FBC8, redo lsn=0/414FBC8
2024-03-24 10:49:29.359 UTC [31631][postmaster] LOG:  database system is shut down
2024-03-24 10:49:29.510 UTC [31683][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-24 10:49:29.510 UTC [31683][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:29.510 UTC [31683][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:29.530 UTC [31695][startup] LOG:  database system was shut down at 2024-03-24 10:49:29 UTC
2024-03-24 10:49:29.545 UTC [31683][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:29.582 UTC [31707][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37566
2024-03-24 10:49:29.586 UTC [31707][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:29.586 UTC [31707][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-24 10:49:29.639 UTC [31718][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37568
2024-03-24 10:49:29.655 UTC [31718][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-24 10:49:29.655 UTC [31718][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-24 10:49:29.699 UTC [31718][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-24 10:49:29.704 UTC [31718][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.071 user=ssltestuser database=trustdb host=localhost port=37568
2024-03-24 10:49:29.711 UTC [31683][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:29.711 UTC [31683][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:29.716 UTC [31683][postmaster] LOG:  background worker "logical replication launcher" (PID 31700) exited with exit code 1
2024-03-24 10:49:29.720 UTC [31692][checkpointer] LOG:  shutting down
2024-03-24 10:49:29.720 UTC [31692][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:29.720 UTC [31692][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/414FC40, redo lsn=0/414FC40
2024-03-24 10:49:29.726 UTC [31683][postmaster] LOG:  database system is shut down
2024-03-24 10:49:29.868 UTC [31739][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-24 10:49:29.868 UTC [31739][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:29.868 UTC [31739][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:29.885 UTC [31755][startup] LOG:  database system was shut down at 2024-03-24 10:49:29 UTC
2024-03-24 10:49:29.895 UTC [31739][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:29.948 UTC [31769][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37576
2024-03-24 10:49:29.953 UTC [31769][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:29.954 UTC [31769][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:29.959 UTC [31769][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:29.982 UTC [31775][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37588
2024-03-24 10:49:29.988 UTC [31775][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-24 10:49:29.988 UTC [31775][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-24 10:49:30.001 UTC [31775][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-24 10:49:30.007 UTC [31775][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=37588
2024-03-24 10:49:30.015 UTC [31739][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:30.015 UTC [31739][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:30.024 UTC [31739][postmaster] LOG:  background worker "logical replication launcher" (PID 31761) exited with exit code 1
2024-03-24 10:49:30.028 UTC [31752][checkpointer] LOG:  shutting down
2024-03-24 10:49:30.028 UTC [31752][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:30.029 UTC [31752][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/414FCB8, redo lsn=0/414FCB8
2024-03-24 10:49:30.034 UTC [31739][postmaster] LOG:  database system is shut down
2024-03-24 10:49:30.171 UTC [31789][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-24 10:49:30.171 UTC [31789][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:30.171 UTC [31789][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:30.186 UTC [31807][startup] LOG:  database system was shut down at 2024-03-24 10:49:30 UTC
2024-03-24 10:49:30.201 UTC [31789][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:30.246 UTC [31819][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37600
2024-03-24 10:49:30.248 UTC [31819][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:30.248 UTC [31819][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:30.256 UTC [31819][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:30.286 UTC [31826][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37614
2024-03-24 10:49:30.296 UTC [31826][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-24 10:49:30.296 UTC [31826][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-24 10:49:30.324 UTC [31826][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-24 10:49:30.330 UTC [31826][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=37614
2024-03-24 10:49:30.345 UTC [31789][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:30.345 UTC [31789][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:30.349 UTC [31789][postmaster] LOG:  background worker "logical replication launcher" (PID 31815) exited with exit code 1
2024-03-24 10:49:30.354 UTC [31805][checkpointer] LOG:  shutting down
2024-03-24 10:49:30.354 UTC [31805][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:30.354 UTC [31805][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/414FD30, redo lsn=0/414FD30
2024-03-24 10:49:30.361 UTC [31789][postmaster] LOG:  database system is shut down
2024-03-24 10:49:30.500 UTC [31844][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-24 10:49:30.500 UTC [31844][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:30.500 UTC [31844][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:30.526 UTC [31860][startup] LOG:  database system was shut down at 2024-03-24 10:49:30 UTC
2024-03-24 10:49:30.534 UTC [31844][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:30.578 UTC [31887][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37622
2024-03-24 10:49:30.580 UTC [31887][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:30.581 UTC [31887][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:30.586 UTC [31887][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-24 10:49:30.609 UTC [31894][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37634
2024-03-24 10:49:30.620 UTC [31894][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-24 10:49:30.620 UTC [31894][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-24 10:49:30.637 UTC [31894][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-24 10:49:30.642 UTC [31894][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=localhost port=37634
2024-03-24 10:49:30.651 UTC [31844][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:30.651 UTC [31844][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:30.656 UTC [31844][postmaster] LOG:  background worker "logical replication launcher" (PID 31873) exited with exit code 1
2024-03-24 10:49:30.658 UTC [31857][checkpointer] LOG:  shutting down
2024-03-24 10:49:30.659 UTC [31857][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:30.659 UTC [31857][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/414FDA8, redo lsn=0/414FDA8
2024-03-24 10:49:30.666 UTC [31844][postmaster] LOG:  database system is shut down
2024-03-24 10:49:30.811 UTC [31919][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-24 10:49:30.811 UTC [31919][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57378
2024-03-24 10:49:30.812 UTC [31919][postmaster] LOG:  listening on Unix socket "/tmp/jOxIR0rWH5/.s.PGSQL.57378"
2024-03-24 10:49:30.850 UTC [31935][startup] LOG:  database system was shut down at 2024-03-24 10:49:30 UTC
2024-03-24 10:49:30.858 UTC [31919][postmaster] LOG:  database system is ready to accept connections
2024-03-24 10:49:30.997 UTC [31969][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37646
2024-03-24 10:49:31.005 UTC [31969][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-24 10:49:31.005 UTC [31969][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-24 10:49:31.041 UTC [31977][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37650
2024-03-24 10:49:31.060 UTC [31977][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-24 10:49:31.060 UTC [31977][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-24 10:49:31.085 UTC [31977][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-24 10:49:31.092 UTC [31977][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.059 user=ssltestuser database=trustdb host=localhost port=37650
2024-03-24 10:49:31.100 UTC [31919][postmaster] LOG:  received fast shutdown request
2024-03-24 10:49:31.100 UTC [31919][postmaster] LOG:  aborting any active transactions
2024-03-24 10:49:31.105 UTC [31919][postmaster] LOG:  background worker "logical replication launcher" (PID 31940) exited with exit code 1
2024-03-24 10:49:31.110 UTC [31932][checkpointer] LOG:  shutting down
2024-03-24 10:49:31.110 UTC [31932][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-24 10:49:31.110 UTC [31932][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/414FE20, redo lsn=0/414FE20
2024-03-24 10:49:31.117 UTC [31919][postmaster] LOG:  database system is shut down
2024-03-24 10:49:31.236 UTC [32007][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-24 10:49:31.236 UTC [32007][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[10:49:31.309](0.219s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"