[03:21:49.795](0.089s) # setting up data directory
# Checking port 51207
# Found port 51207
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=51207 host=/tmp/T7wIDMI4Jw
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[03:21:49.817](0.022s) # 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 33717
[03:21:50.345](0.528s) 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 33937
[03:21:51.744](1.399s) # 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-04-04 03:21:50.119 UTC [33717][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-04-04 03:21:50.119 UTC [33717][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:50.158 UTC [33730][startup] LOG:  database system was shut down at 2024-04-04 03:19:29 UTC
2024-04-04 03:21:50.177 UTC [33717][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:50.318 UTC [33766][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.318 UTC [33766][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-04-04 03:21:50.318 UTC [33766][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.342 UTC [33766][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-04 03:21:50.346 UTC [33766][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.395 UTC [33773][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.395 UTC [33773][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-04-04 03:21:50.395 UTC [33773][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.399 UTC [33773][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-04 03:21:50.401 UTC [33773][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.501 UTC [33785][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.504 UTC [33785][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-04-04 03:21:50.504 UTC [33785][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.508 UTC [33785][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-04 03:21:50.511 UTC [33785][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.547 UTC [33789][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.548 UTC [33789][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-04-04 03:21:50.548 UTC [33789][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.549 UTC [33789][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-04 03:21:50.551 UTC [33789][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.602 UTC [33796][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.607 UTC [33796][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-04-04 03:21:50.607 UTC [33796][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.610 UTC [33796][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-04 03:21:50.612 UTC [33796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.639 UTC [33802][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.640 UTC [33802][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-04-04 03:21:50.640 UTC [33802][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.648 UTC [33802][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-04 03:21:50.743 UTC [33802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.105 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.778 UTC [33817][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.779 UTC [33817][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-04-04 03:21:50.779 UTC [33817][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.780 UTC [33817][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-04 03:21:50.863 UTC [33817][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.917 UTC [33836][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.924 UTC [33836][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-04-04 03:21:50.924 UTC [33836][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.936 UTC [33836][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-04 03:21:50.996 UTC [33836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.084 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.042 UTC [33852][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.043 UTC [33852][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-04-04 03:21:51.043 UTC [33852][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.053 UTC [33852][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-04 03:21:51.121 UTC [33852][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.173 UTC [33866][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.174 UTC [33866][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-04-04 03:21:51.174 UTC [33866][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.179 UTC [33866][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-04 03:21:51.251 UTC [33866][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.078 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.317 UTC [33884][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.318 UTC [33884][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-04-04 03:21:51.318 UTC [33884][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.320 UTC [33884][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-04 03:21:51.417 UTC [33884][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.107 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.432 UTC [33717][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:51.432 UTC [33717][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:51.441 UTC [33717][postmaster] LOG:  background worker "logical replication launcher" (PID 33741) exited with exit code 1
2024-04-04 03:21:51.442 UTC [33724][checkpointer] LOG:  shutting down
2024-04-04 03:21:51.442 UTC [33724][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:51.576 UTC [33724][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.134 s, sync=0.001 s, total=0.135 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414CC38, redo lsn=0/414CC38
2024-04-04 03:21:51.603 UTC [33717][postmaster] LOG:  database system is shut down
2024-04-04 03:21:51.690 UTC [33937][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-04-04 03:21:51.690 UTC [33937][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:51.690 UTC [33937][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:51.706 UTC [33953][startup] LOG:  database system was shut down at 2024-04-04 03:21:51 UTC
2024-04-04 03:21:51.714 UTC [33937][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:51.752 UTC [33937][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:51.752 UTC [33937][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:51.756 UTC [33937][postmaster] LOG:  background worker "logical replication launcher" (PID 33959) exited with exit code 1
2024-04-04 03:21:51.759 UTC [33951][checkpointer] LOG:  shutting down
2024-04-04 03:21:51.759 UTC [33951][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:51.760 UTC [33951][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/414CCB0, redo lsn=0/414CCB0
2024-04-04 03:21:51.765 UTC [33937][postmaster] LOG:  database system is shut down
2024-04-04 03:21:51.921 UTC [33984][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-04 03:21:51.921 UTC [33984][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[03:21:51.956](0.213s) 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 33999
[03:21:52.164](0.207s) 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-04-04 03:21:50.119 UTC [33717][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-04-04 03:21:50.119 UTC [33717][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:50.158 UTC [33730][startup] LOG:  database system was shut down at 2024-04-04 03:19:29 UTC
2024-04-04 03:21:50.177 UTC [33717][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:50.318 UTC [33766][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.318 UTC [33766][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-04-04 03:21:50.318 UTC [33766][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.342 UTC [33766][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-04 03:21:50.346 UTC [33766][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.395 UTC [33773][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.395 UTC [33773][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-04-04 03:21:50.395 UTC [33773][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.399 UTC [33773][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-04 03:21:50.401 UTC [33773][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.501 UTC [33785][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.504 UTC [33785][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-04-04 03:21:50.504 UTC [33785][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.508 UTC [33785][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-04 03:21:50.511 UTC [33785][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.547 UTC [33789][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.548 UTC [33789][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-04-04 03:21:50.548 UTC [33789][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.549 UTC [33789][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-04 03:21:50.551 UTC [33789][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.602 UTC [33796][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.607 UTC [33796][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-04-04 03:21:50.607 UTC [33796][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.610 UTC [33796][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-04 03:21:50.612 UTC [33796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.639 UTC [33802][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.640 UTC [33802][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-04-04 03:21:50.640 UTC [33802][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.648 UTC [33802][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-04 03:21:50.743 UTC [33802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.105 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.778 UTC [33817][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.779 UTC [33817][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-04-04 03:21:50.779 UTC [33817][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.780 UTC [33817][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-04 03:21:50.863 UTC [33817][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.917 UTC [33836][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.924 UTC [33836][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-04-04 03:21:50.924 UTC [33836][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.936 UTC [33836][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-04 03:21:50.996 UTC [33836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.084 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.042 UTC [33852][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.043 UTC [33852][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-04-04 03:21:51.043 UTC [33852][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.053 UTC [33852][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-04 03:21:51.121 UTC [33852][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.173 UTC [33866][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.174 UTC [33866][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-04-04 03:21:51.174 UTC [33866][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.179 UTC [33866][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-04 03:21:51.251 UTC [33866][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.078 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.317 UTC [33884][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.318 UTC [33884][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-04-04 03:21:51.318 UTC [33884][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.320 UTC [33884][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-04 03:21:51.417 UTC [33884][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.107 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.432 UTC [33717][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:51.432 UTC [33717][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:51.441 UTC [33717][postmaster] LOG:  background worker "logical replication launcher" (PID 33741) exited with exit code 1
2024-04-04 03:21:51.442 UTC [33724][checkpointer] LOG:  shutting down
2024-04-04 03:21:51.442 UTC [33724][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:51.576 UTC [33724][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.134 s, sync=0.001 s, total=0.135 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414CC38, redo lsn=0/414CC38
2024-04-04 03:21:51.603 UTC [33717][postmaster] LOG:  database system is shut down
2024-04-04 03:21:51.690 UTC [33937][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-04-04 03:21:51.690 UTC [33937][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:51.690 UTC [33937][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:51.706 UTC [33953][startup] LOG:  database system was shut down at 2024-04-04 03:21:51 UTC
2024-04-04 03:21:51.714 UTC [33937][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:51.752 UTC [33937][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:51.752 UTC [33937][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:51.756 UTC [33937][postmaster] LOG:  background worker "logical replication launcher" (PID 33959) exited with exit code 1
2024-04-04 03:21:51.759 UTC [33951][checkpointer] LOG:  shutting down
2024-04-04 03:21:51.759 UTC [33951][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:51.760 UTC [33951][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/414CCB0, redo lsn=0/414CCB0
2024-04-04 03:21:51.765 UTC [33937][postmaster] LOG:  database system is shut down
2024-04-04 03:21:51.921 UTC [33984][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-04 03:21:51.921 UTC [33984][postmaster] LOG:  database system is shut down
2024-04-04 03:21:52.031 UTC [33999][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-04-04 03:21:52.031 UTC [33999][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:52.031 UTC [33999][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:52.062 UTC [34011][startup] LOG:  database system was shut down at 2024-04-04 03:21:51 UTC
2024-04-04 03:21:52.077 UTC [33999][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:52.173 UTC [33999][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:52.173 UTC [33999][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:52.177 UTC [33999][postmaster] LOG:  background worker "logical replication launcher" (PID 34017) exited with exit code 1
2024-04-04 03:21:52.182 UTC [34008][checkpointer] LOG:  shutting down
2024-04-04 03:21:52.182 UTC [34008][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:52.183 UTC [34008][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/414CD28, redo lsn=0/414CD28
2024-04-04 03:21:52.189 UTC [33999][postmaster] LOG:  database system is shut down
2024-04-04 03:21:52.398 UTC [34089][postmaster] FATAL:  could not set SSL protocol version range
2024-04-04 03:21:52.398 UTC [34089][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-04-04 03:21:52.398 UTC [34089][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[03:21:52.475](0.312s) 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 34113
[03:21:52.696](0.221s) ok 5 - restart succeeds with correct SSL protocol bounds
[03:21:52.696](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 34147
[03:21:53.094](0.398s) ok 6 - server doesn't accept non-SSL connections
[03:21:53.095](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[03:21:53.215](0.120s) ok 8 - connect without server root cert sslmode=require
[03:21:53.215](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[03:21:53.275](0.060s) ok 10 - connect without server root cert sslmode=verify-ca
[03:21:53.276](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[03:21:53.322](0.046s) ok 12 - connect without server root cert sslmode=verify-full
[03:21:53.322](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[03:21:53.379](0.057s) ok 14 - connect with wrong server root cert sslmode=require
[03:21:53.379](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[03:21:53.419](0.039s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[03:21:53.419](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[03:21:53.458](0.039s) ok 18 - connect with wrong server root cert sslmode=verify-full
[03:21:53.458](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[03:21:53.503](0.045s) ok 20 - connect with server CA cert, without root CA
[03:21:53.503](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[03:21:53.559](0.056s) ok 22 - connect with correct server CA cert file sslmode=require
[03:21:53.559](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[03:21:53.609](0.049s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[03:21:53.609](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[03:21:53.664](0.055s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[03:21:53.664](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[03:21:53.728](0.064s) ok 28 - cert root file that contains two certificates, order 1
[03:21:53.729](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[03:21:53.793](0.065s) ok 30 - cert root file that contains two certificates, order 2
[03:21:53.793](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[03:21:53.866](0.073s) ok 32 - connect with sslcertmode=disable
[03:21:53.866](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[03:21:53.958](0.092s) ok 34 - connect with sslcertmode=allow
[03:21:53.959](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[03:21:54.017](0.059s) ok 36 - connect with sslcertmode=require fails without a client certificate
[03:21:54.017](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[03:21:54.090](0.073s) ok 38 - sslcrl option with invalid file name
[03:21:54.091](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[03:21:54.156](0.065s) ok 40 - CRL belonging to a different CA
[03:21:54.156](0.000s) ok 41 - CRL belonging to a different CA: matches
[03:21:54.254](0.098s) ok 42 - directory CRL belonging to a different CA
[03:21:54.254](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[03:21:54.366](0.112s) ok 44 - CRL with a non-revoked cert
[03:21:54.366](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[03:21:54.444](0.078s) ok 46 - directory CRL with a non-revoked cert
[03:21:54.444](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[03:21:54.524](0.080s) ok 48 - mismatch between host name and server certificate sslmode=require
[03:21:54.524](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[03:21:54.571](0.047s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[03:21:54.571](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[03:21:54.637](0.066s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[03:21:54.638](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 34454
[03:21:54.963](0.326s) ok 54 - IP address in the Common Name
[03:21:54.964](0.000s) ok 55 - IP address in the Common Name: no stderr
[03:21:55.038](0.074s) ok 56 - mismatch between host name and server certificate IP address
[03:21:55.038](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 34530
[03:21:55.289](0.251s) ok 58 - IP address in a dNSName
[03:21:55.290](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 34581
[03:21:55.701](0.411s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[03:21:55.701](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[03:21:55.768](0.067s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[03:21:55.768](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[03:21:55.825](0.057s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[03:21:55.825](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[03:21:55.902](0.077s) ok 66 - host name not matching with X.509 Subject Alternative Names
[03:21:55.902](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[03:21:55.961](0.059s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[03:21:55.961](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 34674
[03:21:56.399](0.438s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[03:21:56.399](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[03:21:56.484](0.084s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[03:21:56.484](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[03:21:57.026](0.542s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[03:21:57.026](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 34827
[03:21:57.329](0.303s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[03:21:57.329](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[03:21:57.379](0.049s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[03:21:57.379](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[03:21:57.431](0.052s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[03:21:57.431](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[03:21:57.471](0.040s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[03:21:57.471](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[03:21:57.512](0.041s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[03:21:57.512](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[03:21:57.553](0.040s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[03:21:57.553](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[03:21:57.615](0.062s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[03:21:57.615](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[03:21:57.661](0.046s) ok 90 - IPv6 host with CIDR mask does not match
[03:21:57.662](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 34987
[03:21:57.954](0.293s) ok 92 - certificate with both a CN and SANs 1
[03:21:57.954](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[03:21:57.999](0.044s) ok 94 - certificate with both a CN and SANs 2
[03:21:57.999](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[03:21:58.032](0.033s) ok 96 - certificate with both a CN and SANs ignores CN
[03:21:58.032](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 35075
[03:21:58.342](0.310s) ok 98 - certificate with both a CN and IP SANs matches CN
[03:21:58.342](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[03:21:58.398](0.055s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[03:21:58.398](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[03:21:58.422](0.024s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[03:21:58.422](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 35156
[03:21:58.685](0.263s) ok 104 - certificate with both an IP CN and IP SANs 1
[03:21:58.686](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[03:21:58.748](0.062s) ok 106 - certificate with both an IP CN and IP SANs 2
[03:21:58.748](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[03:21:58.788](0.040s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[03:21:58.788](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 35249
[03:21:59.077](0.289s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[03:21:59.077](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[03:21:59.125](0.047s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[03:21:59.125](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[03:21:59.163](0.038s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[03:21:59.163](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 35343
[03:21:59.541](0.378s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[03:21:59.542](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[03:21:59.601](0.060s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[03:21:59.602](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 35447
[03:21:59.870](0.268s) ok 120 - sslrootcert=system does not connect with private CA
[03:21:59.870](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[03:21:59.892](0.021s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[03:21:59.892](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[03:21:59.950](0.059s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[03:21:59.951](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[03:21:59.976](0.026s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[03:21:59.977](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 35510
[03:22:00.234](0.257s) ok 128 - connects without client-side CRL
[03:22:00.234](0.000s) ok 129 - connects without client-side CRL: no stderr
[03:22:00.274](0.040s) ok 130 - does not connect with client-side CRL file
[03:22:00.274](0.000s) ok 131 - does not connect with client-side CRL file: matches
[03:22:00.333](0.058s) ok 132 - does not connect with client-side CRL directory
[03:22:00.333](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()
[03:22:00.381](0.048s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[03:22:00.381](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[03:22:00.381](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[03:22:00.447](0.066s) ok 137 - connection success with correct range of TLS protocol versions
[03:22:00.448](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[03:22:00.479](0.031s) ok 139 - connection failure with incorrect range of TLS protocol versions
[03:22:00.479](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[03:22:00.498](0.019s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[03:22:00.499](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[03:22:00.514](0.015s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[03:22:00.514](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[03:22:00.515](0.000s) # running server tests
[03:22:00.545](0.031s) ok 145 - certificate authorization fails without client cert
[03:22:00.545](0.000s) ok 146 - certificate authorization fails without client cert: matches
[03:22:00.622](0.077s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[03:22:00.622](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[03:22:00.682](0.060s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[03:22:00.682](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[03:22:00.721](0.039s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[03:22:00.722](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[03:22:00.769](0.047s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[03:22:00.769](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[03:22:00.826](0.057s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[03:22:00.826](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[03:22:00.883](0.056s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[03:22:00.883](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[03:22:00.951](0.068s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[03:22:00.951](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[03:22:01.001](0.049s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[03:22:01.001](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[03:22:01.055](0.054s) ok 163 - certificate authorization succeeds with DN mapping
[03:22:01.055](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[03:22:01.055](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[03:22:01.099](0.044s) ok 166 - certificate authorization succeeds with DN regex mapping
[03:22:01.100](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[03:22:01.149](0.050s) ok 168 - certificate authorization succeeds with CN mapping
[03:22:01.150](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[03:22:01.150](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[03:22:01.150](0.000s) not ok 171 # TODO & SKIP Need Pty support
[03:22:01.150](0.000s) not ok 172 # TODO & SKIP Need Pty support
[03:22:01.150](0.000s) not ok 173 # TODO & SKIP Need Pty support
[03:22:01.151](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_3LMB/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[03:22:01.207](0.057s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[03:22:01.207](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[03:22:01.208](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[03:22:01.241](0.033s) ok 178 - certificate authorization fails because of file permissions
[03:22:01.241](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[03:22:01.288](0.048s) ok 180 - certificate authorization fails with client cert belonging to another user
[03:22:01.289](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[03:22:01.333](0.044s) ok 182 - certificate authorization fails with revoked client cert
[03:22:01.333](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[03:22:01.333](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[03:22:01.392](0.059s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[03:22:01.392](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[03:22:01.392](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[03:22:01.441](0.049s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[03:22:01.441](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[03:22:01.442](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[03:22:01.489](0.047s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[03:22:01.489](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[03:22:01.489](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 35861
[03:22:01.755](0.265s) ok 194 - intermediate client certificate is provided by client
[03:22:01.755](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[03:22:01.780](0.025s) ok 196 - intermediate client certificate is missing
[03:22:01.780](0.000s) ok 197 - intermediate client certificate is missing: matches
[03:22:01.810](0.030s) ok 198 - logged client certificate Subjects are truncated if they're too long
[03:22:01.810](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 35917
[03:22:02.061](0.251s) ok 200 - intermediate client certificate is untrusted
[03:22:02.061](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 35959
[03:22:02.325](0.264s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[03:22:02.325](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[03:22:02.383](0.058s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[03:22:02.383](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 36013
[03:22:02.649](0.266s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[03:22:02.649](0.000s) 
[03:22:02.649](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[03:22:02.650](0.000s) #          got: '2'
#     expected: '0'
[03:22:02.650](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[03:22:02.650](0.000s) 
[03:22:02.650](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[03:22:02.650](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 51207 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[03:22:02.696](0.046s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[03:22:02.696](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 36095
[03:22:02.959](0.263s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[03:22:03.017](0.058s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[03:22:03.017](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 36182
[03:22:03.367](0.349s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[03:22:03.435](0.068s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[03:22:03.435](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 36278
[03:22:03.693](0.258s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[03:22:03.768](0.076s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[03:22:03.768](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 36350
[03:22:04.012](0.243s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[03:22:04.012](0.000s) 
[03:22:04.012](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[03:22:04.012](0.000s) #          got: '2'
#     expected: '0'
[03:22:04.012](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[03:22:04.012](0.000s) 
[03:22:04.013](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[03:22:04.013](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 51207 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: ''
[03:22:04.076](0.064s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[03:22:04.077](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 36420
[03:22:04.317](0.241s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[03:22:04.370](0.053s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[03:22:04.371](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 36480
[03:22:04.639](0.268s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[03:22:04.711](0.072s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[03:22:04.711](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 36563
[03:22:04.964](0.253s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[03:22:05.038](0.074s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[03:22:05.038](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 36639
[03:22:05.275](0.237s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[03:22:05.364](0.088s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[03:22:05.364](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-04-04 03:21:50.119 UTC [33717][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-04-04 03:21:50.119 UTC [33717][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:50.158 UTC [33730][startup] LOG:  database system was shut down at 2024-04-04 03:19:29 UTC
2024-04-04 03:21:50.177 UTC [33717][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:50.318 UTC [33766][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.318 UTC [33766][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-04-04 03:21:50.318 UTC [33766][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.342 UTC [33766][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-04 03:21:50.346 UTC [33766][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.395 UTC [33773][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.395 UTC [33773][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-04-04 03:21:50.395 UTC [33773][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.399 UTC [33773][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-04 03:21:50.401 UTC [33773][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.501 UTC [33785][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.504 UTC [33785][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-04-04 03:21:50.504 UTC [33785][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.508 UTC [33785][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-04 03:21:50.511 UTC [33785][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.547 UTC [33789][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.548 UTC [33789][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-04-04 03:21:50.548 UTC [33789][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.549 UTC [33789][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-04 03:21:50.551 UTC [33789][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.602 UTC [33796][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.607 UTC [33796][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-04-04 03:21:50.607 UTC [33796][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.610 UTC [33796][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-04 03:21:50.612 UTC [33796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.639 UTC [33802][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.640 UTC [33802][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-04-04 03:21:50.640 UTC [33802][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.648 UTC [33802][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-04 03:21:50.743 UTC [33802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.105 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.778 UTC [33817][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.779 UTC [33817][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-04-04 03:21:50.779 UTC [33817][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.780 UTC [33817][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-04 03:21:50.863 UTC [33817][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local]
2024-04-04 03:21:50.917 UTC [33836][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:50.924 UTC [33836][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-04-04 03:21:50.924 UTC [33836][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:50.936 UTC [33836][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-04 03:21:50.996 UTC [33836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.084 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.042 UTC [33852][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.043 UTC [33852][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-04-04 03:21:51.043 UTC [33852][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.053 UTC [33852][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-04 03:21:51.121 UTC [33852][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.173 UTC [33866][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.174 UTC [33866][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-04-04 03:21:51.174 UTC [33866][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.179 UTC [33866][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-04 03:21:51.251 UTC [33866][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.078 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.317 UTC [33884][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-04 03:21:51.318 UTC [33884][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-04-04 03:21:51.318 UTC [33884][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-04 03:21:51.320 UTC [33884][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-04 03:21:51.417 UTC [33884][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.107 user=postgres database=postgres host=[local]
2024-04-04 03:21:51.432 UTC [33717][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:51.432 UTC [33717][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:51.441 UTC [33717][postmaster] LOG:  background worker "logical replication launcher" (PID 33741) exited with exit code 1
2024-04-04 03:21:51.442 UTC [33724][checkpointer] LOG:  shutting down
2024-04-04 03:21:51.442 UTC [33724][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:51.576 UTC [33724][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.134 s, sync=0.001 s, total=0.135 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414CC38, redo lsn=0/414CC38
2024-04-04 03:21:51.603 UTC [33717][postmaster] LOG:  database system is shut down
2024-04-04 03:21:51.690 UTC [33937][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-04-04 03:21:51.690 UTC [33937][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:51.690 UTC [33937][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:51.706 UTC [33953][startup] LOG:  database system was shut down at 2024-04-04 03:21:51 UTC
2024-04-04 03:21:51.714 UTC [33937][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:51.752 UTC [33937][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:51.752 UTC [33937][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:51.756 UTC [33937][postmaster] LOG:  background worker "logical replication launcher" (PID 33959) exited with exit code 1
2024-04-04 03:21:51.759 UTC [33951][checkpointer] LOG:  shutting down
2024-04-04 03:21:51.759 UTC [33951][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:51.760 UTC [33951][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/414CCB0, redo lsn=0/414CCB0
2024-04-04 03:21:51.765 UTC [33937][postmaster] LOG:  database system is shut down
2024-04-04 03:21:51.921 UTC [33984][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-04 03:21:51.921 UTC [33984][postmaster] LOG:  database system is shut down
2024-04-04 03:21:52.031 UTC [33999][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-04-04 03:21:52.031 UTC [33999][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:52.031 UTC [33999][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:52.062 UTC [34011][startup] LOG:  database system was shut down at 2024-04-04 03:21:51 UTC
2024-04-04 03:21:52.077 UTC [33999][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:52.173 UTC [33999][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:52.173 UTC [33999][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:52.177 UTC [33999][postmaster] LOG:  background worker "logical replication launcher" (PID 34017) exited with exit code 1
2024-04-04 03:21:52.182 UTC [34008][checkpointer] LOG:  shutting down
2024-04-04 03:21:52.182 UTC [34008][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:52.183 UTC [34008][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/414CD28, redo lsn=0/414CD28
2024-04-04 03:21:52.189 UTC [33999][postmaster] LOG:  database system is shut down
2024-04-04 03:21:52.398 UTC [34089][postmaster] FATAL:  could not set SSL protocol version range
2024-04-04 03:21:52.398 UTC [34089][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-04-04 03:21:52.398 UTC [34089][postmaster] LOG:  database system is shut down
2024-04-04 03:21:52.632 UTC [34113][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-04-04 03:21:52.632 UTC [34113][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:52.632 UTC [34113][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:52.656 UTC [34126][startup] LOG:  database system was shut down at 2024-04-04 03:21:52 UTC
2024-04-04 03:21:52.664 UTC [34113][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:52.718 UTC [34113][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:52.718 UTC [34113][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:52.723 UTC [34113][postmaster] LOG:  background worker "logical replication launcher" (PID 34131) exited with exit code 1
2024-04-04 03:21:52.727 UTC [34124][checkpointer] LOG:  shutting down
2024-04-04 03:21:52.727 UTC [34124][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:52.728 UTC [34124][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/414CDA0, redo lsn=0/414CDA0
2024-04-04 03:21:52.734 UTC [34113][postmaster] LOG:  database system is shut down
2024-04-04 03:21:52.923 UTC [34147][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-04-04 03:21:52.923 UTC [34147][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:52.923 UTC [34147][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:52.946 UTC [34162][startup] LOG:  database system was shut down at 2024-04-04 03:21:52 UTC
2024-04-04 03:21:52.970 UTC [34147][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:53.078 UTC [34178][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50902
2024-04-04 03:21:53.079 UTC [34178][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-04-04 03:21:53.079 UTC [34178][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-04-04 03:21:53.134 UTC [34183][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50910
2024-04-04 03:21:53.154 UTC [34183][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-04-04 03:21:53.154 UTC [34183][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-04-04 03:21:53.211 UTC [34183][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-04-04 03:21:53.218 UTC [34183][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.088 user=ssltestuser database=trustdb host=localhost port=50910
2024-04-04 03:21:53.266 UTC [34197][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50920
2024-04-04 03:21:53.274 UTC [34197][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:21:53.307 UTC [34205][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50926
2024-04-04 03:21:53.316 UTC [34205][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:21:53.360 UTC [34213][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50932
2024-04-04 03:21:53.381 UTC [34213][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:53.396 UTC [34222][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50938
2024-04-04 03:21:53.410 UTC [34222][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:53.449 UTC [34231][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50940
2024-04-04 03:21:53.457 UTC [34231][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:53.494 UTC [34235][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50950
2024-04-04 03:21:53.504 UTC [34235][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:53.534 UTC [34239][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50966
2024-04-04 03:21:53.546 UTC [34239][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-04-04 03:21:53.546 UTC [34239][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-04-04 03:21:53.547 UTC [34239][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-04-04 03:21:53.549 UTC [34239][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=50966
2024-04-04 03:21:53.597 UTC [34246][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50968
2024-04-04 03:21:53.603 UTC [34246][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-04-04 03:21:53.603 UTC [34246][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-04-04 03:21:53.605 UTC [34246][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-04-04 03:21:53.608 UTC [34246][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=50968
2024-04-04 03:21:53.635 UTC [34251][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50974
2024-04-04 03:21:53.654 UTC [34251][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-04-04 03:21:53.654 UTC [34251][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-04-04 03:21:53.656 UTC [34251][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-04-04 03:21:53.657 UTC [34251][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=50974
2024-04-04 03:21:53.706 UTC [34259][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50986
2024-04-04 03:21:53.721 UTC [34259][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-04-04 03:21:53.721 UTC [34259][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-04-04 03:21:53.724 UTC [34259][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-04-04 03:21:53.730 UTC [34259][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=50986
2024-04-04 03:21:53.764 UTC [34272][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50992
2024-04-04 03:21:53.783 UTC [34272][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-04-04 03:21:53.783 UTC [34272][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-04-04 03:21:53.784 UTC [34272][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-04-04 03:21:53.788 UTC [34272][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=50992
2024-04-04 03:21:53.834 UTC [34281][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=50996
2024-04-04 03:21:53.856 UTC [34281][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-04-04 03:21:53.856 UTC [34281][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-04-04 03:21:53.862 UTC [34281][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-04-04 03:21:53.865 UTC [34281][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=50996
2024-04-04 03:21:53.916 UTC [34297][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51002
2024-04-04 03:21:53.935 UTC [34297][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-04-04 03:21:53.935 UTC [34297][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-04-04 03:21:53.937 UTC [34297][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-04-04 03:21:53.940 UTC [34297][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=51002
2024-04-04 03:21:53.998 UTC [34309][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51014
2024-04-04 03:21:54.010 UTC [34309][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-04-04 03:21:54.010 UTC [34309][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-04-04 03:21:54.021 UTC [34309][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=51014
2024-04-04 03:21:54.072 UTC [34326][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51018
2024-04-04 03:21:54.085 UTC [34326][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-04-04 03:21:54.085 UTC [34326][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-04-04 03:21:54.087 UTC [34326][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-04-04 03:21:54.092 UTC [34326][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=51018
2024-04-04 03:21:54.141 UTC [34343][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51024
2024-04-04 03:21:54.162 UTC [34343][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:54.246 UTC [34367][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51038
2024-04-04 03:21:54.257 UTC [34367][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:54.322 UTC [34382][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51050
2024-04-04 03:21:54.350 UTC [34382][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-04-04 03:21:54.350 UTC [34382][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-04-04 03:21:54.361 UTC [34382][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-04-04 03:21:54.367 UTC [34382][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=ssltestuser database=trustdb host=localhost port=51050
2024-04-04 03:21:54.419 UTC [34400][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51062
2024-04-04 03:21:54.438 UTC [34400][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-04-04 03:21:54.438 UTC [34400][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-04-04 03:21:54.440 UTC [34400][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-04-04 03:21:54.443 UTC [34400][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=51062
2024-04-04 03:21:54.480 UTC [34411][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51074
2024-04-04 03:21:54.503 UTC [34411][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-04-04 03:21:54.503 UTC [34411][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-04-04 03:21:54.509 UTC [34411][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-04-04 03:21:54.516 UTC [34411][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=ssltestuser database=trustdb host=localhost port=51074
2024-04-04 03:21:54.559 UTC [34430][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51078
2024-04-04 03:21:54.565 UTC [34430][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-04-04 03:21:54.566 UTC [34430][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-04-04 03:21:54.567 UTC [34430][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-04-04 03:21:54.573 UTC [34430][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=51078
2024-04-04 03:21:54.611 UTC [34440][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51094
2024-04-04 03:21:54.644 UTC [34147][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:54.644 UTC [34147][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:54.655 UTC [34147][postmaster] LOG:  background worker "logical replication launcher" (PID 34168) exited with exit code 1
2024-04-04 03:21:54.656 UTC [34160][checkpointer] LOG:  shutting down
2024-04-04 03:21:54.656 UTC [34160][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:54.656 UTC [34160][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/414CE18, redo lsn=0/414CE18
2024-04-04 03:21:54.662 UTC [34147][postmaster] LOG:  database system is shut down
2024-04-04 03:21:54.745 UTC [34454][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-04-04 03:21:54.745 UTC [34454][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:54.745 UTC [34454][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:54.786 UTC [34469][startup] LOG:  database system was shut down at 2024-04-04 03:21:54 UTC
2024-04-04 03:21:54.794 UTC [34454][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:54.924 UTC [34499][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51100
2024-04-04 03:21:54.936 UTC [34499][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-04-04 03:21:54.936 UTC [34499][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-04-04 03:21:54.955 UTC [34499][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-04-04 03:21:54.963 UTC [34499][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=51100
2024-04-04 03:21:55.004 UTC [34514][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51102
2024-04-04 03:21:55.050 UTC [34454][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:55.050 UTC [34454][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:55.061 UTC [34454][postmaster] LOG:  background worker "logical replication launcher" (PID 34480) exited with exit code 1
2024-04-04 03:21:55.062 UTC [34466][checkpointer] LOG:  shutting down
2024-04-04 03:21:55.062 UTC [34466][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:55.063 UTC [34466][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/414CE90, redo lsn=0/414CE90
2024-04-04 03:21:55.069 UTC [34454][postmaster] LOG:  database system is shut down
2024-04-04 03:21:55.127 UTC [34530][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-04-04 03:21:55.127 UTC [34530][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:55.127 UTC [34530][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:55.134 UTC [34542][startup] LOG:  database system was shut down at 2024-04-04 03:21:55 UTC
2024-04-04 03:21:55.141 UTC [34530][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:55.234 UTC [34561][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51110
2024-04-04 03:21:55.249 UTC [34561][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-04-04 03:21:55.249 UTC [34561][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-04-04 03:21:55.285 UTC [34561][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-04-04 03:21:55.292 UTC [34561][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=ssltestuser database=trustdb host=localhost port=51110
2024-04-04 03:21:55.296 UTC [34530][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:55.296 UTC [34530][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:55.300 UTC [34530][postmaster] LOG:  background worker "logical replication launcher" (PID 34547) exited with exit code 1
2024-04-04 03:21:55.321 UTC [34539][checkpointer] LOG:  shutting down
2024-04-04 03:21:55.321 UTC [34539][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:55.321 UTC [34539][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/414CF08, redo lsn=0/414CF08
2024-04-04 03:21:55.330 UTC [34530][postmaster] LOG:  database system is shut down
2024-04-04 03:21:55.481 UTC [34581][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-04-04 03:21:55.481 UTC [34581][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:55.491 UTC [34581][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:55.516 UTC [34599][startup] LOG:  database system was shut down at 2024-04-04 03:21:55 UTC
2024-04-04 03:21:55.523 UTC [34581][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:55.643 UTC [34620][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51120
2024-04-04 03:21:55.657 UTC [34620][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-04-04 03:21:55.657 UTC [34620][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-04-04 03:21:55.697 UTC [34620][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-04-04 03:21:55.702 UTC [34620][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.061 user=ssltestuser database=trustdb host=localhost port=51120
2024-04-04 03:21:55.734 UTC [34630][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51130
2024-04-04 03:21:55.751 UTC [34630][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-04-04 03:21:55.751 UTC [34630][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-04-04 03:21:55.754 UTC [34630][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-04-04 03:21:55.764 UTC [34630][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=51130
2024-04-04 03:21:55.791 UTC [34639][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51132
2024-04-04 03:21:55.811 UTC [34639][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-04-04 03:21:55.811 UTC [34639][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-04-04 03:21:55.813 UTC [34639][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-04-04 03:21:55.819 UTC [34639][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=51132
2024-04-04 03:21:55.874 UTC [34651][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51134
2024-04-04 03:21:55.950 UTC [34660][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51140
2024-04-04 03:21:55.984 UTC [34581][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:55.984 UTC [34581][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:55.988 UTC [34581][postmaster] LOG:  background worker "logical replication launcher" (PID 34604) exited with exit code 1
2024-04-04 03:21:55.997 UTC [34597][checkpointer] LOG:  shutting down
2024-04-04 03:21:55.997 UTC [34597][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:55.997 UTC [34597][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/414CF80, redo lsn=0/414CF80
2024-04-04 03:21:56.003 UTC [34581][postmaster] LOG:  database system is shut down
2024-04-04 03:21:56.159 UTC [34674][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-04-04 03:21:56.159 UTC [34674][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:56.159 UTC [34674][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:56.186 UTC [34687][startup] LOG:  database system was shut down at 2024-04-04 03:21:55 UTC
2024-04-04 03:21:56.210 UTC [34674][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:56.340 UTC [34717][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51146
2024-04-04 03:21:56.359 UTC [34717][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-04-04 03:21:56.359 UTC [34717][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-04-04 03:21:56.395 UTC [34717][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-04-04 03:21:56.401 UTC [34717][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.066 user=ssltestuser database=trustdb host=localhost port=51146
2024-04-04 03:21:56.467 UTC [34732][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51156
2024-04-04 03:21:56.514 UTC [34744][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51172
2024-04-04 03:21:57.045 UTC [34674][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:57.045 UTC [34674][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:57.062 UTC [34674][postmaster] LOG:  background worker "logical replication launcher" (PID 34695) exited with exit code 1
2024-04-04 03:21:57.062 UTC [34683][checkpointer] LOG:  shutting down
2024-04-04 03:21:57.062 UTC [34683][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:57.062 UTC [34683][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/414CFF8, redo lsn=0/414CFF8
2024-04-04 03:21:57.068 UTC [34674][postmaster] LOG:  database system is shut down
2024-04-04 03:21:57.194 UTC [34827][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-04-04 03:21:57.194 UTC [34827][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:57.194 UTC [34827][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:57.216 UTC [34848][startup] LOG:  database system was shut down at 2024-04-04 03:21:57 UTC
2024-04-04 03:21:57.227 UTC [34827][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:57.292 UTC [34871][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51174
2024-04-04 03:21:57.298 UTC [34871][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-04-04 03:21:57.298 UTC [34871][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-04-04 03:21:57.325 UTC [34871][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-04-04 03:21:57.331 UTC [34871][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=51174
2024-04-04 03:21:57.364 UTC [34881][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51186
2024-04-04 03:21:57.370 UTC [34881][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-04-04 03:21:57.371 UTC [34881][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-04-04 03:21:57.372 UTC [34881][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-04-04 03:21:57.376 UTC [34881][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=51186
2024-04-04 03:21:57.422 UTC [34893][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51196
2024-04-04 03:21:57.459 UTC [34902][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51202
2024-04-04 03:21:57.465 UTC [34902][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-04-04 03:21:57.465 UTC [34902][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-04-04 03:21:57.467 UTC [34902][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-04-04 03:21:57.470 UTC [34902][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=51202
2024-04-04 03:21:57.500 UTC [34915][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51210
2024-04-04 03:21:57.507 UTC [34915][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-04-04 03:21:57.507 UTC [34915][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-04-04 03:21:57.508 UTC [34915][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-04-04 03:21:57.514 UTC [34915][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=51210
2024-04-04 03:21:57.541 UTC [34922][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51218
2024-04-04 03:21:57.547 UTC [34922][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-04-04 03:21:57.547 UTC [34922][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-04-04 03:21:57.549 UTC [34922][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-04-04 03:21:57.554 UTC [34922][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=51218
2024-04-04 03:21:57.606 UTC [34932][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51226
2024-04-04 03:21:57.641 UTC [34944][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51240
2024-04-04 03:21:57.670 UTC [34827][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:57.670 UTC [34827][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:57.686 UTC [34827][postmaster] LOG:  background worker "logical replication launcher" (PID 34857) exited with exit code 1
2024-04-04 03:21:57.686 UTC [34845][checkpointer] LOG:  shutting down
2024-04-04 03:21:57.686 UTC [34845][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:57.687 UTC [34845][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/414D070, redo lsn=0/414D070
2024-04-04 03:21:57.693 UTC [34827][postmaster] LOG:  database system is shut down
2024-04-04 03:21:57.809 UTC [34987][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-04-04 03:21:57.810 UTC [34987][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:57.810 UTC [34987][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:57.814 UTC [35004][startup] LOG:  database system was shut down at 2024-04-04 03:21:57 UTC
2024-04-04 03:21:57.823 UTC [34987][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:57.900 UTC [35026][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43674
2024-04-04 03:21:57.911 UTC [35026][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-04-04 03:21:57.911 UTC [35026][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-04-04 03:21:57.950 UTC [35026][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-04-04 03:21:57.957 UTC [35026][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.058 user=ssltestuser database=trustdb host=localhost port=43674
2024-04-04 03:21:57.983 UTC [35045][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43682
2024-04-04 03:21:57.990 UTC [35045][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-04-04 03:21:57.990 UTC [35045][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-04-04 03:21:57.995 UTC [35045][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-04-04 03:21:57.998 UTC [35045][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=43682
2024-04-04 03:21:58.023 UTC [35053][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43688
2024-04-04 03:21:58.062 UTC [34987][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:58.062 UTC [34987][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:58.067 UTC [34987][postmaster] LOG:  background worker "logical replication launcher" (PID 35011) exited with exit code 1
2024-04-04 03:21:58.071 UTC [35002][checkpointer] LOG:  shutting down
2024-04-04 03:21:58.071 UTC [35002][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:58.072 UTC [35002][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/414D0E8, redo lsn=0/414D0E8
2024-04-04 03:21:58.078 UTC [34987][postmaster] LOG:  database system is shut down
2024-04-04 03:21:58.219 UTC [35075][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-04-04 03:21:58.219 UTC [35075][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:58.219 UTC [35075][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:58.244 UTC [35084][startup] LOG:  database system was shut down at 2024-04-04 03:21:58 UTC
2024-04-04 03:21:58.252 UTC [35075][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:58.314 UTC [35098][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43700
2024-04-04 03:21:58.320 UTC [35098][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-04-04 03:21:58.320 UTC [35098][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-04-04 03:21:58.338 UTC [35098][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-04-04 03:21:58.344 UTC [35098][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=trustdb host=localhost port=43700
2024-04-04 03:21:58.380 UTC [35117][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43708
2024-04-04 03:21:58.393 UTC [35117][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-04-04 03:21:58.393 UTC [35117][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-04-04 03:21:58.394 UTC [35117][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-04-04 03:21:58.399 UTC [35117][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=43708
2024-04-04 03:21:58.411 UTC [35133][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43714
2024-04-04 03:21:58.417 UTC [35133][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-04-04 03:21:58.417 UTC [35133][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-04-04 03:21:58.419 UTC [35133][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-04-04 03:21:58.426 UTC [35133][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=43714
2024-04-04 03:21:58.431 UTC [35075][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:58.431 UTC [35075][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:58.439 UTC [35075][postmaster] LOG:  background worker "logical replication launcher" (PID 35090) exited with exit code 1
2024-04-04 03:21:58.440 UTC [35082][checkpointer] LOG:  shutting down
2024-04-04 03:21:58.440 UTC [35082][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:58.440 UTC [35082][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/414D160, redo lsn=0/414D160
2024-04-04 03:21:58.445 UTC [35075][postmaster] LOG:  database system is shut down
2024-04-04 03:21:58.589 UTC [35156][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-04-04 03:21:58.589 UTC [35156][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:58.591 UTC [35156][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:58.605 UTC [35172][startup] LOG:  database system was shut down at 2024-04-04 03:21:58 UTC
2024-04-04 03:21:58.614 UTC [35156][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:58.659 UTC [35192][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43726
2024-04-04 03:21:58.665 UTC [35192][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-04-04 03:21:58.665 UTC [35192][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-04-04 03:21:58.681 UTC [35192][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-04-04 03:21:58.687 UTC [35192][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=43726
2024-04-04 03:21:58.714 UTC [35204][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43730
2024-04-04 03:21:58.730 UTC [35204][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-04-04 03:21:58.730 UTC [35204][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-04-04 03:21:58.732 UTC [35204][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-04-04 03:21:58.735 UTC [35204][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=43730
2024-04-04 03:21:58.775 UTC [35219][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43746
2024-04-04 03:21:58.796 UTC [35156][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:58.796 UTC [35156][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:58.801 UTC [35156][postmaster] LOG:  background worker "logical replication launcher" (PID 35178) exited with exit code 1
2024-04-04 03:21:58.805 UTC [35170][checkpointer] LOG:  shutting down
2024-04-04 03:21:58.805 UTC [35170][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:58.806 UTC [35170][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/414D1D8, redo lsn=0/414D1D8
2024-04-04 03:21:58.811 UTC [35156][postmaster] LOG:  database system is shut down
2024-04-04 03:21:58.935 UTC [35249][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-04-04 03:21:58.935 UTC [35249][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:58.935 UTC [35249][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:58.944 UTC [35259][startup] LOG:  database system was shut down at 2024-04-04 03:21:58 UTC
2024-04-04 03:21:58.955 UTC [35249][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:59.038 UTC [35281][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43748
2024-04-04 03:21:59.054 UTC [35281][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-04-04 03:21:59.054 UTC [35281][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-04-04 03:21:59.073 UTC [35281][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-04-04 03:21:59.079 UTC [35281][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=trustdb host=localhost port=43748
2024-04-04 03:21:59.100 UTC [35298][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43750
2024-04-04 03:21:59.119 UTC [35298][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-04-04 03:21:59.119 UTC [35298][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-04-04 03:21:59.121 UTC [35298][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-04-04 03:21:59.126 UTC [35298][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=43750
2024-04-04 03:21:59.151 UTC [35308][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43766
2024-04-04 03:21:59.157 UTC [35308][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-04-04 03:21:59.157 UTC [35308][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-04-04 03:21:59.159 UTC [35308][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-04-04 03:21:59.164 UTC [35308][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=43766
2024-04-04 03:21:59.171 UTC [35249][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:59.171 UTC [35249][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:59.179 UTC [35249][postmaster] LOG:  background worker "logical replication launcher" (PID 35268) exited with exit code 1
2024-04-04 03:21:59.179 UTC [35257][checkpointer] LOG:  shutting down
2024-04-04 03:21:59.179 UTC [35257][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:59.180 UTC [35257][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/414D250, redo lsn=0/414D250
2024-04-04 03:21:59.185 UTC [35249][postmaster] LOG:  database system is shut down
2024-04-04 03:21:59.329 UTC [35343][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-04-04 03:21:59.329 UTC [35343][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:59.329 UTC [35343][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:59.350 UTC [35363][startup] LOG:  database system was shut down at 2024-04-04 03:21:59 UTC
2024-04-04 03:21:59.372 UTC [35343][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:59.518 UTC [35403][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43780
2024-04-04 03:21:59.525 UTC [35403][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-04-04 03:21:59.525 UTC [35403][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-04-04 03:21:59.537 UTC [35403][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-04-04 03:21:59.543 UTC [35403][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=43780
2024-04-04 03:21:59.582 UTC [35417][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43784
2024-04-04 03:21:59.607 UTC [35343][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:59.607 UTC [35343][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:59.623 UTC [35343][postmaster] LOG:  background worker "logical replication launcher" (PID 35376) exited with exit code 1
2024-04-04 03:21:59.623 UTC [35359][checkpointer] LOG:  shutting down
2024-04-04 03:21:59.623 UTC [35359][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:59.624 UTC [35359][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/414D2C8, redo lsn=0/414D2C8
2024-04-04 03:21:59.629 UTC [35343][postmaster] LOG:  database system is shut down
2024-04-04 03:21:59.761 UTC [35447][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-04-04 03:21:59.761 UTC [35447][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:21:59.761 UTC [35447][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:21:59.786 UTC [35455][startup] LOG:  database system was shut down at 2024-04-04 03:21:59 UTC
2024-04-04 03:21:59.794 UTC [35447][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:21:59.862 UTC [35473][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43788
2024-04-04 03:21:59.872 UTC [35473][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-04 03:21:59.926 UTC [35484][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43794
2024-04-04 03:21:59.934 UTC [35484][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-04-04 03:21:59.934 UTC [35484][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-04-04 03:21:59.946 UTC [35484][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-04-04 03:21:59.952 UTC [35484][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=43794
2024-04-04 03:21:59.968 UTC [35490][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43808
2024-04-04 03:21:59.991 UTC [35447][postmaster] LOG:  received fast shutdown request
2024-04-04 03:21:59.991 UTC [35447][postmaster] LOG:  aborting any active transactions
2024-04-04 03:21:59.997 UTC [35447][postmaster] LOG:  background worker "logical replication launcher" (PID 35463) exited with exit code 1
2024-04-04 03:21:59.997 UTC [35453][checkpointer] LOG:  shutting down
2024-04-04 03:21:59.997 UTC [35453][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:21:59.998 UTC [35453][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/414D340, redo lsn=0/414D340
2024-04-04 03:22:00.003 UTC [35447][postmaster] LOG:  database system is shut down
2024-04-04 03:22:00.145 UTC [35510][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-04-04 03:22:00.145 UTC [35510][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:00.145 UTC [35510][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:00.178 UTC [35517][startup] LOG:  database system was shut down at 2024-04-04 03:21:59 UTC
2024-04-04 03:22:00.186 UTC [35510][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:00.211 UTC [35530][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43810
2024-04-04 03:22:00.217 UTC [35530][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-04-04 03:22:00.217 UTC [35530][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-04-04 03:22:00.230 UTC [35530][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-04-04 03:22:00.233 UTC [35530][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=43810
2024-04-04 03:22:00.266 UTC [35538][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43826
2024-04-04 03:22:00.276 UTC [35538][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-04-04 03:22:00.302 UTC [35547][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43828
2024-04-04 03:22:00.320 UTC [35547][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-04-04 03:22:00.358 UTC [35562][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43842
2024-04-04 03:22:00.367 UTC [35562][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-04-04 03:22:00.367 UTC [35562][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-04-04 03:22:00.371 UTC [35562][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-04-04 03:22:00.376 UTC [35562][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=43842
2024-04-04 03:22:00.431 UTC [35578][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43846
2024-04-04 03:22:00.441 UTC [35578][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-04-04 03:22:00.441 UTC [35578][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-04-04 03:22:00.443 UTC [35578][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-04-04 03:22:00.447 UTC [35578][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=43846
2024-04-04 03:22:00.531 UTC [35618][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43848
2024-04-04 03:22:00.541 UTC [35618][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-04-04 03:22:00.584 UTC [35634][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43860
2024-04-04 03:22:00.593 UTC [35634][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-04-04 03:22:00.593 UTC [35634][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-04-04 03:22:00.618 UTC [35634][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_3LMB/client.key$$
2024-04-04 03:22:00.624 UTC [35634][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=certdb host=localhost port=43860
2024-04-04 03:22:00.645 UTC [35654][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43864
2024-04-04 03:22:00.654 UTC [35654][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-04-04 03:22:00.654 UTC [35654][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-04-04 03:22:00.655 UTC [35654][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_3LMB/client-der.key$$
2024-04-04 03:22:00.666 UTC [35654][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=43864
2024-04-04 03:22:00.701 UTC [35667][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43872
2024-04-04 03:22:00.713 UTC [35667][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-04-04 03:22:00.713 UTC [35667][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-04-04 03:22:00.717 UTC [35667][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_3LMB/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-04-04 03:22:00.723 UTC [35667][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=certdb host=localhost port=43872
2024-04-04 03:22:00.752 UTC [35675][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43882
2024-04-04 03:22:00.762 UTC [35675][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-04-04 03:22:00.762 UTC [35675][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-04-04 03:22:00.763 UTC [35675][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_3LMB/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-04-04 03:22:00.768 UTC [35675][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=certdb host=localhost port=43882
2024-04-04 03:22:00.794 UTC [35681][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43886
2024-04-04 03:22:00.811 UTC [35681][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-04-04 03:22:00.811 UTC [35681][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-04-04 03:22:00.814 UTC [35681][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_3LMB/client.key$$
2024-04-04 03:22:00.815 UTC [35681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb host=localhost port=43886
2024-04-04 03:22:00.850 UTC [35689][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43896
2024-04-04 03:22:00.870 UTC [35689][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-04-04 03:22:00.870 UTC [35689][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-04-04 03:22:00.871 UTC [35689][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_3LMB/client.key$$
2024-04-04 03:22:00.873 UTC [35689][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=certdb host=localhost port=43896
2024-04-04 03:22:00.911 UTC [35702][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43898
2024-04-04 03:22:00.942 UTC [35702][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-04-04 03:22:00.994 UTC [35714][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43908
2024-04-04 03:22:01.002 UTC [35714][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:22:01.024 UTC [35720][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43920
2024-04-04 03:22:01.032 UTC [35720][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-04-04 03:22:01.032 UTC [35720][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-04-04 03:22:01.051 UTC [35720][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_3LMB/client-dn.key$$
2024-04-04 03:22:01.056 UTC [35720][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=certdb_dn host=localhost port=43920
2024-04-04 03:22:01.075 UTC [35730][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43932
2024-04-04 03:22:01.083 UTC [35730][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-04-04 03:22:01.083 UTC [35730][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-04-04 03:22:01.095 UTC [35730][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_3LMB/client-dn.key$$
2024-04-04 03:22:01.101 UTC [35730][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=certdb_dn_re host=localhost port=43932
2024-04-04 03:22:01.119 UTC [35744][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43940
2024-04-04 03:22:01.131 UTC [35744][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-04-04 03:22:01.131 UTC [35744][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-04-04 03:22:01.145 UTC [35744][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_3LMB/client-dn.key$$
2024-04-04 03:22:01.152 UTC [35744][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=certdb_cn host=localhost port=43940
2024-04-04 03:22:01.178 UTC [35761][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43954
2024-04-04 03:22:01.187 UTC [35761][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-04-04 03:22:01.187 UTC [35761][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-04-04 03:22:01.202 UTC [35761][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-04-04 03:22:01.209 UTC [35761][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=certdb host=localhost port=43954
2024-04-04 03:22:01.235 UTC [35777][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43956
2024-04-04 03:22:01.240 UTC [35777][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:22:01.272 UTC [35783][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43968
2024-04-04 03:22:01.280 UTC [35783][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-04-04 03:22:01.280 UTC [35783][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-04-04 03:22:01.280 UTC [35783][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-04-04 03:22:01.280 UTC [35783][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-04-04 03:22:01.322 UTC [35796][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43972
2024-04-04 03:22:01.332 UTC [35796][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-04 03:22:01.332 UTC [35796][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-04-04 03:22:01.360 UTC [35814][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43982
2024-04-04 03:22:01.375 UTC [35814][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-04-04 03:22:01.375 UTC [35814][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-04-04 03:22:01.388 UTC [35814][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_3LMB/client.key$$
2024-04-04 03:22:01.394 UTC [35814][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=verifydb host=localhost port=43982
2024-04-04 03:22:01.430 UTC [35825][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43994
2024-04-04 03:22:01.438 UTC [35825][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-04-04 03:22:01.438 UTC [35825][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-04-04 03:22:01.438 UTC [35825][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-04-04 03:22:01.438 UTC [35825][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-04-04 03:22:01.464 UTC [35830][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=43996
2024-04-04 03:22:01.483 UTC [35830][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-04-04 03:22:01.483 UTC [35830][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-04-04 03:22:01.485 UTC [35830][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_3LMB/client.key$$
2024-04-04 03:22:01.490 UTC [35830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=yetanotheruser database=verifydb host=localhost port=43996
2024-04-04 03:22:01.500 UTC [35510][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:01.500 UTC [35510][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:01.507 UTC [35510][postmaster] LOG:  background worker "logical replication launcher" (PID 35524) exited with exit code 1
2024-04-04 03:22:01.507 UTC [35515][checkpointer] LOG:  shutting down
2024-04-04 03:22:01.507 UTC [35515][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:01.508 UTC [35515][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/414D3B8, redo lsn=0/414D3B8
2024-04-04 03:22:01.513 UTC [35510][postmaster] LOG:  database system is shut down
2024-04-04 03:22:01.623 UTC [35861][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-04-04 03:22:01.623 UTC [35861][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:01.623 UTC [35861][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:01.634 UTC [35867][startup] LOG:  database system was shut down at 2024-04-04 03:22:01 UTC
2024-04-04 03:22:01.652 UTC [35861][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:01.726 UTC [35882][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44006
2024-04-04 03:22:01.735 UTC [35882][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-04-04 03:22:01.735 UTC [35882][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-04-04 03:22:01.750 UTC [35882][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_3LMB/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-04-04 03:22:01.756 UTC [35882][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=certdb host=localhost port=44006
2024-04-04 03:22:01.770 UTC [35887][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44018
2024-04-04 03:22:01.779 UTC [35887][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-04 03:22:01.779 UTC [35887][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-04-04 03:22:01.795 UTC [35889][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44026
2024-04-04 03:22:01.802 UTC [35889][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-04 03:22:01.802 UTC [35889][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-04-04 03:22:01.820 UTC [35861][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:01.820 UTC [35861][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:01.827 UTC [35861][postmaster] LOG:  background worker "logical replication launcher" (PID 35873) exited with exit code 1
2024-04-04 03:22:01.827 UTC [35864][checkpointer] LOG:  shutting down
2024-04-04 03:22:01.827 UTC [35864][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:01.828 UTC [35864][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/414D430, redo lsn=0/414D430
2024-04-04 03:22:01.833 UTC [35861][postmaster] LOG:  database system is shut down
2024-04-04 03:22:01.954 UTC [35917][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-04-04 03:22:01.954 UTC [35917][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:01.954 UTC [35917][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:01.968 UTC [35926][startup] LOG:  database system was shut down at 2024-04-04 03:22:01 UTC
2024-04-04 03:22:01.976 UTC [35917][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:02.050 UTC [35936][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44040
2024-04-04 03:22:02.057 UTC [35936][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-04 03:22:02.057 UTC [35936][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-04-04 03:22:02.072 UTC [35917][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:02.072 UTC [35917][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:02.077 UTC [35917][postmaster] LOG:  background worker "logical replication launcher" (PID 35929) exited with exit code 1
2024-04-04 03:22:02.084 UTC [35924][checkpointer] LOG:  shutting down
2024-04-04 03:22:02.084 UTC [35924][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:02.084 UTC [35924][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/414D4A8, redo lsn=0/414D4A8
2024-04-04 03:22:02.090 UTC [35917][postmaster] LOG:  database system is shut down
2024-04-04 03:22:02.226 UTC [35959][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-04-04 03:22:02.226 UTC [35959][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:02.226 UTC [35959][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:02.238 UTC [35971][startup] LOG:  database system was shut down at 2024-04-04 03:22:02 UTC
2024-04-04 03:22:02.246 UTC [35959][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:02.314 UTC [35980][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44056
2024-04-04 03:22:02.324 UTC [35980][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-04 03:22:02.324 UTC [35980][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-04-04 03:22:02.366 UTC [35984][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44066
2024-04-04 03:22:02.375 UTC [35984][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-04 03:22:02.375 UTC [35984][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-04-04 03:22:02.400 UTC [35959][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:02.400 UTC [35959][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:02.413 UTC [35959][postmaster] LOG:  background worker "logical replication launcher" (PID 35974) exited with exit code 1
2024-04-04 03:22:02.413 UTC [35969][checkpointer] LOG:  shutting down
2024-04-04 03:22:02.413 UTC [35969][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:02.414 UTC [35969][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/414D520, redo lsn=0/414D520
2024-04-04 03:22:02.420 UTC [35959][postmaster] LOG:  database system is shut down
2024-04-04 03:22:02.566 UTC [36013][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-04-04 03:22:02.566 UTC [36013][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:02.566 UTC [36013][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:02.578 UTC [36031][startup] LOG:  database system was shut down at 2024-04-04 03:22:02 UTC
2024-04-04 03:22:02.590 UTC [36013][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:02.638 UTC [36048][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44080
2024-04-04 03:22:02.640 UTC [36048][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:02.640 UTC [36048][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-04 03:22:02.664 UTC [36057][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44088
2024-04-04 03:22:02.675 UTC [36057][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-04-04 03:22:02.675 UTC [36057][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-04-04 03:22:02.692 UTC [36057][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-04-04 03:22:02.697 UTC [36057][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=44088
2024-04-04 03:22:02.703 UTC [36013][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:02.703 UTC [36013][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:02.708 UTC [36013][postmaster] LOG:  background worker "logical replication launcher" (PID 36036) exited with exit code 1
2024-04-04 03:22:02.713 UTC [36029][checkpointer] LOG:  shutting down
2024-04-04 03:22:02.713 UTC [36029][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:02.714 UTC [36029][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/414D598, redo lsn=0/414D598
2024-04-04 03:22:02.722 UTC [36013][postmaster] LOG:  database system is shut down
2024-04-04 03:22:02.847 UTC [36095][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-04-04 03:22:02.847 UTC [36095][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:02.847 UTC [36095][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:02.860 UTC [36108][startup] LOG:  database system was shut down at 2024-04-04 03:22:02 UTC
2024-04-04 03:22:02.876 UTC [36095][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:02.942 UTC [36128][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44104
2024-04-04 03:22:02.945 UTC [36128][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:02.946 UTC [36128][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:02.961 UTC [36128][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-04-04 03:22:02.987 UTC [36138][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44116
2024-04-04 03:22:02.994 UTC [36138][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-04-04 03:22:02.994 UTC [36138][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-04-04 03:22:03.013 UTC [36138][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-04-04 03:22:03.018 UTC [36138][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=44116
2024-04-04 03:22:03.024 UTC [36095][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:03.024 UTC [36095][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:03.028 UTC [36095][postmaster] LOG:  background worker "logical replication launcher" (PID 36115) exited with exit code 1
2024-04-04 03:22:03.034 UTC [36106][checkpointer] LOG:  shutting down
2024-04-04 03:22:03.034 UTC [36106][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:03.035 UTC [36106][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/414D610, redo lsn=0/414D610
2024-04-04 03:22:03.043 UTC [36095][postmaster] LOG:  database system is shut down
2024-04-04 03:22:03.186 UTC [36182][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-04-04 03:22:03.186 UTC [36182][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:03.186 UTC [36182][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:03.234 UTC [36205][startup] LOG:  database system was shut down at 2024-04-04 03:22:03 UTC
2024-04-04 03:22:03.241 UTC [36182][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:03.352 UTC [36238][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44124
2024-04-04 03:22:03.354 UTC [36238][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:03.354 UTC [36238][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-04 03:22:03.409 UTC [36248][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44136
2024-04-04 03:22:03.418 UTC [36248][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-04-04 03:22:03.418 UTC [36248][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-04-04 03:22:03.431 UTC [36248][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-04-04 03:22:03.436 UTC [36248][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=44136
2024-04-04 03:22:03.450 UTC [36182][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:03.450 UTC [36182][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:03.457 UTC [36182][postmaster] LOG:  background worker "logical replication launcher" (PID 36213) exited with exit code 1
2024-04-04 03:22:03.459 UTC [36196][checkpointer] LOG:  shutting down
2024-04-04 03:22:03.459 UTC [36196][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:03.460 UTC [36196][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/414D688, redo lsn=0/414D688
2024-04-04 03:22:03.465 UTC [36182][postmaster] LOG:  database system is shut down
2024-04-04 03:22:03.601 UTC [36278][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-04-04 03:22:03.601 UTC [36278][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:03.601 UTC [36278][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:03.616 UTC [36287][startup] LOG:  database system was shut down at 2024-04-04 03:22:03 UTC
2024-04-04 03:22:03.624 UTC [36278][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:03.678 UTC [36307][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44138
2024-04-04 03:22:03.681 UTC [36307][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:03.687 UTC [36307][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:03.694 UTC [36307][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-04-04 03:22:03.721 UTC [36316][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44140
2024-04-04 03:22:03.734 UTC [36316][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-04-04 03:22:03.734 UTC [36316][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-04-04 03:22:03.764 UTC [36316][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-04-04 03:22:03.769 UTC [36316][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=44140
2024-04-04 03:22:03.772 UTC [36278][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:03.772 UTC [36278][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:03.777 UTC [36278][postmaster] LOG:  background worker "logical replication launcher" (PID 36291) exited with exit code 1
2024-04-04 03:22:03.784 UTC [36284][checkpointer] LOG:  shutting down
2024-04-04 03:22:03.784 UTC [36284][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:03.784 UTC [36284][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/414D700, redo lsn=0/414D700
2024-04-04 03:22:03.790 UTC [36278][postmaster] LOG:  database system is shut down
2024-04-04 03:22:03.934 UTC [36350][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-04-04 03:22:03.934 UTC [36350][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:03.934 UTC [36350][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:03.961 UTC [36373][startup] LOG:  database system was shut down at 2024-04-04 03:22:03 UTC
2024-04-04 03:22:03.970 UTC [36350][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:04.002 UTC [36385][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44148
2024-04-04 03:22:04.004 UTC [36385][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.004 UTC [36385][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-04 03:22:04.042 UTC [36392][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44154
2024-04-04 03:22:04.048 UTC [36392][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-04-04 03:22:04.048 UTC [36392][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-04-04 03:22:04.072 UTC [36392][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-04-04 03:22:04.086 UTC [36392][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=ssltestuser database=trustdb host=localhost port=44154
2024-04-04 03:22:04.093 UTC [36350][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:04.093 UTC [36350][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:04.101 UTC [36350][postmaster] LOG:  background worker "logical replication launcher" (PID 36380) exited with exit code 1
2024-04-04 03:22:04.101 UTC [36369][checkpointer] LOG:  shutting down
2024-04-04 03:22:04.101 UTC [36369][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:04.101 UTC [36369][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/414D778, redo lsn=0/414D778
2024-04-04 03:22:04.107 UTC [36350][postmaster] LOG:  database system is shut down
2024-04-04 03:22:04.230 UTC [36420][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-04-04 03:22:04.230 UTC [36420][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:04.230 UTC [36420][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:04.259 UTC [36429][startup] LOG:  database system was shut down at 2024-04-04 03:22:04 UTC
2024-04-04 03:22:04.267 UTC [36420][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:04.310 UTC [36448][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44166
2024-04-04 03:22:04.312 UTC [36448][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.312 UTC [36448][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.317 UTC [36448][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:22:04.345 UTC [36455][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44180
2024-04-04 03:22:04.352 UTC [36455][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-04-04 03:22:04.352 UTC [36455][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-04-04 03:22:04.366 UTC [36455][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-04-04 03:22:04.370 UTC [36455][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=44180
2024-04-04 03:22:04.380 UTC [36420][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:04.380 UTC [36420][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:04.384 UTC [36420][postmaster] LOG:  background worker "logical replication launcher" (PID 36442) exited with exit code 1
2024-04-04 03:22:04.389 UTC [36427][checkpointer] LOG:  shutting down
2024-04-04 03:22:04.389 UTC [36427][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:04.390 UTC [36427][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/414D7F0, redo lsn=0/414D7F0
2024-04-04 03:22:04.395 UTC [36420][postmaster] LOG:  database system is shut down
2024-04-04 03:22:04.531 UTC [36480][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-04-04 03:22:04.531 UTC [36480][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:04.531 UTC [36480][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:04.537 UTC [36501][startup] LOG:  database system was shut down at 2024-04-04 03:22:04 UTC
2024-04-04 03:22:04.546 UTC [36480][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:04.630 UTC [36513][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44186
2024-04-04 03:22:04.632 UTC [36513][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.633 UTC [36513][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.640 UTC [36513][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:22:04.677 UTC [36523][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44194
2024-04-04 03:22:04.683 UTC [36523][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-04-04 03:22:04.683 UTC [36523][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-04-04 03:22:04.707 UTC [36523][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-04-04 03:22:04.710 UTC [36523][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=44194
2024-04-04 03:22:04.717 UTC [36480][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:04.717 UTC [36480][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:04.728 UTC [36480][postmaster] LOG:  background worker "logical replication launcher" (PID 36505) exited with exit code 1
2024-04-04 03:22:04.728 UTC [36499][checkpointer] LOG:  shutting down
2024-04-04 03:22:04.728 UTC [36499][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:04.729 UTC [36499][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/414D868, redo lsn=0/414D868
2024-04-04 03:22:04.735 UTC [36480][postmaster] LOG:  database system is shut down
2024-04-04 03:22:04.880 UTC [36563][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-04-04 03:22:04.880 UTC [36563][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:04.880 UTC [36563][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:04.894 UTC [36573][startup] LOG:  database system was shut down at 2024-04-04 03:22:04 UTC
2024-04-04 03:22:04.902 UTC [36563][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:04.954 UTC [36593][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44206
2024-04-04 03:22:04.958 UTC [36593][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.958 UTC [36593][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:04.965 UTC [36593][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-04 03:22:04.993 UTC [36601][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44216
2024-04-04 03:22:05.012 UTC [36601][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-04-04 03:22:05.012 UTC [36601][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-04-04 03:22:05.034 UTC [36601][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-04-04 03:22:05.039 UTC [36601][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=ssltestuser database=trustdb host=localhost port=44216
2024-04-04 03:22:05.048 UTC [36563][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:05.048 UTC [36563][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:05.056 UTC [36563][postmaster] LOG:  background worker "logical replication launcher" (PID 36578) exited with exit code 1
2024-04-04 03:22:05.056 UTC [36571][checkpointer] LOG:  shutting down
2024-04-04 03:22:05.056 UTC [36571][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:05.057 UTC [36571][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/414D8E0, redo lsn=0/414D8E0
2024-04-04 03:22:05.064 UTC [36563][postmaster] LOG:  database system is shut down
2024-04-04 03:22:05.200 UTC [36639][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-04-04 03:22:05.200 UTC [36639][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 51207
2024-04-04 03:22:05.200 UTC [36639][postmaster] LOG:  listening on Unix socket "/tmp/T7wIDMI4Jw/.s.PGSQL.51207"
2024-04-04 03:22:05.227 UTC [36655][startup] LOG:  database system was shut down at 2024-04-04 03:22:05 UTC
2024-04-04 03:22:05.235 UTC [36639][postmaster] LOG:  database system is ready to accept connections
2024-04-04 03:22:05.265 UTC [36678][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44224
2024-04-04 03:22:05.267 UTC [36678][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-04 03:22:05.267 UTC [36678][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-04 03:22:05.306 UTC [36683][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=44228
2024-04-04 03:22:05.312 UTC [36683][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-04-04 03:22:05.312 UTC [36683][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-04-04 03:22:05.360 UTC [36683][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-04-04 03:22:05.365 UTC [36683][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=ssltestuser database=trustdb host=localhost port=44228
2024-04-04 03:22:05.389 UTC [36639][postmaster] LOG:  received fast shutdown request
2024-04-04 03:22:05.389 UTC [36639][postmaster] LOG:  aborting any active transactions
2024-04-04 03:22:05.394 UTC [36639][postmaster] LOG:  background worker "logical replication launcher" (PID 36667) exited with exit code 1
2024-04-04 03:22:05.398 UTC [36653][checkpointer] LOG:  shutting down
2024-04-04 03:22:05.398 UTC [36653][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-04 03:22:05.398 UTC [36653][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/414D958, redo lsn=0/414D958
2024-04-04 03:22:05.414 UTC [36639][postmaster] LOG:  database system is shut down
2024-04-04 03:22:05.526 UTC [36704][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-04-04 03:22:05.526 UTC [36704][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[03:22:05.591](0.227s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"