[10:19:34.292](0.026s) # setting up data directory # Checking port 60541 # Found port 60541 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives Connection string: port=60541 host=/tmp/XV33ADncba Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [10:19:34.303](0.011s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 37562 [10:19:34.497](0.194s) ok 1 - ssl_library parameter psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37788 [10:19:35.283](0.786s) # testing password-protected keys ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-01 10:19:34.385 UTC [37562][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:34.385 UTC [37562][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:34.390 UTC [37569][startup] LOG: database system was shut down at 2024-04-01 10:17:11 UTC 2024-04-01 10:19:34.393 UTC [37562][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:34.491 UTC [37597][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.491 UTC [37597][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.491 UTC [37597][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.495 UTC [37597][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-01 10:19:34.496 UTC [37597][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.502 UTC [37599][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.503 UTC [37599][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.503 UTC [37599][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.503 UTC [37599][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-01 10:19:34.503 UTC [37599][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.504 UTC [37599][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.548 UTC [37609][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.548 UTC [37609][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.548 UTC [37609][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.596 UTC [37622][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.603 UTC [37622][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.603 UTC [37622][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.616 UTC [37622][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-01 10:19:34.616 UTC [37622][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.622 UTC [37622][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.646 UTC [37637][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.688 UTC [37637][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.688 UTC [37637][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.688 UTC [37637][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-01 10:19:34.688 UTC [37637][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.689 UTC [37637][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.042 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.778 UTC [37663][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.779 UTC [37663][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.779 UTC [37663][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.782 UTC [37663][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-01 10:19:34.782 UTC [37663][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.811 UTC [37663][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.820 UTC [37683][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.820 UTC [37683][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.820 UTC [37683][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.821 UTC [37683][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-01 10:19:34.821 UTC [37683][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.850 UTC [37683][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.884 UTC [37703][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.884 UTC [37703][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.884 UTC [37703][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.885 UTC [37703][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-01 10:19:34.885 UTC [37703][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.905 UTC [37703][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.932 UTC [37716][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.933 UTC [37716][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.933 UTC [37716][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.933 UTC [37716][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-01 10:19:34.933 UTC [37716][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.958 UTC [37716][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.982 UTC [37726][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.982 UTC [37726][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.982 UTC [37726][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.983 UTC [37726][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-01 10:19:34.983 UTC [37726][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.999 UTC [37726][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-04-01 10:19:35.029 UTC [37742][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:35.029 UTC [37742][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:35.029 UTC [37742][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:35.030 UTC [37742][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-01 10:19:35.030 UTC [37742][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:35.056 UTC [37742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-04-01 10:19:35.077 UTC [37562][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.077 UTC [37562][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.080 UTC [37562][postmaster] LOG: background worker "logical replication launcher" (PID 37576) exited with exit code 1 2024-04-01 10:19:35.080 UTC [37567][checkpointer] LOG: shutting down 2024-04-01 10:19:35.080 UTC [37567][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.134 UTC [37567][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.054 s, sync=0.001 s, total=0.054 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41007E8, redo lsn=0/41007E8 2024-04-01 10:19:35.137 UTC [37562][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:35.197 UTC [37796][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:35.199 UTC [37788][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:35.286 UTC [37788][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.286 UTC [37788][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.287 UTC [37788][postmaster] LOG: background worker "logical replication launcher" (PID 37800) exited with exit code 1 2024-04-01 10:19:35.287 UTC [37794][checkpointer] LOG: shutting down 2024-04-01 10:19:35.287 UTC [37794][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.288 UTC [37794][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/4100860, redo lsn=0/4100860 2024-04-01 10:19:35.290 UTC [37788][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.395 UTC [37845][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-01 10:19:35.395 UTC [37845][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:19:35.490](0.206s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 37872 [10:19:35.593](0.103s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-01 10:19:34.385 UTC [37562][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:34.385 UTC [37562][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:34.390 UTC [37569][startup] LOG: database system was shut down at 2024-04-01 10:17:11 UTC 2024-04-01 10:19:34.393 UTC [37562][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:34.491 UTC [37597][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.491 UTC [37597][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.491 UTC [37597][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.495 UTC [37597][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-01 10:19:34.496 UTC [37597][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.502 UTC [37599][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.503 UTC [37599][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.503 UTC [37599][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.503 UTC [37599][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-01 10:19:34.503 UTC [37599][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.504 UTC [37599][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.548 UTC [37609][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.548 UTC [37609][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.548 UTC [37609][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.596 UTC [37622][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.603 UTC [37622][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.603 UTC [37622][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.616 UTC [37622][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-01 10:19:34.616 UTC [37622][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.622 UTC [37622][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.646 UTC [37637][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.688 UTC [37637][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.688 UTC [37637][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.688 UTC [37637][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-01 10:19:34.688 UTC [37637][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.689 UTC [37637][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.042 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.778 UTC [37663][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.779 UTC [37663][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.779 UTC [37663][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.782 UTC [37663][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-01 10:19:34.782 UTC [37663][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.811 UTC [37663][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.820 UTC [37683][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.820 UTC [37683][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.820 UTC [37683][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.821 UTC [37683][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-01 10:19:34.821 UTC [37683][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.850 UTC [37683][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.884 UTC [37703][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.884 UTC [37703][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.884 UTC [37703][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.885 UTC [37703][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-01 10:19:34.885 UTC [37703][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.905 UTC [37703][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.932 UTC [37716][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.933 UTC [37716][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.933 UTC [37716][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.933 UTC [37716][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-01 10:19:34.933 UTC [37716][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.958 UTC [37716][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.982 UTC [37726][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.982 UTC [37726][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.982 UTC [37726][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.983 UTC [37726][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-01 10:19:34.983 UTC [37726][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.999 UTC [37726][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-04-01 10:19:35.029 UTC [37742][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:35.029 UTC [37742][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:35.029 UTC [37742][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:35.030 UTC [37742][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-01 10:19:35.030 UTC [37742][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:35.056 UTC [37742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-04-01 10:19:35.077 UTC [37562][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.077 UTC [37562][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.080 UTC [37562][postmaster] LOG: background worker "logical replication launcher" (PID 37576) exited with exit code 1 2024-04-01 10:19:35.080 UTC [37567][checkpointer] LOG: shutting down 2024-04-01 10:19:35.080 UTC [37567][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.134 UTC [37567][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.054 s, sync=0.001 s, total=0.054 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41007E8, redo lsn=0/41007E8 2024-04-01 10:19:35.137 UTC [37562][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:35.197 UTC [37796][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:35.199 UTC [37788][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:35.286 UTC [37788][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.286 UTC [37788][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.287 UTC [37788][postmaster] LOG: background worker "logical replication launcher" (PID 37800) exited with exit code 1 2024-04-01 10:19:35.287 UTC [37794][checkpointer] LOG: shutting down 2024-04-01 10:19:35.287 UTC [37794][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.288 UTC [37794][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/4100860, redo lsn=0/4100860 2024-04-01 10:19:35.290 UTC [37788][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.395 UTC [37845][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-01 10:19:35.395 UTC [37845][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.504 UTC [37872][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:35.504 UTC [37872][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:35.504 UTC [37872][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:35.505 UTC [37876][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:35.508 UTC [37872][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:35.595 UTC [37872][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.595 UTC [37872][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.598 UTC [37872][postmaster] LOG: background worker "logical replication launcher" (PID 37880) exited with exit code 1 2024-04-01 10:19:35.599 UTC [37874][checkpointer] LOG: shutting down 2024-04-01 10:19:35.599 UTC [37874][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.599 UTC [37874][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/41008D8, redo lsn=0/41008D8 2024-04-01 10:19:35.601 UTC [37872][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.708 UTC [37909][postmaster] FATAL: could not set SSL protocol version range 2024-04-01 10:19:35.708 UTC [37909][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-01 10:19:35.709 UTC [37909][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:19:35.805](0.213s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 37938 [10:19:35.908](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [10:19:35.909](0.000s) # running client tests ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37995 [10:19:36.127](0.218s) ok 6 - server doesn't accept non-SSL connections [10:19:36.127](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [10:19:36.143](0.015s) ok 8 - connect without server root cert sslmode=require [10:19:36.143](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [10:19:36.151](0.008s) ok 10 - connect without server root cert sslmode=verify-ca [10:19:36.151](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [10:19:36.159](0.008s) ok 12 - connect without server root cert sslmode=verify-full [10:19:36.159](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [10:19:36.171](0.012s) ok 14 - connect with wrong server root cert sslmode=require [10:19:36.171](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [10:19:36.182](0.011s) ok 16 - connect with wrong server root cert sslmode=verify-ca [10:19:36.182](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [10:19:36.193](0.011s) ok 18 - connect with wrong server root cert sslmode=verify-full [10:19:36.193](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [10:19:36.206](0.013s) ok 20 - connect with server CA cert, without root CA [10:19:36.207](0.001s) ok 21 - connect with server CA cert, without root CA: matches [10:19:36.220](0.013s) ok 22 - connect with correct server CA cert file sslmode=require [10:19:36.220](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [10:19:36.233](0.013s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [10:19:36.233](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [10:19:36.247](0.014s) ok 26 - connect with correct server CA cert file sslmode=verify-full [10:19:36.248](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [10:19:36.261](0.013s) ok 28 - cert root file that contains two certificates, order 1 [10:19:36.261](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [10:19:36.274](0.013s) ok 30 - cert root file that contains two certificates, order 2 [10:19:36.274](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [10:19:36.287](0.013s) ok 32 - connect with sslcertmode=disable [10:19:36.287](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [10:19:36.300](0.013s) ok 34 - connect with sslcertmode=allow [10:19:36.301](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [10:19:36.315](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [10:19:36.315](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [10:19:36.328](0.013s) ok 38 - sslcrl option with invalid file name [10:19:36.328](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [10:19:36.339](0.011s) ok 40 - CRL belonging to a different CA [10:19:36.339](0.000s) ok 41 - CRL belonging to a different CA: matches [10:19:36.350](0.011s) ok 42 - directory CRL belonging to a different CA [10:19:36.350](0.000s) ok 43 - directory CRL belonging to a different CA: matches [10:19:36.363](0.013s) ok 44 - CRL with a non-revoked cert [10:19:36.363](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [10:19:36.376](0.013s) ok 46 - directory CRL with a non-revoked cert [10:19:36.376](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [10:19:36.389](0.012s) ok 48 - mismatch between host name and server certificate sslmode=require [10:19:36.389](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [10:19:36.400](0.012s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [10:19:36.401](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [10:19:36.410](0.010s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [10:19:36.411](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38103 [10:19:36.638](0.228s) ok 54 - IP address in the Common Name [10:19:36.639](0.000s) ok 55 - IP address in the Common Name: no stderr [10:19:36.652](0.013s) ok 56 - mismatch between host name and server certificate IP address [10:19:36.652](0.000s) ok 57 - mismatch between host name and server certificate IP address: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38133 [10:19:36.882](0.230s) ok 58 - IP address in a dNSName [10:19:36.883](0.000s) ok 59 - IP address in a dNSName: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38157 [10:19:37.109](0.226s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [10:19:37.109](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [10:19:37.122](0.013s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [10:19:37.122](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [10:19:37.136](0.013s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [10:19:37.136](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [10:19:37.147](0.011s) ok 66 - host name not matching with X.509 Subject Alternative Names [10:19:37.147](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [10:19:37.159](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [10:19:37.159](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38193 [10:19:37.395](0.235s) ok 70 - host name matching with a single X.509 Subject Alternative Name [10:19:37.395](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [10:19:37.407](0.012s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [10:19:37.407](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [10:19:37.419](0.012s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [10:19:37.419](0.000s) ok 75 - host name not matching with a single X.509 Subject Alternative Name wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38220 [10:19:37.652](0.233s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [10:19:37.653](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [10:19:37.668](0.016s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [10:19:37.669](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [10:19:37.682](0.014s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [10:19:37.683](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [10:19:37.697](0.015s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [10:19:37.697](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [10:19:37.712](0.014s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [10:19:37.712](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [10:19:37.726](0.014s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [10:19:37.726](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [10:19:37.738](0.012s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [10:19:37.738](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [10:19:37.749](0.011s) ok 90 - IPv6 host with CIDR mask does not match [10:19:37.750](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38261 [10:19:37.983](0.233s) ok 92 - certificate with both a CN and SANs 1 [10:19:37.983](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [10:19:37.995](0.012s) ok 94 - certificate with both a CN and SANs 2 [10:19:37.995](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [10:19:38.007](0.011s) ok 96 - certificate with both a CN and SANs ignores CN [10:19:38.007](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38280 [10:19:38.235](0.228s) ok 98 - certificate with both a CN and IP SANs matches CN [10:19:38.235](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [10:19:38.246](0.011s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [10:19:38.247](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [10:19:38.258](0.011s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [10:19:38.258](0.000s) ok 103 - certificate with both a CN and IP SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38295 [10:19:38.490](0.232s) ok 104 - certificate with both an IP CN and IP SANs 1 [10:19:38.490](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [10:19:38.502](0.012s) ok 106 - certificate with both an IP CN and IP SANs 2 [10:19:38.502](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [10:19:38.512](0.010s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [10:19:38.513](0.000s) ok 109 - certificate with both an IP CN and IP SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38309 [10:19:38.745](0.232s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [10:19:38.745](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [10:19:38.757](0.012s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [10:19:38.757](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [10:19:38.769](0.012s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [10:19:38.769](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38323 [10:19:39.001](0.232s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [10:19:39.002](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [10:19:39.012](0.010s) ok 118 - server certificate without CN or SANs sslmode=verify-full [10:19:39.012](0.000s) ok 119 - server certificate without CN or SANs sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38335 [10:19:39.239](0.227s) ok 120 - sslrootcert=system does not connect with private CA [10:19:39.239](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [10:19:39.244](0.005s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [10:19:39.244](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [10:19:39.259](0.015s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [10:19:39.260](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [10:19:39.269](0.010s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [10:19:39.270](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38350 [10:19:39.502](0.233s) ok 128 - connects without client-side CRL [10:19:39.503](0.000s) ok 129 - connects without client-side CRL: no stderr [10:19:39.514](0.012s) ok 130 - does not connect with client-side CRL file [10:19:39.515](0.000s) ok 131 - does not connect with client-side CRL file: matches [10:19:39.526](0.011s) ok 132 - does not connect with client-side CRL directory [10:19:39.526](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [10:19:39.538](0.013s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [10:19:39.539](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [10:19:39.539](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [10:19:39.551](0.012s) ok 137 - connection success with correct range of TLS protocol versions [10:19:39.551](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [10:19:39.556](0.005s) ok 139 - connection failure with incorrect range of TLS protocol versions [10:19:39.556](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [10:19:39.560](0.004s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [10:19:39.560](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [10:19:39.565](0.004s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [10:19:39.565](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [10:19:39.565](0.000s) # running server tests [10:19:39.575](0.009s) ok 145 - certificate authorization fails without client cert [10:19:39.575](0.000s) ok 146 - certificate authorization fails without client cert: matches [10:19:39.591](0.016s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [10:19:39.591](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [10:19:39.603](0.012s) ok 149 - certificate authorization succeeds with correct client cert in DER format [10:19:39.603](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [10:19:39.616](0.013s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [10:19:39.616](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [10:19:39.628](0.012s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [10:19:39.628](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [10:19:39.641](0.012s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [10:19:39.641](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [10:19:39.653](0.012s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [10:19:39.653](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [10:19:39.663](0.010s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [10:19:39.663](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [10:19:39.671](0.008s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [10:19:39.671](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [10:19:39.688](0.017s) ok 163 - certificate authorization succeeds with DN mapping [10:19:39.688](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [10:19:39.689](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [10:19:39.705](0.017s) ok 166 - certificate authorization succeeds with DN regex mapping [10:19:39.706](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [10:19:39.722](0.016s) ok 168 - certificate authorization succeeds with CN mapping [10:19:39.722](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [10:19:39.723](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [10:19:39.723](0.000s) not ok 171 # TODO & SKIP Need Pty support [10:19:39.723](0.000s) not ok 172 # TODO & SKIP Need Pty support [10:19:39.723](0.000s) not ok 173 # TODO & SKIP Need Pty support [10:19:39.723](0.000s) not ok 174 # TODO & SKIP Need Pty support # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [10:19:39.761](0.037s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [10:19:39.761](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [10:19:39.761](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [10:19:39.768](0.007s) ok 178 - certificate authorization fails because of file permissions [10:19:39.768](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [10:19:39.781](0.012s) ok 180 - certificate authorization fails with client cert belonging to another user [10:19:39.781](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [10:19:39.792](0.011s) ok 182 - certificate authorization fails with revoked client cert [10:19:39.792](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [10:19:39.792](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [10:19:39.808](0.016s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [10:19:39.808](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [10:19:39.808](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [10:19:39.819](0.011s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [10:19:39.820](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [10:19:39.820](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [10:19:39.832](0.012s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [10:19:39.832](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [10:19:39.832](0.000s) ok 193 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: log matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38410 [10:19:40.067](0.234s) ok 194 - intermediate client certificate is provided by client [10:19:40.067](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [10:19:40.078](0.011s) ok 196 - intermediate client certificate is missing [10:19:40.078](0.000s) ok 197 - intermediate client certificate is missing: matches [10:19:40.089](0.011s) ok 198 - logged client certificate Subjects are truncated if they're too long [10:19:40.090](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38424 [10:19:40.317](0.228s) ok 200 - intermediate client certificate is untrusted [10:19:40.317](0.000s) ok 201 - intermediate client certificate is untrusted: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38434 [10:19:40.547](0.229s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [10:19:40.547](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [10:19:40.560](0.013s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [10:19:40.560](0.000s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38446 [10:19:40.785](0.225s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [10:19:40.785](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [10:19:40.785](0.000s) # got: '2' # expected: '0' [10:19:40.786](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [10:19:40.786](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [10:19:40.786](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 60541 failed: SSL SYSCALL error: EOF detected' # expected: '' [10:19:40.801](0.015s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [10:19:40.801](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38458 [10:19:41.026](0.225s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [10:19:41.041](0.015s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [10:19:41.041](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38470 [10:19:41.266](0.225s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [10:19:41.282](0.015s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [10:19:41.282](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38482 [10:19:41.507](0.225s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [10:19:41.524](0.016s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [10:19:41.524](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38494 [10:19:41.748](0.223s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [10:19:41.748](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [10:19:41.748](0.000s) # got: '2' # expected: '0' [10:19:41.748](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [10:19:41.748](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [10:19:41.748](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 60541 failed: SSL SYSCALL error: EOF detected' # expected: '' [10:19:41.767](0.019s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [10:19:41.767](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38506 [10:19:41.994](0.227s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [10:19:42.015](0.020s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [10:19:42.015](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38518 [10:19:42.244](0.229s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [10:19:42.263](0.019s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [10:19:42.263](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38530 [10:19:42.491](0.228s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [10:19:42.508](0.017s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [10:19:42.508](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 38542 [10:19:42.734](0.226s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [10:19:42.750](0.016s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [10:19:42.750](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-01 10:19:34.385 UTC [37562][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:34.385 UTC [37562][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:34.390 UTC [37569][startup] LOG: database system was shut down at 2024-04-01 10:17:11 UTC 2024-04-01 10:19:34.393 UTC [37562][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:34.491 UTC [37597][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.491 UTC [37597][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.491 UTC [37597][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.495 UTC [37597][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-01 10:19:34.496 UTC [37597][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.502 UTC [37599][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.503 UTC [37599][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.503 UTC [37599][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.503 UTC [37599][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-01 10:19:34.503 UTC [37599][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.504 UTC [37599][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.548 UTC [37609][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.548 UTC [37609][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.548 UTC [37609][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.549 UTC [37609][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.596 UTC [37622][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.603 UTC [37622][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.603 UTC [37622][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.616 UTC [37622][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-01 10:19:34.616 UTC [37622][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.622 UTC [37622][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.646 UTC [37637][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.688 UTC [37637][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.688 UTC [37637][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.688 UTC [37637][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-01 10:19:34.688 UTC [37637][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-04-01 10:19:34.689 UTC [37637][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.042 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.778 UTC [37663][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.779 UTC [37663][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.779 UTC [37663][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.782 UTC [37663][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-01 10:19:34.782 UTC [37663][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.811 UTC [37663][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.820 UTC [37683][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.820 UTC [37683][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.820 UTC [37683][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.821 UTC [37683][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-01 10:19:34.821 UTC [37683][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.850 UTC [37683][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.884 UTC [37703][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.884 UTC [37703][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.884 UTC [37703][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.885 UTC [37703][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-01 10:19:34.885 UTC [37703][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.905 UTC [37703][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.932 UTC [37716][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.933 UTC [37716][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.933 UTC [37716][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.933 UTC [37716][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-01 10:19:34.933 UTC [37716][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.958 UTC [37716][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-04-01 10:19:34.982 UTC [37726][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:34.982 UTC [37726][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:34.982 UTC [37726][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:34.983 UTC [37726][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-01 10:19:34.983 UTC [37726][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:34.999 UTC [37726][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-04-01 10:19:35.029 UTC [37742][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:19:35.029 UTC [37742][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:19:35.029 UTC [37742][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-04-01 10:19:35.030 UTC [37742][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-01 10:19:35.030 UTC [37742][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-04-01 10:19:35.056 UTC [37742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-04-01 10:19:35.077 UTC [37562][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.077 UTC [37562][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.080 UTC [37562][postmaster] LOG: background worker "logical replication launcher" (PID 37576) exited with exit code 1 2024-04-01 10:19:35.080 UTC [37567][checkpointer] LOG: shutting down 2024-04-01 10:19:35.080 UTC [37567][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.134 UTC [37567][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.054 s, sync=0.001 s, total=0.054 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41007E8, redo lsn=0/41007E8 2024-04-01 10:19:35.137 UTC [37562][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:35.195 UTC [37788][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:35.197 UTC [37796][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:35.199 UTC [37788][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:35.286 UTC [37788][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.286 UTC [37788][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.287 UTC [37788][postmaster] LOG: background worker "logical replication launcher" (PID 37800) exited with exit code 1 2024-04-01 10:19:35.287 UTC [37794][checkpointer] LOG: shutting down 2024-04-01 10:19:35.287 UTC [37794][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.288 UTC [37794][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/4100860, redo lsn=0/4100860 2024-04-01 10:19:35.290 UTC [37788][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.395 UTC [37845][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-01 10:19:35.395 UTC [37845][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.504 UTC [37872][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:35.504 UTC [37872][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:35.504 UTC [37872][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:35.505 UTC [37876][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:35.508 UTC [37872][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:35.595 UTC [37872][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.595 UTC [37872][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.598 UTC [37872][postmaster] LOG: background worker "logical replication launcher" (PID 37880) exited with exit code 1 2024-04-01 10:19:35.599 UTC [37874][checkpointer] LOG: shutting down 2024-04-01 10:19:35.599 UTC [37874][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.599 UTC [37874][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/41008D8, redo lsn=0/41008D8 2024-04-01 10:19:35.601 UTC [37872][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.708 UTC [37909][postmaster] FATAL: could not set SSL protocol version range 2024-04-01 10:19:35.708 UTC [37909][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-01 10:19:35.709 UTC [37909][postmaster] LOG: database system is shut down 2024-04-01 10:19:35.819 UTC [37938][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:35.819 UTC [37938][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:35.819 UTC [37938][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:35.821 UTC [37943][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:35.823 UTC [37938][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:35.911 UTC [37938][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:35.911 UTC [37938][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:35.913 UTC [37938][postmaster] LOG: background worker "logical replication launcher" (PID 37947) exited with exit code 1 2024-04-01 10:19:35.913 UTC [37941][checkpointer] LOG: shutting down 2024-04-01 10:19:35.913 UTC [37941][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:35.914 UTC [37941][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/4100950, redo lsn=0/4100950 2024-04-01 10:19:35.915 UTC [37938][postmaster] LOG: database system is shut down 2024-04-01 10:19:36.029 UTC [37995][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:36.029 UTC [37995][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:36.029 UTC [37995][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:36.032 UTC [38001][startup] LOG: database system was shut down at 2024-04-01 10:19:35 UTC 2024-04-01 10:19:36.035 UTC [37995][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:36.125 UTC [38028][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61846 2024-04-01 10:19:36.126 UTC [38028][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-01 10:19:36.126 UTC [38028][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-04-01 10:19:36.132 UTC [38031][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61848 2024-04-01 10:19:36.136 UTC [38031][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.136 UTC [38031][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-01 10:19:36.141 UTC [38031][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-01 10:19:36.141 UTC [38031][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61848 2024-04-01 10:19:36.148 UTC [38036][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61850 2024-04-01 10:19:36.149 UTC [38036][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:19:36.156 UTC [38039][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61852 2024-04-01 10:19:36.158 UTC [38039][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:19:36.165 UTC [38042][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61853 2024-04-01 10:19:36.170 UTC [38042][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:36.177 UTC [38045][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61854 2024-04-01 10:19:36.181 UTC [38045][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:36.188 UTC [38048][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61856 2024-04-01 10:19:36.191 UTC [38048][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:36.200 UTC [38055][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61858 2024-04-01 10:19:36.205 UTC [38055][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:36.213 UTC [38057][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61859 2024-04-01 10:19:36.217 UTC [38057][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.217 UTC [38057][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-01 10:19:36.218 UTC [38057][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-01 10:19:36.219 UTC [38057][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61859 2024-04-01 10:19:36.226 UTC [38059][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61860 2024-04-01 10:19:36.230 UTC [38059][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.230 UTC [38059][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-01 10:19:36.231 UTC [38059][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-01 10:19:36.232 UTC [38059][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61860 2024-04-01 10:19:36.240 UTC [38061][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61861 2024-04-01 10:19:36.244 UTC [38061][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.244 UTC [38061][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-01 10:19:36.245 UTC [38061][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-01 10:19:36.246 UTC [38061][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61861 2024-04-01 10:19:36.254 UTC [38063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61862 2024-04-01 10:19:36.258 UTC [38063][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.258 UTC [38063][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-01 10:19:36.259 UTC [38063][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-01 10:19:36.259 UTC [38063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61862 2024-04-01 10:19:36.267 UTC [38065][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61863 2024-04-01 10:19:36.271 UTC [38065][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.271 UTC [38065][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-01 10:19:36.272 UTC [38065][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-01 10:19:36.273 UTC [38065][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61863 2024-04-01 10:19:36.280 UTC [38067][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61864 2024-04-01 10:19:36.285 UTC [38067][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.285 UTC [38067][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-01 10:19:36.286 UTC [38067][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-01 10:19:36.286 UTC [38067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61864 2024-04-01 10:19:36.293 UTC [38069][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61865 2024-04-01 10:19:36.298 UTC [38069][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.298 UTC [38069][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-01 10:19:36.298 UTC [38069][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-01 10:19:36.299 UTC [38069][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61865 2024-04-01 10:19:36.307 UTC [38072][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61866 2024-04-01 10:19:36.312 UTC [38072][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.312 UTC [38072][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-01 10:19:36.313 UTC [38072][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61866 2024-04-01 10:19:36.321 UTC [38080][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61867 2024-04-01 10:19:36.325 UTC [38080][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.325 UTC [38080][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-01 10:19:36.326 UTC [38080][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-01 10:19:36.326 UTC [38080][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61867 2024-04-01 10:19:36.334 UTC [38082][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61868 2024-04-01 10:19:36.338 UTC [38082][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:36.345 UTC [38084][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61869 2024-04-01 10:19:36.349 UTC [38084][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:36.356 UTC [38087][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61870 2024-04-01 10:19:36.361 UTC [38087][client backend] [[unknown]][11/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.361 UTC [38087][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-01 10:19:36.361 UTC [38087][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-01 10:19:36.362 UTC [38087][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61870 2024-04-01 10:19:36.369 UTC [38090][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61871 2024-04-01 10:19:36.374 UTC [38090][client backend] [[unknown]][12/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.374 UTC [38090][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-01 10:19:36.375 UTC [38090][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-01 10:19:36.375 UTC [38090][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61871 2024-04-01 10:19:36.382 UTC [38092][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61872 2024-04-01 10:19:36.386 UTC [38092][client backend] [[unknown]][13/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.387 UTC [38092][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-01 10:19:36.387 UTC [38092][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-01 10:19:36.388 UTC [38092][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61872 2024-04-01 10:19:36.394 UTC [38094][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61873 2024-04-01 10:19:36.398 UTC [38094][client backend] [[unknown]][14/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.398 UTC [38094][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-01 10:19:36.399 UTC [38094][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-01 10:19:36.399 UTC [38094][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61873 2024-04-01 10:19:36.406 UTC [38097][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61874 2024-04-01 10:19:36.412 UTC [37995][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:36.412 UTC [37995][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:36.414 UTC [37995][postmaster] LOG: background worker "logical replication launcher" (PID 38005) exited with exit code 1 2024-04-01 10:19:36.414 UTC [37999][checkpointer] LOG: shutting down 2024-04-01 10:19:36.414 UTC [37999][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:36.415 UTC [37999][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/41009C8, redo lsn=0/41009C8 2024-04-01 10:19:36.416 UTC [37995][postmaster] LOG: database system is shut down 2024-04-01 10:19:36.526 UTC [38103][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:36.526 UTC [38103][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:36.526 UTC [38103][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:36.528 UTC [38108][startup] LOG: database system was shut down at 2024-04-01 10:19:36 UTC 2024-04-01 10:19:36.532 UTC [38103][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:36.626 UTC [38120][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61877 2024-04-01 10:19:36.632 UTC [38120][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.632 UTC [38120][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-01 10:19:36.636 UTC [38120][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-01 10:19:36.637 UTC [38120][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61877 2024-04-01 10:19:36.645 UTC [38124][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61879 2024-04-01 10:19:36.656 UTC [38103][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:36.656 UTC [38103][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:36.658 UTC [38103][postmaster] LOG: background worker "logical replication launcher" (PID 38112) exited with exit code 1 2024-04-01 10:19:36.658 UTC [38106][checkpointer] LOG: shutting down 2024-04-01 10:19:36.658 UTC [38106][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:36.658 UTC [38106][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/4100A40, redo lsn=0/4100A40 2024-04-01 10:19:36.660 UTC [38103][postmaster] LOG: database system is shut down 2024-04-01 10:19:36.776 UTC [38133][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:36.776 UTC [38133][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:36.776 UTC [38133][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:36.778 UTC [38142][startup] LOG: database system was shut down at 2024-04-01 10:19:36 UTC 2024-04-01 10:19:36.781 UTC [38133][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:36.870 UTC [38149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61881 2024-04-01 10:19:36.875 UTC [38149][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:36.875 UTC [38149][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-01 10:19:36.880 UTC [38149][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-01 10:19:36.881 UTC [38149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61881 2024-04-01 10:19:36.885 UTC [38133][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:36.885 UTC [38133][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:36.886 UTC [38133][postmaster] LOG: background worker "logical replication launcher" (PID 38145) exited with exit code 1 2024-04-01 10:19:36.887 UTC [38140][checkpointer] LOG: shutting down 2024-04-01 10:19:36.887 UTC [38140][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:36.887 UTC [38140][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/4100AB8, redo lsn=0/4100AB8 2024-04-01 10:19:36.889 UTC [38133][postmaster] LOG: database system is shut down 2024-04-01 10:19:37.003 UTC [38157][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:37.003 UTC [38157][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:37.003 UTC [38157][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:37.005 UTC [38164][startup] LOG: database system was shut down at 2024-04-01 10:19:36 UTC 2024-04-01 10:19:37.009 UTC [38157][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:37.098 UTC [38173][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61883 2024-04-01 10:19:37.103 UTC [38173][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.103 UTC [38173][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-01 10:19:37.107 UTC [38173][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-01 10:19:37.107 UTC [38173][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61883 2024-04-01 10:19:37.115 UTC [38176][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61884 2024-04-01 10:19:37.120 UTC [38176][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.120 UTC [38176][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-01 10:19:37.120 UTC [38176][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-01 10:19:37.121 UTC [38176][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61884 2024-04-01 10:19:37.128 UTC [38178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61885 2024-04-01 10:19:37.133 UTC [38178][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.133 UTC [38178][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-01 10:19:37.134 UTC [38178][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-01 10:19:37.134 UTC [38178][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61885 2024-04-01 10:19:37.141 UTC [38180][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61886 2024-04-01 10:19:37.154 UTC [38182][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61887 2024-04-01 10:19:37.161 UTC [38157][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:37.161 UTC [38157][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:37.162 UTC [38157][postmaster] LOG: background worker "logical replication launcher" (PID 38167) exited with exit code 1 2024-04-01 10:19:37.162 UTC [38162][checkpointer] LOG: shutting down 2024-04-01 10:19:37.163 UTC [38162][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:37.163 UTC [38162][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/4100B30, redo lsn=0/4100B30 2024-04-01 10:19:37.164 UTC [38157][postmaster] LOG: database system is shut down 2024-04-01 10:19:37.280 UTC [38193][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:37.281 UTC [38193][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:37.281 UTC [38193][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:37.283 UTC [38196][startup] LOG: database system was shut down at 2024-04-01 10:19:37 UTC 2024-04-01 10:19:37.285 UTC [38193][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:37.382 UTC [38205][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61889 2024-04-01 10:19:37.388 UTC [38205][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.388 UTC [38205][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-01 10:19:37.392 UTC [38205][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-01 10:19:37.393 UTC [38205][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61889 2024-04-01 10:19:37.401 UTC [38208][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61890 2024-04-01 10:19:37.413 UTC [38210][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61891 2024-04-01 10:19:37.425 UTC [38193][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:37.425 UTC [38193][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:37.427 UTC [38193][postmaster] LOG: background worker "logical replication launcher" (PID 38199) exited with exit code 1 2024-04-01 10:19:37.427 UTC [38194][checkpointer] LOG: shutting down 2024-04-01 10:19:37.427 UTC [38194][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:37.427 UTC [38194][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/4100BA8, redo lsn=0/4100BA8 2024-04-01 10:19:37.429 UTC [38193][postmaster] LOG: database system is shut down 2024-04-01 10:19:37.544 UTC [38220][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:37.544 UTC [38220][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:37.544 UTC [38220][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:37.546 UTC [38224][startup] LOG: database system was shut down at 2024-04-01 10:19:37 UTC 2024-04-01 10:19:37.550 UTC [38220][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:37.640 UTC [38233][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61893 2024-04-01 10:19:37.645 UTC [38233][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.645 UTC [38233][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-01 10:19:37.650 UTC [38233][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-01 10:19:37.651 UTC [38233][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61893 2024-04-01 10:19:37.660 UTC [38235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61894 2024-04-01 10:19:37.665 UTC [38235][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.665 UTC [38235][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-01 10:19:37.666 UTC [38235][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-01 10:19:37.667 UTC [38235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=61894 2024-04-01 10:19:37.676 UTC [38238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61895 2024-04-01 10:19:37.690 UTC [38244][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61896 2024-04-01 10:19:37.695 UTC [38244][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.695 UTC [38244][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-01 10:19:37.696 UTC [38244][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-01 10:19:37.696 UTC [38244][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61896 2024-04-01 10:19:37.704 UTC [38248][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61897 2024-04-01 10:19:37.709 UTC [38248][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.709 UTC [38248][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-01 10:19:37.710 UTC [38248][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-01 10:19:37.711 UTC [38248][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61897 2024-04-01 10:19:37.718 UTC [38252][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61898 2024-04-01 10:19:37.723 UTC [38252][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.723 UTC [38252][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-01 10:19:37.724 UTC [38252][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-01 10:19:37.724 UTC [38252][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61898 2024-04-01 10:19:37.732 UTC [38254][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61899 2024-04-01 10:19:37.744 UTC [38256][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61900 2024-04-01 10:19:37.751 UTC [38220][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:37.751 UTC [38220][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:37.753 UTC [38220][postmaster] LOG: background worker "logical replication launcher" (PID 38228) exited with exit code 1 2024-04-01 10:19:37.753 UTC [38222][checkpointer] LOG: shutting down 2024-04-01 10:19:37.753 UTC [38222][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:37.753 UTC [38222][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/4100C20, redo lsn=0/4100C20 2024-04-01 10:19:37.755 UTC [38220][postmaster] LOG: database system is shut down 2024-04-01 10:19:37.870 UTC [38261][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:37.870 UTC [38261][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:37.870 UTC [38261][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:37.871 UTC [38265][startup] LOG: database system was shut down at 2024-04-01 10:19:37 UTC 2024-04-01 10:19:37.874 UTC [38261][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:37.971 UTC [38271][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61902 2024-04-01 10:19:37.976 UTC [38271][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.976 UTC [38271][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-01 10:19:37.981 UTC [38271][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-01 10:19:37.981 UTC [38271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61902 2024-04-01 10:19:37.988 UTC [38273][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61903 2024-04-01 10:19:37.993 UTC [38273][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:37.993 UTC [38273][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-01 10:19:37.994 UTC [38273][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-01 10:19:37.994 UTC [38273][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61903 2024-04-01 10:19:38.001 UTC [38275][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61904 2024-04-01 10:19:38.011 UTC [38261][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:38.011 UTC [38261][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:38.013 UTC [38261][postmaster] LOG: background worker "logical replication launcher" (PID 38268) exited with exit code 1 2024-04-01 10:19:38.013 UTC [38263][checkpointer] LOG: shutting down 2024-04-01 10:19:38.013 UTC [38263][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:38.013 UTC [38263][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/4100C98, redo lsn=0/4100C98 2024-04-01 10:19:38.014 UTC [38261][postmaster] LOG: database system is shut down 2024-04-01 10:19:38.124 UTC [38280][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:38.124 UTC [38280][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:38.124 UTC [38280][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:38.125 UTC [38283][startup] LOG: database system was shut down at 2024-04-01 10:19:38 UTC 2024-04-01 10:19:38.128 UTC [38280][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:38.224 UTC [38289][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61905 2024-04-01 10:19:38.229 UTC [38289][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.229 UTC [38289][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-01 10:19:38.233 UTC [38289][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-01 10:19:38.234 UTC [38289][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61905 2024-04-01 10:19:38.240 UTC [38291][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61906 2024-04-01 10:19:38.244 UTC [38291][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.244 UTC [38291][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-01 10:19:38.245 UTC [38291][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-01 10:19:38.245 UTC [38291][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61906 2024-04-01 10:19:38.251 UTC [38293][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61907 2024-04-01 10:19:38.256 UTC [38293][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.256 UTC [38293][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-01 10:19:38.257 UTC [38293][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-01 10:19:38.257 UTC [38293][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61907 2024-04-01 10:19:38.260 UTC [38280][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:38.260 UTC [38280][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:38.261 UTC [38280][postmaster] LOG: background worker "logical replication launcher" (PID 38286) exited with exit code 1 2024-04-01 10:19:38.261 UTC [38281][checkpointer] LOG: shutting down 2024-04-01 10:19:38.261 UTC [38281][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:38.261 UTC [38281][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/4100D10, redo lsn=0/4100D10 2024-04-01 10:19:38.262 UTC [38280][postmaster] LOG: database system is shut down 2024-04-01 10:19:38.378 UTC [38295][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:38.378 UTC [38295][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:38.378 UTC [38295][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:38.379 UTC [38298][startup] LOG: database system was shut down at 2024-04-01 10:19:38 UTC 2024-04-01 10:19:38.382 UTC [38295][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:38.479 UTC [38303][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61908 2024-04-01 10:19:38.484 UTC [38303][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.484 UTC [38303][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-01 10:19:38.488 UTC [38303][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-01 10:19:38.489 UTC [38303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61908 2024-04-01 10:19:38.495 UTC [38305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61909 2024-04-01 10:19:38.500 UTC [38305][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.500 UTC [38305][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-01 10:19:38.501 UTC [38305][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-01 10:19:38.501 UTC [38305][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61909 2024-04-01 10:19:38.507 UTC [38307][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61910 2024-04-01 10:19:38.514 UTC [38295][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:38.514 UTC [38295][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:38.516 UTC [38295][postmaster] LOG: background worker "logical replication launcher" (PID 38301) exited with exit code 1 2024-04-01 10:19:38.516 UTC [38296][checkpointer] LOG: shutting down 2024-04-01 10:19:38.516 UTC [38296][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:38.516 UTC [38296][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/4100D88, redo lsn=0/4100D88 2024-04-01 10:19:38.517 UTC [38295][postmaster] LOG: database system is shut down 2024-04-01 10:19:38.632 UTC [38309][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:38.632 UTC [38309][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:38.632 UTC [38309][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:38.633 UTC [38312][startup] LOG: database system was shut down at 2024-04-01 10:19:38 UTC 2024-04-01 10:19:38.636 UTC [38309][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:38.734 UTC [38317][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61911 2024-04-01 10:19:38.739 UTC [38317][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.739 UTC [38317][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-01 10:19:38.743 UTC [38317][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-01 10:19:38.744 UTC [38317][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61911 2024-04-01 10:19:38.751 UTC [38319][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61912 2024-04-01 10:19:38.755 UTC [38319][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.755 UTC [38319][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-01 10:19:38.756 UTC [38319][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-01 10:19:38.756 UTC [38319][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=61912 2024-04-01 10:19:38.762 UTC [38321][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61913 2024-04-01 10:19:38.767 UTC [38321][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.767 UTC [38321][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-01 10:19:38.768 UTC [38321][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-01 10:19:38.768 UTC [38321][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61913 2024-04-01 10:19:38.771 UTC [38309][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:38.771 UTC [38309][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:38.772 UTC [38309][postmaster] LOG: background worker "logical replication launcher" (PID 38315) exited with exit code 1 2024-04-01 10:19:38.772 UTC [38310][checkpointer] LOG: shutting down 2024-04-01 10:19:38.772 UTC [38310][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:38.772 UTC [38310][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/4100E00, redo lsn=0/4100E00 2024-04-01 10:19:38.773 UTC [38309][postmaster] LOG: database system is shut down 2024-04-01 10:19:38.889 UTC [38323][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:38.889 UTC [38323][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:38.889 UTC [38323][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:38.890 UTC [38326][startup] LOG: database system was shut down at 2024-04-01 10:19:38 UTC 2024-04-01 10:19:38.893 UTC [38323][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:38.990 UTC [38331][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61914 2024-04-01 10:19:38.995 UTC [38331][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:38.995 UTC [38331][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-01 10:19:39.000 UTC [38331][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-01 10:19:39.000 UTC [38331][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61914 2024-04-01 10:19:39.007 UTC [38333][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61915 2024-04-01 10:19:39.014 UTC [38323][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:39.014 UTC [38323][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:39.015 UTC [38323][postmaster] LOG: background worker "logical replication launcher" (PID 38329) exited with exit code 1 2024-04-01 10:19:39.015 UTC [38324][checkpointer] LOG: shutting down 2024-04-01 10:19:39.015 UTC [38324][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:39.015 UTC [38324][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/4100E78, redo lsn=0/4100E78 2024-04-01 10:19:39.016 UTC [38323][postmaster] LOG: database system is shut down 2024-04-01 10:19:39.131 UTC [38335][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:39.132 UTC [38335][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:39.132 UTC [38335][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:39.133 UTC [38338][startup] LOG: database system was shut down at 2024-04-01 10:19:39 UTC 2024-04-01 10:19:39.135 UTC [38335][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:39.234 UTC [38343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61916 2024-04-01 10:19:39.238 UTC [38343][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:19:39.249 UTC [38346][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61917 2024-04-01 10:19:39.254 UTC [38346][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:39.254 UTC [38346][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-01 10:19:39.258 UTC [38346][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-01 10:19:39.259 UTC [38346][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61917 2024-04-01 10:19:39.265 UTC [38348][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61918 2024-04-01 10:19:39.271 UTC [38335][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:39.271 UTC [38335][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:39.272 UTC [38335][postmaster] LOG: background worker "logical replication launcher" (PID 38341) exited with exit code 1 2024-04-01 10:19:39.272 UTC [38336][checkpointer] LOG: shutting down 2024-04-01 10:19:39.272 UTC [38336][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:39.273 UTC [38336][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/4100EF0, redo lsn=0/4100EF0 2024-04-01 10:19:39.274 UTC [38335][postmaster] LOG: database system is shut down 2024-04-01 10:19:39.389 UTC [38350][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:39.389 UTC [38350][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:39.389 UTC [38350][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:39.391 UTC [38353][startup] LOG: database system was shut down at 2024-04-01 10:19:39 UTC 2024-04-01 10:19:39.393 UTC [38350][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:39.491 UTC [38358][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61919 2024-04-01 10:19:39.496 UTC [38358][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:39.496 UTC [38358][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-01 10:19:39.500 UTC [38358][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-01 10:19:39.501 UTC [38358][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61919 2024-04-01 10:19:39.508 UTC [38360][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61920 2024-04-01 10:19:39.513 UTC [38360][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-04-01 10:19:39.520 UTC [38362][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61921 2024-04-01 10:19:39.524 UTC [38362][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-04-01 10:19:39.531 UTC [38364][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61922 2024-04-01 10:19:39.535 UTC [38364][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:39.535 UTC [38364][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-01 10:19:39.536 UTC [38364][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-01 10:19:39.538 UTC [38364][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=61922 2024-04-01 10:19:39.544 UTC [38366][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61923 2024-04-01 10:19:39.549 UTC [38366][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:39.549 UTC [38366][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-01 10:19:39.549 UTC [38366][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-01 10:19:39.550 UTC [38366][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=61923 2024-04-01 10:19:39.569 UTC [38371][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61924 2024-04-01 10:19:39.574 UTC [38371][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-04-01 10:19:39.579 UTC [38373][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61925 2024-04-01 10:19:39.585 UTC [38373][client backend] [[unknown]][4/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.585 UTC [38373][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-01 10:19:39.589 UTC [38373][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client.key$$ 2024-04-01 10:19:39.590 UTC [38373][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=61925 2024-04-01 10:19:39.595 UTC [38375][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61926 2024-04-01 10:19:39.601 UTC [38375][client backend] [[unknown]][5/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.601 UTC [38375][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-01 10:19:39.602 UTC [38375][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client-der.key$$ 2024-04-01 10:19:39.602 UTC [38375][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=61926 2024-04-01 10:19:39.608 UTC [38377][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61927 2024-04-01 10:19:39.614 UTC [38377][client backend] [[unknown]][6/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.614 UTC [38377][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-01 10:19:39.615 UTC [38377][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-04-01 10:19:39.615 UTC [38377][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=61927 2024-04-01 10:19:39.620 UTC [38379][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61928 2024-04-01 10:19:39.626 UTC [38379][client backend] [[unknown]][7/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.626 UTC [38379][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-01 10:19:39.627 UTC [38379][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-04-01 10:19:39.627 UTC [38379][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=61928 2024-04-01 10:19:39.633 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61929 2024-04-01 10:19:39.639 UTC [38381][client backend] [[unknown]][8/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.639 UTC [38381][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-01 10:19:39.639 UTC [38381][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=require sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client.key$$ 2024-04-01 10:19:39.640 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=61929 2024-04-01 10:19:39.645 UTC [38383][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61930 2024-04-01 10:19:39.651 UTC [38383][client backend] [[unknown]][9/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.651 UTC [38383][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-01 10:19:39.652 UTC [38383][client backend] [001_ssltests.pl][9/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=allow sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client.key$$ 2024-04-01 10:19:39.652 UTC [38383][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=61930 2024-04-01 10:19:39.658 UTC [38385][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61931 2024-04-01 10:19:39.662 UTC [38385][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-04-01 10:19:39.668 UTC [38387][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61932 2024-04-01 10:19:39.670 UTC [38387][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:19:39.676 UTC [38389][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61933 2024-04-01 10:19:39.682 UTC [38389][client backend] [[unknown]][11/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8) 2024-04-01 10:19:39.682 UTC [38389][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-01 10:19:39.686 UTC [38389][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client-dn.key$$ 2024-04-01 10:19:39.687 UTC [38389][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=61933 2024-04-01 10:19:39.694 UTC [38391][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61934 2024-04-01 10:19:39.700 UTC [38391][client backend] [[unknown]][12/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9) 2024-04-01 10:19:39.700 UTC [38391][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-01 10:19:39.704 UTC [38391][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn_re user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client-dn.key$$ 2024-04-01 10:19:39.704 UTC [38391][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=61934 2024-04-01 10:19:39.711 UTC [38393][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61935 2024-04-01 10:19:39.717 UTC [38393][client backend] [[unknown]][13/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10) 2024-04-01 10:19:39.717 UTC [38393][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-01 10:19:39.721 UTC [38393][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_cn user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client-dn.key$$ 2024-04-01 10:19:39.721 UTC [38393][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=61935 2024-04-01 10:19:39.751 UTC [38396][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61936 2024-04-01 10:19:39.758 UTC [38396][client backend] [[unknown]][14/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.758 UTC [38396][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-01 10:19:39.758 UTC [38396][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-01 10:19:39.760 UTC [38396][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=61936 2024-04-01 10:19:39.766 UTC [38398][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61937 2024-04-01 10:19:39.767 UTC [38398][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:19:39.773 UTC [38400][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61938 2024-04-01 10:19:39.780 UTC [38400][client backend] [[unknown]][15/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:39.780 UTC [38400][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-01 10:19:39.780 UTC [38400][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-04-01 10:19:39.780 UTC [38400][client backend] [[unknown]][15/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb all 127.0.0.1/32 cert" 2024-04-01 10:19:39.785 UTC [38402][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61939 2024-04-01 10:19:39.791 UTC [38402][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:19:39.791 UTC [38402][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-01 10:19:39.796 UTC [38404][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61940 2024-04-01 10:19:39.802 UTC [38404][client backend] [[unknown]][16/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4) 2024-04-01 10:19:39.802 UTC [38404][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-01 10:19:39.806 UTC [38404][client backend] [001_ssltests.pl][16/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client.key$$ 2024-04-01 10:19:39.807 UTC [38404][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=61940 2024-04-01 10:19:39.813 UTC [38406][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61941 2024-04-01 10:19:39.818 UTC [38406][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-01 10:19:39.818 UTC [38406][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-04-01 10:19:39.818 UTC [38406][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-04-01 10:19:39.818 UTC [38406][client backend] [[unknown]][17/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb anotheruser 127.0.0.1/32 trust clientcert=verify-full" 2024-04-01 10:19:39.824 UTC [38408][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61942 2024-04-01 10:19:39.830 UTC [38408][client backend] [[unknown]][18/1:0] LOG: connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6) 2024-04-01 10:19:39.830 UTC [38408][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-01 10:19:39.830 UTC [38408][client backend] [001_ssltests.pl][18/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=yetanotheruser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/client.key$$ 2024-04-01 10:19:39.831 UTC [38408][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=61942 2024-04-01 10:19:39.834 UTC [38350][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:39.834 UTC [38350][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:39.835 UTC [38350][postmaster] LOG: background worker "logical replication launcher" (PID 38356) exited with exit code 1 2024-04-01 10:19:39.835 UTC [38351][checkpointer] LOG: shutting down 2024-04-01 10:19:39.835 UTC [38351][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:39.835 UTC [38351][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/4100F68, redo lsn=0/4100F68 2024-04-01 10:19:39.836 UTC [38350][postmaster] LOG: database system is shut down 2024-04-01 10:19:39.952 UTC [38410][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:39.952 UTC [38410][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:39.952 UTC [38410][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:39.953 UTC [38413][startup] LOG: database system was shut down at 2024-04-01 10:19:39 UTC 2024-04-01 10:19:39.956 UTC [38410][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:40.053 UTC [38418][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61943 2024-04-01 10:19:40.060 UTC [38418][client backend] [[unknown]][0/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:19:40.060 UTC [38418][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-01 10:19:40.065 UTC [38418][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=certdb sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_tttC/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-01 10:19:40.065 UTC [38418][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=61943 2024-04-01 10:19:40.072 UTC [38420][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61944 2024-04-01 10:19:40.077 UTC [38420][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:19:40.077 UTC [38420][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-01 10:19:40.083 UTC [38422][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61945 2024-04-01 10:19:40.088 UTC [38422][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:19:40.088 UTC [38422][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-01 10:19:40.091 UTC [38410][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:40.091 UTC [38410][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:40.092 UTC [38410][postmaster] LOG: background worker "logical replication launcher" (PID 38416) exited with exit code 1 2024-04-01 10:19:40.092 UTC [38411][checkpointer] LOG: shutting down 2024-04-01 10:19:40.092 UTC [38411][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:40.093 UTC [38411][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/4100FE0, redo lsn=0/4100FE0 2024-04-01 10:19:40.094 UTC [38410][postmaster] LOG: database system is shut down 2024-04-01 10:19:40.209 UTC [38424][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:40.209 UTC [38424][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:40.209 UTC [38424][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:40.211 UTC [38427][startup] LOG: database system was shut down at 2024-04-01 10:19:40 UTC 2024-04-01 10:19:40.213 UTC [38424][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:40.310 UTC [38432][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61946 2024-04-01 10:19:40.316 UTC [38432][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:19:40.316 UTC [38432][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-01 10:19:40.319 UTC [38424][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:40.319 UTC [38424][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:40.320 UTC [38424][postmaster] LOG: background worker "logical replication launcher" (PID 38430) exited with exit code 1 2024-04-01 10:19:40.320 UTC [38425][checkpointer] LOG: shutting down 2024-04-01 10:19:40.320 UTC [38425][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:40.320 UTC [38425][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/4101058, redo lsn=0/4101058 2024-04-01 10:19:40.322 UTC [38424][postmaster] LOG: database system is shut down 2024-04-01 10:19:40.437 UTC [38434][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:40.437 UTC [38434][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:40.437 UTC [38434][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:40.439 UTC [38437][startup] LOG: database system was shut down at 2024-04-01 10:19:40 UTC 2024-04-01 10:19:40.442 UTC [38434][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:40.539 UTC [38442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61947 2024-04-01 10:19:40.545 UTC [38442][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:19:40.545 UTC [38442][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-01 10:19:40.552 UTC [38444][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61948 2024-04-01 10:19:40.558 UTC [38444][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:19:40.558 UTC [38444][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-01 10:19:40.562 UTC [38434][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:40.562 UTC [38434][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:40.563 UTC [38434][postmaster] LOG: background worker "logical replication launcher" (PID 38440) exited with exit code 1 2024-04-01 10:19:40.564 UTC [38435][checkpointer] LOG: shutting down 2024-04-01 10:19:40.564 UTC [38435][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:40.564 UTC [38435][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/41010D0, redo lsn=0/41010D0 2024-04-01 10:19:40.566 UTC [38434][postmaster] LOG: database system is shut down 2024-04-01 10:19:40.680 UTC [38446][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:40.680 UTC [38446][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:40.680 UTC [38446][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:40.681 UTC [38449][startup] LOG: database system was shut down at 2024-04-01 10:19:40 UTC 2024-04-01 10:19:40.683 UTC [38446][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:40.781 UTC [38454][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61949 2024-04-01 10:19:40.783 UTC [38454][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:40.783 UTC [38454][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:40.790 UTC [38456][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61950 2024-04-01 10:19:40.795 UTC [38456][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:40.795 UTC [38456][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-01 10:19:40.800 UTC [38456][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-01 10:19:40.800 UTC [38456][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61950 2024-04-01 10:19:40.803 UTC [38446][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:40.803 UTC [38446][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:40.804 UTC [38446][postmaster] LOG: background worker "logical replication launcher" (PID 38452) exited with exit code 1 2024-04-01 10:19:40.804 UTC [38447][checkpointer] LOG: shutting down 2024-04-01 10:19:40.804 UTC [38447][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:40.804 UTC [38447][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/4101148, redo lsn=0/4101148 2024-04-01 10:19:40.805 UTC [38446][postmaster] LOG: database system is shut down 2024-04-01 10:19:40.920 UTC [38458][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:40.920 UTC [38458][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:40.920 UTC [38458][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:40.922 UTC [38461][startup] LOG: database system was shut down at 2024-04-01 10:19:40 UTC 2024-04-01 10:19:40.924 UTC [38458][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:41.022 UTC [38466][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61951 2024-04-01 10:19:41.024 UTC [38466][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:41.024 UTC [38466][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:41.030 UTC [38468][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61952 2024-04-01 10:19:41.035 UTC [38468][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:41.035 UTC [38468][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-01 10:19:41.040 UTC [38468][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-01 10:19:41.040 UTC [38468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61952 2024-04-01 10:19:41.043 UTC [38458][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:41.043 UTC [38458][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:41.044 UTC [38458][postmaster] LOG: background worker "logical replication launcher" (PID 38464) exited with exit code 1 2024-04-01 10:19:41.044 UTC [38459][checkpointer] LOG: shutting down 2024-04-01 10:19:41.044 UTC [38459][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:41.044 UTC [38459][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/41011C0, redo lsn=0/41011C0 2024-04-01 10:19:41.045 UTC [38458][postmaster] LOG: database system is shut down 2024-04-01 10:19:41.161 UTC [38470][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:41.161 UTC [38470][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:41.161 UTC [38470][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:41.163 UTC [38473][startup] LOG: database system was shut down at 2024-04-01 10:19:41 UTC 2024-04-01 10:19:41.166 UTC [38470][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:41.263 UTC [38478][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61953 2024-04-01 10:19:41.264 UTC [38478][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:41.264 UTC [38478][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:41.271 UTC [38480][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61954 2024-04-01 10:19:41.276 UTC [38480][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:41.276 UTC [38480][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-01 10:19:41.280 UTC [38480][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-01 10:19:41.281 UTC [38480][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=61954 2024-04-01 10:19:41.284 UTC [38470][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:41.284 UTC [38470][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:41.285 UTC [38470][postmaster] LOG: background worker "logical replication launcher" (PID 38476) exited with exit code 1 2024-04-01 10:19:41.285 UTC [38471][checkpointer] LOG: shutting down 2024-04-01 10:19:41.285 UTC [38471][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:41.285 UTC [38471][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/4101238, redo lsn=0/4101238 2024-04-01 10:19:41.286 UTC [38470][postmaster] LOG: database system is shut down 2024-04-01 10:19:41.402 UTC [38482][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:41.402 UTC [38482][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:41.402 UTC [38482][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:41.404 UTC [38485][startup] LOG: database system was shut down at 2024-04-01 10:19:41 UTC 2024-04-01 10:19:41.407 UTC [38482][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:41.503 UTC [38490][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61955 2024-04-01 10:19:41.505 UTC [38490][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:41.505 UTC [38490][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:41.513 UTC [38492][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61956 2024-04-01 10:19:41.518 UTC [38492][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:41.518 UTC [38492][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-01 10:19:41.522 UTC [38492][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-01 10:19:41.523 UTC [38492][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61956 2024-04-01 10:19:41.526 UTC [38482][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:41.526 UTC [38482][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:41.528 UTC [38482][postmaster] LOG: background worker "logical replication launcher" (PID 38488) exited with exit code 1 2024-04-01 10:19:41.528 UTC [38483][checkpointer] LOG: shutting down 2024-04-01 10:19:41.528 UTC [38483][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:41.528 UTC [38483][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/41012B0, redo lsn=0/41012B0 2024-04-01 10:19:41.530 UTC [38482][postmaster] LOG: database system is shut down 2024-04-01 10:19:41.645 UTC [38494][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:41.645 UTC [38494][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:41.645 UTC [38494][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:41.647 UTC [38497][startup] LOG: database system was shut down at 2024-04-01 10:19:41 UTC 2024-04-01 10:19:41.649 UTC [38494][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:41.743 UTC [38502][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61957 2024-04-01 10:19:41.745 UTC [38502][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:41.745 UTC [38502][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:41.756 UTC [38504][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61958 2024-04-01 10:19:41.761 UTC [38504][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:41.761 UTC [38504][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-01 10:19:41.765 UTC [38504][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-01 10:19:41.766 UTC [38504][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61958 2024-04-01 10:19:41.770 UTC [38494][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:41.770 UTC [38494][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:41.771 UTC [38494][postmaster] LOG: background worker "logical replication launcher" (PID 38500) exited with exit code 1 2024-04-01 10:19:41.771 UTC [38495][checkpointer] LOG: shutting down 2024-04-01 10:19:41.771 UTC [38495][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:41.772 UTC [38495][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/4101328, redo lsn=0/4101328 2024-04-01 10:19:41.774 UTC [38494][postmaster] LOG: database system is shut down 2024-04-01 10:19:41.888 UTC [38506][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:41.888 UTC [38506][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:41.888 UTC [38506][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:41.890 UTC [38509][startup] LOG: database system was shut down at 2024-04-01 10:19:41 UTC 2024-04-01 10:19:41.894 UTC [38506][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:41.991 UTC [38514][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61959 2024-04-01 10:19:41.992 UTC [38514][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:41.992 UTC [38514][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:42.002 UTC [38516][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61960 2024-04-01 10:19:42.007 UTC [38516][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:42.007 UTC [38516][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-01 10:19:42.012 UTC [38516][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-01 10:19:42.013 UTC [38516][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=61960 2024-04-01 10:19:42.019 UTC [38506][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:42.019 UTC [38506][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:42.021 UTC [38506][postmaster] LOG: background worker "logical replication launcher" (PID 38512) exited with exit code 1 2024-04-01 10:19:42.021 UTC [38507][checkpointer] LOG: shutting down 2024-04-01 10:19:42.021 UTC [38507][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:42.021 UTC [38507][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/41013A0, redo lsn=0/41013A0 2024-04-01 10:19:42.023 UTC [38506][postmaster] LOG: database system is shut down 2024-04-01 10:19:42.137 UTC [38518][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:42.137 UTC [38518][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:42.137 UTC [38518][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:42.139 UTC [38521][startup] LOG: database system was shut down at 2024-04-01 10:19:42 UTC 2024-04-01 10:19:42.143 UTC [38518][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:42.240 UTC [38526][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61961 2024-04-01 10:19:42.242 UTC [38526][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:42.242 UTC [38526][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:42.251 UTC [38528][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61962 2024-04-01 10:19:42.256 UTC [38528][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:42.256 UTC [38528][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-01 10:19:42.261 UTC [38528][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-01 10:19:42.261 UTC [38528][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=61962 2024-04-01 10:19:42.265 UTC [38518][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:42.265 UTC [38518][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:42.267 UTC [38518][postmaster] LOG: background worker "logical replication launcher" (PID 38524) exited with exit code 1 2024-04-01 10:19:42.267 UTC [38519][checkpointer] LOG: shutting down 2024-04-01 10:19:42.267 UTC [38519][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:42.268 UTC [38519][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/4101418, redo lsn=0/4101418 2024-04-01 10:19:42.269 UTC [38518][postmaster] LOG: database system is shut down 2024-04-01 10:19:42.385 UTC [38530][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:42.385 UTC [38530][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:42.385 UTC [38530][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:42.387 UTC [38533][startup] LOG: database system was shut down at 2024-04-01 10:19:42 UTC 2024-04-01 10:19:42.390 UTC [38530][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:42.486 UTC [38538][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61963 2024-04-01 10:19:42.489 UTC [38538][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:42.489 UTC [38538][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:42.497 UTC [38540][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61964 2024-04-01 10:19:42.502 UTC [38540][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:42.502 UTC [38540][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-01 10:19:42.506 UTC [38540][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-01 10:19:42.507 UTC [38540][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61964 2024-04-01 10:19:42.510 UTC [38530][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:42.510 UTC [38530][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:42.511 UTC [38530][postmaster] LOG: background worker "logical replication launcher" (PID 38536) exited with exit code 1 2024-04-01 10:19:42.512 UTC [38531][checkpointer] LOG: shutting down 2024-04-01 10:19:42.512 UTC [38531][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:42.512 UTC [38531][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/4101490, redo lsn=0/4101490 2024-04-01 10:19:42.514 UTC [38530][postmaster] LOG: database system is shut down 2024-04-01 10:19:42.628 UTC [38542][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-04-01 10:19:42.628 UTC [38542][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60541 2024-04-01 10:19:42.628 UTC [38542][postmaster] LOG: listening on Unix socket "/tmp/XV33ADncba/.s.PGSQL.60541" 2024-04-01 10:19:42.630 UTC [38545][startup] LOG: database system was shut down at 2024-04-01 10:19:42 UTC 2024-04-01 10:19:42.633 UTC [38542][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:19:42.730 UTC [38550][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61965 2024-04-01 10:19:42.732 UTC [38550][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:19:42.732 UTC [38550][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:19:42.739 UTC [38552][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=61966 2024-04-01 10:19:42.744 UTC [38552][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:19:42.744 UTC [38552][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-01 10:19:42.748 UTC [38552][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-01 10:19:42.749 UTC [38552][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=61966 2024-04-01 10:19:42.752 UTC [38542][postmaster] LOG: received fast shutdown request 2024-04-01 10:19:42.752 UTC [38542][postmaster] LOG: aborting any active transactions 2024-04-01 10:19:42.753 UTC [38542][postmaster] LOG: background worker "logical replication launcher" (PID 38548) exited with exit code 1 2024-04-01 10:19:42.753 UTC [38543][checkpointer] LOG: shutting down 2024-04-01 10:19:42.753 UTC [38543][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:19:42.754 UTC [38543][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/4101508, redo lsn=0/4101508 2024-04-01 10:19:42.755 UTC [38542][postmaster] LOG: database system is shut down 2024-04-01 10:19:42.868 UTC [38554][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-04-01 10:19:42.868 UTC [38554][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:19:42.966](0.216s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"