[13:18:58.133](0.030s) # setting up data directory # Checking port 60863 # Found port 60863 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=60863 host=/tmp/R_9jImeSL6 Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [13:18:58.152](0.019s) # 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 36935 [13:18:58.348](0.196s) 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 37201 [13:18:59.131](0.784s) # 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-03-16 13:18:58.244 UTC [36935][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:58.244 UTC [36935][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:58.247 UTC [36940][startup] LOG: database system was shut down at 2024-03-16 13:16:49 UTC 2024-03-16 13:18:58.249 UTC [36935][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:58.341 UTC [36978][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.341 UTC [36978][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-03-16 13:18:58.341 UTC [36978][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.345 UTC [36978][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-16 13:18:58.346 UTC [36978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.354 UTC [36982][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.354 UTC [36982][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-03-16 13:18:58.354 UTC [36982][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.398 UTC [36995][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.398 UTC [36995][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-03-16 13:18:58.398 UTC [36995][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.482 UTC [37021][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.482 UTC [37021][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-03-16 13:18:58.482 UTC [37021][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.527 UTC [37039][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.528 UTC [37039][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-03-16 13:18:58.528 UTC [37039][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.528 UTC [37039][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-16 13:18:58.528 UTC [37039][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.530 UTC [37039][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.557 UTC [37052][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.557 UTC [37052][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-03-16 13:18:58.557 UTC [37052][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.557 UTC [37052][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-16 13:18:58.557 UTC [37052][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.578 UTC [37052][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.622 UTC [37063][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.623 UTC [37063][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-03-16 13:18:58.623 UTC [37063][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.624 UTC [37063][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-16 13:18:58.624 UTC [37063][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.718 UTC [37063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.096 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.736 UTC [37112][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.736 UTC [37112][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-03-16 13:18:58.736 UTC [37112][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.737 UTC [37112][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-16 13:18:58.737 UTC [37112][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.757 UTC [37112][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.780 UTC [37131][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.781 UTC [37131][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-03-16 13:18:58.781 UTC [37131][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.781 UTC [37131][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-16 13:18:58.781 UTC [37131][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.802 UTC [37131][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.827 UTC [37141][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.827 UTC [37141][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-03-16 13:18:58.828 UTC [37141][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.828 UTC [37141][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-16 13:18:58.828 UTC [37141][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.845 UTC [37141][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.878 UTC [37157][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.878 UTC [37157][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-03-16 13:18:58.878 UTC [37157][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.879 UTC [37157][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-16 13:18:58.879 UTC [37157][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.912 UTC [37157][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.922 UTC [36935][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:58.922 UTC [36935][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:58.924 UTC [36935][postmaster] LOG: background worker "logical replication launcher" (PID 36946) exited with exit code 1 2024-03-16 13:18:58.925 UTC [36937][checkpointer] LOG: shutting down 2024-03-16 13:18:58.925 UTC [36937][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:58.966 UTC [36937][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.041 s, sync=0.001 s, total=0.042 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413CAC0, redo lsn=0/413CAC0 2024-03-16 13:18:58.973 UTC [36935][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.035 UTC [37205][startup] LOG: database system was shut down at 2024-03-16 13:18:58 UTC 2024-03-16 13:18:59.037 UTC [37201][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.133 UTC [37201][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:59.133 UTC [37201][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:59.135 UTC [37201][postmaster] LOG: background worker "logical replication launcher" (PID 37208) exited with exit code 1 2024-03-16 13:18:59.136 UTC [37203][checkpointer] LOG: shutting down 2024-03-16 13:18:59.136 UTC [37203][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:59.136 UTC [37203][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/413CB38, redo lsn=0/413CB38 2024-03-16 13:18:59.138 UTC [37201][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.244 UTC [37254][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-16 13:18:59.244 UTC [37254][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:18:59.336](0.205s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/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 37277 [13:18:59.442](0.105s) 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-03-16 13:18:58.244 UTC [36935][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:58.244 UTC [36935][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:58.247 UTC [36940][startup] LOG: database system was shut down at 2024-03-16 13:16:49 UTC 2024-03-16 13:18:58.249 UTC [36935][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:58.341 UTC [36978][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.341 UTC [36978][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-03-16 13:18:58.341 UTC [36978][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.345 UTC [36978][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-16 13:18:58.346 UTC [36978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.354 UTC [36982][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.354 UTC [36982][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-03-16 13:18:58.354 UTC [36982][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.398 UTC [36995][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.398 UTC [36995][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-03-16 13:18:58.398 UTC [36995][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.482 UTC [37021][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.482 UTC [37021][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-03-16 13:18:58.482 UTC [37021][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.527 UTC [37039][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.528 UTC [37039][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-03-16 13:18:58.528 UTC [37039][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.528 UTC [37039][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-16 13:18:58.528 UTC [37039][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.530 UTC [37039][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.557 UTC [37052][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.557 UTC [37052][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-03-16 13:18:58.557 UTC [37052][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.557 UTC [37052][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-16 13:18:58.557 UTC [37052][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.578 UTC [37052][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.622 UTC [37063][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.623 UTC [37063][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-03-16 13:18:58.623 UTC [37063][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.624 UTC [37063][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-16 13:18:58.624 UTC [37063][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.718 UTC [37063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.096 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.736 UTC [37112][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.736 UTC [37112][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-03-16 13:18:58.736 UTC [37112][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.737 UTC [37112][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-16 13:18:58.737 UTC [37112][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.757 UTC [37112][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.780 UTC [37131][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.781 UTC [37131][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-03-16 13:18:58.781 UTC [37131][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.781 UTC [37131][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-16 13:18:58.781 UTC [37131][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.802 UTC [37131][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.827 UTC [37141][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.827 UTC [37141][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-03-16 13:18:58.828 UTC [37141][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.828 UTC [37141][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-16 13:18:58.828 UTC [37141][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.845 UTC [37141][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.878 UTC [37157][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.878 UTC [37157][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-03-16 13:18:58.878 UTC [37157][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.879 UTC [37157][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-16 13:18:58.879 UTC [37157][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.912 UTC [37157][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.922 UTC [36935][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:58.922 UTC [36935][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:58.924 UTC [36935][postmaster] LOG: background worker "logical replication launcher" (PID 36946) exited with exit code 1 2024-03-16 13:18:58.925 UTC [36937][checkpointer] LOG: shutting down 2024-03-16 13:18:58.925 UTC [36937][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:58.966 UTC [36937][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.041 s, sync=0.001 s, total=0.042 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413CAC0, redo lsn=0/413CAC0 2024-03-16 13:18:58.973 UTC [36935][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.035 UTC [37205][startup] LOG: database system was shut down at 2024-03-16 13:18:58 UTC 2024-03-16 13:18:59.037 UTC [37201][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.133 UTC [37201][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:59.133 UTC [37201][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:59.135 UTC [37201][postmaster] LOG: background worker "logical replication launcher" (PID 37208) exited with exit code 1 2024-03-16 13:18:59.136 UTC [37203][checkpointer] LOG: shutting down 2024-03-16 13:18:59.136 UTC [37203][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:59.136 UTC [37203][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/413CB38, redo lsn=0/413CB38 2024-03-16 13:18:59.138 UTC [37201][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.244 UTC [37254][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-16 13:18:59.244 UTC [37254][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.351 UTC [37277][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.351 UTC [37277][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.351 UTC [37277][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.353 UTC [37281][startup] LOG: database system was shut down at 2024-03-16 13:18:59 UTC 2024-03-16 13:18:59.356 UTC [37277][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.444 UTC [37277][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:59.444 UTC [37277][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:59.446 UTC [37277][postmaster] LOG: background worker "logical replication launcher" (PID 37284) exited with exit code 1 2024-03-16 13:18:59.447 UTC [37279][checkpointer] LOG: shutting down 2024-03-16 13:18:59.447 UTC [37279][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:59.447 UTC [37279][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/413CBB0, redo lsn=0/413CBB0 2024-03-16 13:18:59.449 UTC [37277][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.554 UTC [37330][postmaster] FATAL: could not set SSL protocol version range 2024-03-16 13:18:59.554 UTC [37330][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-16 13:18:59.554 UTC [37330][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:18:59.647](0.205s) 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 37345 [13:18:59.750](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [13:18:59.750](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 37404 [13:18:59.967](0.217s) ok 6 - server doesn't accept non-SSL connections [13:18:59.967](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [13:18:59.981](0.014s) ok 8 - connect without server root cert sslmode=require [13:18:59.981](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [13:18:59.988](0.007s) ok 10 - connect without server root cert sslmode=verify-ca [13:18:59.988](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [13:18:59.994](0.006s) ok 12 - connect without server root cert sslmode=verify-full [13:18:59.994](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [13:19:00.003](0.008s) ok 14 - connect with wrong server root cert sslmode=require [13:19:00.003](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [13:19:00.011](0.008s) ok 16 - connect with wrong server root cert sslmode=verify-ca [13:19:00.011](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [13:19:00.020](0.009s) ok 18 - connect with wrong server root cert sslmode=verify-full [13:19:00.020](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [13:19:00.029](0.009s) ok 20 - connect with server CA cert, without root CA [13:19:00.029](0.000s) ok 21 - connect with server CA cert, without root CA: matches [13:19:00.039](0.010s) ok 22 - connect with correct server CA cert file sslmode=require [13:19:00.039](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [13:19:00.049](0.010s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [13:19:00.049](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [13:19:00.059](0.010s) ok 26 - connect with correct server CA cert file sslmode=verify-full [13:19:00.060](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [13:19:00.070](0.010s) ok 28 - cert root file that contains two certificates, order 1 [13:19:00.070](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [13:19:00.084](0.014s) ok 30 - cert root file that contains two certificates, order 2 [13:19:00.084](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [13:19:00.096](0.012s) ok 32 - connect with sslcertmode=disable [13:19:00.096](0.001s) ok 33 - connect with sslcertmode=disable: no stderr [13:19:00.109](0.013s) ok 34 - connect with sslcertmode=allow [13:19:00.109](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [13:19:00.121](0.011s) ok 36 - connect with sslcertmode=require fails without a client certificate [13:19:00.121](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [13:19:00.133](0.012s) ok 38 - sslcrl option with invalid file name [13:19:00.133](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [13:19:00.143](0.010s) ok 40 - CRL belonging to a different CA [13:19:00.143](0.000s) ok 41 - CRL belonging to a different CA: matches [13:19:00.152](0.009s) ok 42 - directory CRL belonging to a different CA [13:19:00.152](0.000s) ok 43 - directory CRL belonging to a different CA: matches [13:19:00.163](0.010s) ok 44 - CRL with a non-revoked cert [13:19:00.163](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [13:19:00.173](0.010s) ok 46 - directory CRL with a non-revoked cert [13:19:00.173](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [13:19:00.183](0.010s) ok 48 - mismatch between host name and server certificate sslmode=require [13:19:00.183](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [13:19:00.193](0.010s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [13:19:00.193](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [13:19:00.201](0.008s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [13:19:00.202](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 37504 [13:19:00.427](0.225s) ok 54 - IP address in the Common Name [13:19:00.427](0.000s) ok 55 - IP address in the Common Name: no stderr [13:19:00.436](0.009s) ok 56 - mismatch between host name and server certificate IP address [13:19:00.436](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 37530 [13:19:00.666](0.229s) ok 58 - IP address in a dNSName [13:19:00.666](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 37555 [13:19:00.890](0.224s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [13:19:00.890](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [13:19:00.901](0.011s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [13:19:00.901](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [13:19:00.912](0.011s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [13:19:00.912](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [13:19:00.922](0.010s) ok 66 - host name not matching with X.509 Subject Alternative Names [13:19:00.922](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [13:19:00.933](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [13:19:00.934](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 37589 [13:19:01.160](0.227s) ok 70 - host name matching with a single X.509 Subject Alternative Name [13:19:01.161](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [13:19:01.169](0.009s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [13:19:01.170](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [13:19:01.178](0.008s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [13:19:01.178](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 37617 [13:19:01.406](0.228s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [13:19:01.406](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [13:19:01.418](0.012s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [13:19:01.418](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [13:19:01.427](0.009s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [13:19:01.427](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [13:19:01.438](0.010s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [13:19:01.438](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [13:19:01.448](0.010s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [13:19:01.448](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [13:19:01.459](0.011s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [13:19:01.459](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [13:19:01.468](0.010s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [13:19:01.469](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [13:19:01.478](0.009s) ok 90 - IPv6 host with CIDR mask does not match [13:19:01.478](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 37655 [13:19:01.703](0.225s) ok 92 - certificate with both a CN and SANs 1 [13:19:01.703](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [13:19:01.715](0.012s) ok 94 - certificate with both a CN and SANs 2 [13:19:01.715](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [13:19:01.726](0.011s) ok 96 - certificate with both a CN and SANs ignores CN [13:19:01.726](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 37672 [13:19:01.959](0.233s) ok 98 - certificate with both a CN and IP SANs matches CN [13:19:01.959](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [13:19:01.970](0.010s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [13:19:01.970](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [13:19:01.980](0.010s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [13:19:01.980](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 37690 [13:19:02.210](0.230s) ok 104 - certificate with both an IP CN and IP SANs 1 [13:19:02.210](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [13:19:02.220](0.010s) ok 106 - certificate with both an IP CN and IP SANs 2 [13:19:02.220](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [13:19:02.229](0.009s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [13:19:02.229](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 37705 [13:19:02.460](0.231s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [13:19:02.460](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [13:19:02.474](0.013s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [13:19:02.474](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [13:19:02.485](0.011s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [13:19:02.485](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 37719 [13:19:02.710](0.225s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [13:19:02.710](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [13:19:02.719](0.009s) ok 118 - server certificate without CN or SANs sslmode=verify-full [13:19:02.719](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 37731 [13:19:02.944](0.225s) ok 120 - sslrootcert=system does not connect with private CA [13:19:02.944](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [13:19:02.948](0.004s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [13:19:02.948](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [13:19:02.961](0.013s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [13:19:02.962](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [13:19:02.970](0.009s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [13:19:02.970](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 37746 [13:19:03.200](0.229s) ok 128 - connects without client-side CRL [13:19:03.200](0.000s) ok 129 - connects without client-side CRL: no stderr [13:19:03.208](0.008s) ok 130 - does not connect with client-side CRL file [13:19:03.209](0.000s) ok 131 - does not connect with client-side CRL file: matches [13:19:03.217](0.008s) ok 132 - does not connect with client-side CRL directory [13:19:03.217](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() [13:19:03.227](0.010s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [13:19:03.228](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [13:19:03.228](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [13:19:03.237](0.009s) ok 137 - connection success with correct range of TLS protocol versions [13:19:03.237](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [13:19:03.241](0.004s) ok 139 - connection failure with incorrect range of TLS protocol versions [13:19:03.241](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [13:19:03.245](0.004s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [13:19:03.245](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [13:19:03.249](0.004s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [13:19:03.249](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [13:19:03.249](0.000s) # running server tests [13:19:03.258](0.009s) ok 145 - certificate authorization fails without client cert [13:19:03.258](0.000s) ok 146 - certificate authorization fails without client cert: matches [13:19:03.273](0.014s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [13:19:03.273](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [13:19:03.284](0.011s) ok 149 - certificate authorization succeeds with correct client cert in DER format [13:19:03.284](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [13:19:03.296](0.012s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [13:19:03.296](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [13:19:03.308](0.011s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [13:19:03.308](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [13:19:03.319](0.011s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [13:19:03.319](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [13:19:03.330](0.011s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [13:19:03.330](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [13:19:03.339](0.009s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [13:19:03.339](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [13:19:03.346](0.007s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [13:19:03.346](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [13:19:03.361](0.015s) ok 163 - certificate authorization succeeds with DN mapping [13:19:03.361](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [13:19:03.361](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [13:19:03.376](0.015s) ok 166 - certificate authorization succeeds with DN regex mapping [13:19:03.376](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [13:19:03.391](0.015s) ok 168 - certificate authorization succeeds with CN mapping [13:19:03.391](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [13:19:03.391](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [13:19:03.391](0.000s) not ok 171 # TODO & SKIP Need Pty support [13:19:03.392](0.000s) not ok 172 # TODO & SKIP Need Pty support [13:19:03.392](0.000s) not ok 173 # TODO & SKIP Need Pty support [13:19:03.392](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_wEso/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [13:19:03.419](0.027s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [13:19:03.419](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [13:19:03.419](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [13:19:03.425](0.006s) ok 178 - certificate authorization fails because of file permissions [13:19:03.426](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [13:19:03.436](0.011s) ok 180 - certificate authorization fails with client cert belonging to another user [13:19:03.436](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [13:19:03.446](0.010s) ok 182 - certificate authorization fails with revoked client cert [13:19:03.446](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [13:19:03.447](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [13:19:03.462](0.015s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [13:19:03.462](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [13:19:03.462](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [13:19:03.473](0.011s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [13:19:03.473](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [13:19:03.473](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [13:19:03.484](0.011s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [13:19:03.485](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [13:19:03.485](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 37806 [13:19:03.716](0.231s) ok 194 - intermediate client certificate is provided by client [13:19:03.716](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [13:19:03.726](0.010s) ok 196 - intermediate client certificate is missing [13:19:03.726](0.000s) ok 197 - intermediate client certificate is missing: matches [13:19:03.737](0.011s) ok 198 - logged client certificate Subjects are truncated if they're too long [13:19:03.737](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 37820 [13:19:03.965](0.227s) ok 200 - intermediate client certificate is untrusted [13:19:03.965](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 37830 [13:19:04.193](0.228s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [13:19:04.193](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [13:19:04.204](0.010s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [13:19:04.204](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 37842 [13:19:04.424](0.220s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [13:19:04.424](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. [13:19:04.424](0.000s) # got: '2' # expected: '0' [13:19:04.425](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:19:04.425](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. [13:19:04.425](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 60863 failed: SSL SYSCALL error: EOF detected' # expected: '' [13:19:04.440](0.015s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [13:19:04.441](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 37854 [13:19:04.664](0.223s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [13:19:04.677](0.014s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [13:19:04.677](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 37866 [13:19:04.901](0.223s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [13:19:04.915](0.014s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [13:19:04.915](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 37878 [13:19:05.136](0.221s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [13:19:05.150](0.013s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [13:19:05.150](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 37890 [13:19:05.373](0.223s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [13:19:05.373](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. [13:19:05.373](0.000s) # got: '2' # expected: '0' [13:19:05.373](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:19:05.373](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. [13:19:05.374](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 60863 failed: SSL SYSCALL error: EOF detected' # expected: '' [13:19:05.387](0.013s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [13:19:05.387](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 37902 [13:19:05.610](0.224s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [13:19:05.626](0.015s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [13:19:05.626](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 37914 [13:19:05.851](0.225s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [13:19:05.865](0.014s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [13:19:05.866](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 37926 [13:19:06.089](0.223s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [13:19:06.103](0.014s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [13:19:06.103](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 37938 [13:19:06.325](0.222s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [13:19:06.342](0.016s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [13:19:06.342](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-03-16 13:18:58.244 UTC [36935][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:58.244 UTC [36935][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:58.247 UTC [36940][startup] LOG: database system was shut down at 2024-03-16 13:16:49 UTC 2024-03-16 13:18:58.249 UTC [36935][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:58.341 UTC [36978][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.341 UTC [36978][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-03-16 13:18:58.341 UTC [36978][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.345 UTC [36978][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-16 13:18:58.346 UTC [36978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.354 UTC [36982][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.354 UTC [36982][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-03-16 13:18:58.354 UTC [36982][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.355 UTC [36982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.398 UTC [36995][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.398 UTC [36995][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-03-16 13:18:58.398 UTC [36995][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.399 UTC [36995][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.482 UTC [37021][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.482 UTC [37021][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-03-16 13:18:58.482 UTC [37021][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.484 UTC [37021][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.527 UTC [37039][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.528 UTC [37039][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-03-16 13:18:58.528 UTC [37039][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.528 UTC [37039][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-16 13:18:58.528 UTC [37039][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-16 13:18:58.530 UTC [37039][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.557 UTC [37052][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.557 UTC [37052][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-03-16 13:18:58.557 UTC [37052][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.557 UTC [37052][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-16 13:18:58.557 UTC [37052][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.578 UTC [37052][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.622 UTC [37063][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.623 UTC [37063][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-03-16 13:18:58.623 UTC [37063][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.624 UTC [37063][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-16 13:18:58.624 UTC [37063][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.718 UTC [37063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.096 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.736 UTC [37112][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.736 UTC [37112][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-03-16 13:18:58.736 UTC [37112][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.737 UTC [37112][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-16 13:18:58.737 UTC [37112][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.757 UTC [37112][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.780 UTC [37131][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.781 UTC [37131][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-03-16 13:18:58.781 UTC [37131][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.781 UTC [37131][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-16 13:18:58.781 UTC [37131][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.802 UTC [37131][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.827 UTC [37141][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.827 UTC [37141][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-03-16 13:18:58.828 UTC [37141][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.828 UTC [37141][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-16 13:18:58.828 UTC [37141][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.845 UTC [37141][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.878 UTC [37157][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:18:58.878 UTC [37157][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-03-16 13:18:58.878 UTC [37157][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-16 13:18:58.879 UTC [37157][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-16 13:18:58.879 UTC [37157][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-16 13:18:58.912 UTC [37157][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local] 2024-03-16 13:18:58.922 UTC [36935][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:58.922 UTC [36935][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:58.924 UTC [36935][postmaster] LOG: background worker "logical replication launcher" (PID 36946) exited with exit code 1 2024-03-16 13:18:58.925 UTC [36937][checkpointer] LOG: shutting down 2024-03-16 13:18:58.925 UTC [36937][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:58.966 UTC [36937][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.041 s, sync=0.001 s, total=0.042 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413CAC0, redo lsn=0/413CAC0 2024-03-16 13:18:58.973 UTC [36935][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.034 UTC [37201][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.035 UTC [37205][startup] LOG: database system was shut down at 2024-03-16 13:18:58 UTC 2024-03-16 13:18:59.037 UTC [37201][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.133 UTC [37201][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:59.133 UTC [37201][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:59.135 UTC [37201][postmaster] LOG: background worker "logical replication launcher" (PID 37208) exited with exit code 1 2024-03-16 13:18:59.136 UTC [37203][checkpointer] LOG: shutting down 2024-03-16 13:18:59.136 UTC [37203][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:59.136 UTC [37203][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/413CB38, redo lsn=0/413CB38 2024-03-16 13:18:59.138 UTC [37201][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.244 UTC [37254][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-16 13:18:59.244 UTC [37254][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.351 UTC [37277][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.351 UTC [37277][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.351 UTC [37277][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.353 UTC [37281][startup] LOG: database system was shut down at 2024-03-16 13:18:59 UTC 2024-03-16 13:18:59.356 UTC [37277][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.444 UTC [37277][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:59.444 UTC [37277][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:59.446 UTC [37277][postmaster] LOG: background worker "logical replication launcher" (PID 37284) exited with exit code 1 2024-03-16 13:18:59.447 UTC [37279][checkpointer] LOG: shutting down 2024-03-16 13:18:59.447 UTC [37279][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:59.447 UTC [37279][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/413CBB0, redo lsn=0/413CBB0 2024-03-16 13:18:59.449 UTC [37277][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.554 UTC [37330][postmaster] FATAL: could not set SSL protocol version range 2024-03-16 13:18:59.554 UTC [37330][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-16 13:18:59.554 UTC [37330][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.661 UTC [37345][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.661 UTC [37345][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.661 UTC [37345][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.663 UTC [37353][startup] LOG: database system was shut down at 2024-03-16 13:18:59 UTC 2024-03-16 13:18:59.665 UTC [37345][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.752 UTC [37345][postmaster] LOG: received fast shutdown request 2024-03-16 13:18:59.752 UTC [37345][postmaster] LOG: aborting any active transactions 2024-03-16 13:18:59.754 UTC [37345][postmaster] LOG: background worker "logical replication launcher" (PID 37356) exited with exit code 1 2024-03-16 13:18:59.754 UTC [37351][checkpointer] LOG: shutting down 2024-03-16 13:18:59.754 UTC [37351][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:18:59.754 UTC [37351][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/413CC28, redo lsn=0/413CC28 2024-03-16 13:18:59.756 UTC [37345][postmaster] LOG: database system is shut down 2024-03-16 13:18:59.864 UTC [37404][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:18:59.864 UTC [37404][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:18:59.864 UTC [37404][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:18:59.865 UTC [37409][startup] LOG: database system was shut down at 2024-03-16 13:18:59 UTC 2024-03-16 13:18:59.867 UTC [37404][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:18:59.966 UTC [37432][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54004 2024-03-16 13:18:59.966 UTC [37432][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-03-16 13:18:59.966 UTC [37432][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-16 13:18:59.972 UTC [37434][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54005 2024-03-16 13:18:59.975 UTC [37434][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-03-16 13:18:59.975 UTC [37434][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:18:59.980 UTC [37434][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-03-16 13:18:59.980 UTC [37434][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54005 2024-03-16 13:18:59.985 UTC [37436][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54006 2024-03-16 13:18:59.987 UTC [37436][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:18:59.992 UTC [37438][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54007 2024-03-16 13:18:59.993 UTC [37438][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:18:59.998 UTC [37440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54008 2024-03-16 13:19:00.002 UTC [37440][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:00.007 UTC [37444][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54009 2024-03-16 13:19:00.010 UTC [37444][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:00.016 UTC [37446][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54010 2024-03-16 13:19:00.019 UTC [37446][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:00.024 UTC [37456][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54011 2024-03-16 13:19:00.028 UTC [37456][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:00.033 UTC [37458][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54012 2024-03-16 13:19:00.037 UTC [37458][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-03-16 13:19:00.037 UTC [37458][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.038 UTC [37458][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-03-16 13:19:00.038 UTC [37458][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54012 2024-03-16 13:19:00.044 UTC [37460][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54013 2024-03-16 13:19:00.047 UTC [37460][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-03-16 13:19:00.047 UTC [37460][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.048 UTC [37460][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-16 13:19:00.048 UTC [37460][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54013 2024-03-16 13:19:00.054 UTC [37462][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54014 2024-03-16 13:19:00.057 UTC [37462][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-03-16 13:19:00.057 UTC [37462][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.058 UTC [37462][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-03-16 13:19:00.058 UTC [37462][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54014 2024-03-16 13:19:00.064 UTC [37464][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54015 2024-03-16 13:19:00.068 UTC [37464][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-03-16 13:19:00.068 UTC [37464][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.069 UTC [37464][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-03-16 13:19:00.069 UTC [37464][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54015 2024-03-16 13:19:00.077 UTC [37466][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54016 2024-03-16 13:19:00.081 UTC [37466][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-03-16 13:19:00.081 UTC [37466][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.082 UTC [37466][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-03-16 13:19:00.082 UTC [37466][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54016 2024-03-16 13:19:00.089 UTC [37468][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54017 2024-03-16 13:19:00.093 UTC [37468][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-03-16 13:19:00.093 UTC [37468][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.094 UTC [37468][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-03-16 13:19:00.095 UTC [37468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54017 2024-03-16 13:19:00.102 UTC [37470][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54018 2024-03-16 13:19:00.106 UTC [37470][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-03-16 13:19:00.106 UTC [37470][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.107 UTC [37470][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-03-16 13:19:00.108 UTC [37470][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54018 2024-03-16 13:19:00.115 UTC [37473][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54019 2024-03-16 13:19:00.119 UTC [37473][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-03-16 13:19:00.119 UTC [37473][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.120 UTC [37473][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54019 2024-03-16 13:19:00.127 UTC [37477][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54021 2024-03-16 13:19:00.131 UTC [37477][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-03-16 13:19:00.131 UTC [37477][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.131 UTC [37477][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-03-16 13:19:00.132 UTC [37477][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54021 2024-03-16 13:19:00.138 UTC [37481][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54023 2024-03-16 13:19:00.142 UTC [37481][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:00.148 UTC [37483][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54024 2024-03-16 13:19:00.151 UTC [37483][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:00.157 UTC [37485][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54025 2024-03-16 13:19:00.161 UTC [37485][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-03-16 13:19:00.161 UTC [37485][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.161 UTC [37485][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-03-16 13:19:00.162 UTC [37485][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54025 2024-03-16 13:19:00.167 UTC [37487][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54026 2024-03-16 13:19:00.171 UTC [37487][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-03-16 13:19:00.171 UTC [37487][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.172 UTC [37487][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-03-16 13:19:00.172 UTC [37487][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54026 2024-03-16 13:19:00.177 UTC [37489][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54027 2024-03-16 13:19:00.181 UTC [37489][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-03-16 13:19:00.181 UTC [37489][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.182 UTC [37489][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-03-16 13:19:00.182 UTC [37489][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54027 2024-03-16 13:19:00.187 UTC [37493][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54028 2024-03-16 13:19:00.191 UTC [37493][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-03-16 13:19:00.191 UTC [37493][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.192 UTC [37493][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-03-16 13:19:00.192 UTC [37493][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54028 2024-03-16 13:19:00.197 UTC [37495][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54029 2024-03-16 13:19:00.204 UTC [37404][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:00.204 UTC [37404][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:00.205 UTC [37404][postmaster] LOG: background worker "logical replication launcher" (PID 37412) exited with exit code 1 2024-03-16 13:19:00.205 UTC [37407][checkpointer] LOG: shutting down 2024-03-16 13:19:00.205 UTC [37407][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:00.205 UTC [37407][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413CCA0, redo lsn=0/413CCA0 2024-03-16 13:19:00.206 UTC [37404][postmaster] LOG: database system is shut down 2024-03-16 13:19:00.320 UTC [37504][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:00.320 UTC [37504][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:00.320 UTC [37504][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:00.322 UTC [37507][startup] LOG: database system was shut down at 2024-03-16 13:19:00 UTC 2024-03-16 13:19:00.324 UTC [37504][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:00.417 UTC [37517][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54031 2024-03-16 13:19:00.421 UTC [37517][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-03-16 13:19:00.421 UTC [37517][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.425 UTC [37517][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-16 13:19:00.425 UTC [37517][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54031 2024-03-16 13:19:00.432 UTC [37519][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54032 2024-03-16 13:19:00.438 UTC [37504][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:00.438 UTC [37504][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:00.439 UTC [37504][postmaster] LOG: background worker "logical replication launcher" (PID 37510) exited with exit code 1 2024-03-16 13:19:00.439 UTC [37505][checkpointer] LOG: shutting down 2024-03-16 13:19:00.439 UTC [37505][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:00.439 UTC [37505][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/413CD18, redo lsn=0/413CD18 2024-03-16 13:19:00.440 UTC [37504][postmaster] LOG: database system is shut down 2024-03-16 13:19:00.552 UTC [37530][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:00.552 UTC [37530][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:00.553 UTC [37530][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:00.554 UTC [37533][startup] LOG: database system was shut down at 2024-03-16 13:19:00 UTC 2024-03-16 13:19:00.556 UTC [37530][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:00.656 UTC [37541][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54034 2024-03-16 13:19:00.660 UTC [37541][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-03-16 13:19:00.660 UTC [37541][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.664 UTC [37541][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-16 13:19:00.664 UTC [37541][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54034 2024-03-16 13:19:00.668 UTC [37530][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:00.668 UTC [37530][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:00.670 UTC [37530][postmaster] LOG: background worker "logical replication launcher" (PID 37536) exited with exit code 1 2024-03-16 13:19:00.670 UTC [37531][checkpointer] LOG: shutting down 2024-03-16 13:19:00.670 UTC [37531][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:00.670 UTC [37531][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/413CD90, redo lsn=0/413CD90 2024-03-16 13:19:00.672 UTC [37530][postmaster] LOG: database system is shut down 2024-03-16 13:19:00.779 UTC [37555][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:00.779 UTC [37555][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:00.779 UTC [37555][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:00.781 UTC [37559][startup] LOG: database system was shut down at 2024-03-16 13:19:00 UTC 2024-03-16 13:19:00.783 UTC [37555][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:00.880 UTC [37568][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54036 2024-03-16 13:19:00.884 UTC [37568][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-03-16 13:19:00.884 UTC [37568][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.889 UTC [37568][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:00.889 UTC [37568][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54036 2024-03-16 13:19:00.895 UTC [37570][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54037 2024-03-16 13:19:00.899 UTC [37570][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-03-16 13:19:00.899 UTC [37570][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.900 UTC [37570][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:00.900 UTC [37570][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54037 2024-03-16 13:19:00.906 UTC [37573][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54038 2024-03-16 13:19:00.910 UTC [37573][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-03-16 13:19:00.910 UTC [37573][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:00.910 UTC [37573][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-16 13:19:00.911 UTC [37573][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54038 2024-03-16 13:19:00.917 UTC [37581][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54039 2024-03-16 13:19:00.928 UTC [37583][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54040 2024-03-16 13:19:00.936 UTC [37555][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:00.936 UTC [37555][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:00.937 UTC [37555][postmaster] LOG: background worker "logical replication launcher" (PID 37563) exited with exit code 1 2024-03-16 13:19:00.938 UTC [37557][checkpointer] LOG: shutting down 2024-03-16 13:19:00.938 UTC [37557][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:00.938 UTC [37557][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413CE08, redo lsn=0/413CE08 2024-03-16 13:19:00.940 UTC [37555][postmaster] LOG: database system is shut down 2024-03-16 13:19:01.049 UTC [37589][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:01.049 UTC [37589][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:01.049 UTC [37589][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:01.051 UTC [37593][startup] LOG: database system was shut down at 2024-03-16 13:19:00 UTC 2024-03-16 13:19:01.054 UTC [37589][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:01.151 UTC [37605][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54042 2024-03-16 13:19:01.155 UTC [37605][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-03-16 13:19:01.155 UTC [37605][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.159 UTC [37605][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:01.159 UTC [37605][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54042 2024-03-16 13:19:01.165 UTC [37607][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54043 2024-03-16 13:19:01.174 UTC [37609][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54044 2024-03-16 13:19:01.182 UTC [37589][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:01.182 UTC [37589][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:01.182 UTC [37589][postmaster] LOG: background worker "logical replication launcher" (PID 37596) exited with exit code 1 2024-03-16 13:19:01.183 UTC [37591][checkpointer] LOG: shutting down 2024-03-16 13:19:01.183 UTC [37591][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:01.183 UTC [37591][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/413CE80, redo lsn=0/413CE80 2024-03-16 13:19:01.184 UTC [37589][postmaster] LOG: database system is shut down 2024-03-16 13:19:01.299 UTC [37617][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:01.299 UTC [37617][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:01.299 UTC [37617][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:01.301 UTC [37620][startup] LOG: database system was shut down at 2024-03-16 13:19:01 UTC 2024-03-16 13:19:01.304 UTC [37617][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:01.396 UTC [37634][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54046 2024-03-16 13:19:01.400 UTC [37634][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-03-16 13:19:01.400 UTC [37634][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.404 UTC [37634][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-16 13:19:01.404 UTC [37634][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54046 2024-03-16 13:19:01.412 UTC [37636][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54047 2024-03-16 13:19:01.416 UTC [37636][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-03-16 13:19:01.416 UTC [37636][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.416 UTC [37636][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-03-16 13:19:01.417 UTC [37636][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54047 2024-03-16 13:19:01.423 UTC [37638][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54048 2024-03-16 13:19:01.432 UTC [37640][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54049 2024-03-16 13:19:01.436 UTC [37640][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-03-16 13:19:01.436 UTC [37640][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.436 UTC [37640][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-03-16 13:19:01.437 UTC [37640][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54049 2024-03-16 13:19:01.442 UTC [37642][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54050 2024-03-16 13:19:01.446 UTC [37642][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-03-16 13:19:01.446 UTC [37642][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.447 UTC [37642][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-03-16 13:19:01.447 UTC [37642][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54050 2024-03-16 13:19:01.452 UTC [37645][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54051 2024-03-16 13:19:01.456 UTC [37645][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-03-16 13:19:01.457 UTC [37645][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.457 UTC [37645][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-03-16 13:19:01.458 UTC [37645][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54051 2024-03-16 13:19:01.464 UTC [37649][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54053 2024-03-16 13:19:01.473 UTC [37651][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54054 2024-03-16 13:19:01.479 UTC [37617][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:01.479 UTC [37617][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:01.480 UTC [37617][postmaster] LOG: background worker "logical replication launcher" (PID 37623) exited with exit code 1 2024-03-16 13:19:01.480 UTC [37618][checkpointer] LOG: shutting down 2024-03-16 13:19:01.480 UTC [37618][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:01.480 UTC [37618][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/413CEF8, redo lsn=0/413CEF8 2024-03-16 13:19:01.482 UTC [37617][postmaster] LOG: database system is shut down 2024-03-16 13:19:01.591 UTC [37655][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:01.591 UTC [37655][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:01.591 UTC [37655][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:01.593 UTC [37658][startup] LOG: database system was shut down at 2024-03-16 13:19:01 UTC 2024-03-16 13:19:01.595 UTC [37655][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:01.693 UTC [37664][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54055 2024-03-16 13:19:01.697 UTC [37664][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-03-16 13:19:01.697 UTC [37664][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.701 UTC [37664][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:01.702 UTC [37664][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54055 2024-03-16 13:19:01.709 UTC [37667][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54056 2024-03-16 13:19:01.713 UTC [37667][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-03-16 13:19:01.713 UTC [37667][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.713 UTC [37667][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:01.714 UTC [37667][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54056 2024-03-16 13:19:01.720 UTC [37669][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54057 2024-03-16 13:19:01.730 UTC [37655][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:01.730 UTC [37655][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:01.731 UTC [37655][postmaster] LOG: background worker "logical replication launcher" (PID 37661) exited with exit code 1 2024-03-16 13:19:01.732 UTC [37656][checkpointer] LOG: shutting down 2024-03-16 13:19:01.732 UTC [37656][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:01.732 UTC [37656][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/413CF70, redo lsn=0/413CF70 2024-03-16 13:19:01.734 UTC [37655][postmaster] LOG: database system is shut down 2024-03-16 13:19:01.847 UTC [37672][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:01.847 UTC [37672][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:01.847 UTC [37672][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:01.849 UTC [37675][startup] LOG: database system was shut down at 2024-03-16 13:19:01 UTC 2024-03-16 13:19:01.851 UTC [37672][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:01.949 UTC [37682][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54058 2024-03-16 13:19:01.953 UTC [37682][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-03-16 13:19:01.953 UTC [37682][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.957 UTC [37682][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-16 13:19:01.958 UTC [37682][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54058 2024-03-16 13:19:01.964 UTC [37684][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54059 2024-03-16 13:19:01.968 UTC [37684][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-03-16 13:19:01.968 UTC [37684][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.968 UTC [37684][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-16 13:19:01.969 UTC [37684][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54059 2024-03-16 13:19:01.974 UTC [37686][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54060 2024-03-16 13:19:01.978 UTC [37686][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-03-16 13:19:01.978 UTC [37686][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:01.979 UTC [37686][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-16 13:19:01.979 UTC [37686][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54060 2024-03-16 13:19:01.981 UTC [37672][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:01.981 UTC [37672][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:01.982 UTC [37672][postmaster] LOG: background worker "logical replication launcher" (PID 37678) exited with exit code 1 2024-03-16 13:19:01.982 UTC [37673][checkpointer] LOG: shutting down 2024-03-16 13:19:01.982 UTC [37673][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:01.983 UTC [37673][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/413CFE8, redo lsn=0/413CFE8 2024-03-16 13:19:01.984 UTC [37672][postmaster] LOG: database system is shut down 2024-03-16 13:19:02.099 UTC [37690][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:02.099 UTC [37690][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:02.099 UTC [37690][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:02.101 UTC [37693][startup] LOG: database system was shut down at 2024-03-16 13:19:01 UTC 2024-03-16 13:19:02.103 UTC [37690][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:02.200 UTC [37699][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54061 2024-03-16 13:19:02.204 UTC [37699][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-03-16 13:19:02.204 UTC [37699][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.208 UTC [37699][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-03-16 13:19:02.209 UTC [37699][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54061 2024-03-16 13:19:02.214 UTC [37701][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54062 2024-03-16 13:19:02.218 UTC [37701][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-03-16 13:19:02.218 UTC [37701][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.219 UTC [37701][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-16 13:19:02.219 UTC [37701][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54062 2024-03-16 13:19:02.225 UTC [37703][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54063 2024-03-16 13:19:02.231 UTC [37690][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:02.231 UTC [37690][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:02.232 UTC [37690][postmaster] LOG: background worker "logical replication launcher" (PID 37696) exited with exit code 1 2024-03-16 13:19:02.232 UTC [37691][checkpointer] LOG: shutting down 2024-03-16 13:19:02.232 UTC [37691][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:02.232 UTC [37691][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/413D060, redo lsn=0/413D060 2024-03-16 13:19:02.233 UTC [37690][postmaster] LOG: database system is shut down 2024-03-16 13:19:02.348 UTC [37705][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:02.348 UTC [37705][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:02.348 UTC [37705][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:02.349 UTC [37708][startup] LOG: database system was shut down at 2024-03-16 13:19:02 UTC 2024-03-16 13:19:02.351 UTC [37705][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:02.450 UTC [37713][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54064 2024-03-16 13:19:02.454 UTC [37713][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-03-16 13:19:02.454 UTC [37713][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.459 UTC [37713][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-16 13:19:02.459 UTC [37713][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54064 2024-03-16 13:19:02.467 UTC [37715][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54065 2024-03-16 13:19:02.471 UTC [37715][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-03-16 13:19:02.471 UTC [37715][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.472 UTC [37715][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:02.472 UTC [37715][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54065 2024-03-16 13:19:02.479 UTC [37717][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54066 2024-03-16 13:19:02.483 UTC [37717][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-03-16 13:19:02.483 UTC [37717][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.483 UTC [37717][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-16 13:19:02.484 UTC [37717][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=54066 2024-03-16 13:19:02.486 UTC [37705][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:02.486 UTC [37705][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:02.487 UTC [37705][postmaster] LOG: background worker "logical replication launcher" (PID 37711) exited with exit code 1 2024-03-16 13:19:02.487 UTC [37706][checkpointer] LOG: shutting down 2024-03-16 13:19:02.487 UTC [37706][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:02.488 UTC [37706][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/413D0D8, redo lsn=0/413D0D8 2024-03-16 13:19:02.489 UTC [37705][postmaster] LOG: database system is shut down 2024-03-16 13:19:02.598 UTC [37719][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:02.598 UTC [37719][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:02.598 UTC [37719][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:02.599 UTC [37722][startup] LOG: database system was shut down at 2024-03-16 13:19:02 UTC 2024-03-16 13:19:02.601 UTC [37719][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:02.700 UTC [37727][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54067 2024-03-16 13:19:02.704 UTC [37727][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-03-16 13:19:02.704 UTC [37727][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.708 UTC [37727][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-16 13:19:02.709 UTC [37727][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54067 2024-03-16 13:19:02.714 UTC [37729][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54068 2024-03-16 13:19:02.720 UTC [37719][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:02.720 UTC [37719][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:02.721 UTC [37719][postmaster] LOG: background worker "logical replication launcher" (PID 37725) exited with exit code 1 2024-03-16 13:19:02.721 UTC [37720][checkpointer] LOG: shutting down 2024-03-16 13:19:02.721 UTC [37720][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:02.721 UTC [37720][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/413D150, redo lsn=0/413D150 2024-03-16 13:19:02.722 UTC [37719][postmaster] LOG: database system is shut down 2024-03-16 13:19:02.836 UTC [37731][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:02.837 UTC [37731][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:02.837 UTC [37731][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:02.838 UTC [37734][startup] LOG: database system was shut down at 2024-03-16 13:19:02 UTC 2024-03-16 13:19:02.840 UTC [37731][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:02.939 UTC [37739][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54069 2024-03-16 13:19:02.942 UTC [37739][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:19:02.952 UTC [37742][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54070 2024-03-16 13:19:02.956 UTC [37742][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-03-16 13:19:02.956 UTC [37742][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:02.960 UTC [37742][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-16 13:19:02.960 UTC [37742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54070 2024-03-16 13:19:02.966 UTC [37744][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54071 2024-03-16 13:19:02.972 UTC [37731][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:02.972 UTC [37731][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:02.972 UTC [37731][postmaster] LOG: background worker "logical replication launcher" (PID 37737) exited with exit code 1 2024-03-16 13:19:02.972 UTC [37732][checkpointer] LOG: shutting down 2024-03-16 13:19:02.972 UTC [37732][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:02.973 UTC [37732][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/413D1C8, redo lsn=0/413D1C8 2024-03-16 13:19:02.974 UTC [37731][postmaster] LOG: database system is shut down 2024-03-16 13:19:03.088 UTC [37746][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:03.088 UTC [37746][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:03.088 UTC [37746][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:03.089 UTC [37749][startup] LOG: database system was shut down at 2024-03-16 13:19:02 UTC 2024-03-16 13:19:03.091 UTC [37746][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:03.190 UTC [37754][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54072 2024-03-16 13:19:03.194 UTC [37754][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-03-16 13:19:03.194 UTC [37754][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.198 UTC [37754][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-16 13:19:03.199 UTC [37754][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54072 2024-03-16 13:19:03.204 UTC [37756][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54073 2024-03-16 13:19:03.208 UTC [37756][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-16 13:19:03.213 UTC [37758][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54074 2024-03-16 13:19:03.216 UTC [37758][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-16 13:19:03.221 UTC [37760][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54075 2024-03-16 13:19:03.224 UTC [37760][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-03-16 13:19:03.224 UTC [37760][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.225 UTC [37760][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-16 13:19:03.227 UTC [37760][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=54075 2024-03-16 13:19:03.232 UTC [37762][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54076 2024-03-16 13:19:03.235 UTC [37762][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-03-16 13:19:03.235 UTC [37762][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-03-16 13:19:03.236 UTC [37762][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-03-16 13:19:03.236 UTC [37762][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=54076 2024-03-16 13:19:03.253 UTC [37767][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54077 2024-03-16 13:19:03.257 UTC [37767][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-16 13:19:03.262 UTC [37769][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54078 2024-03-16 13:19:03.267 UTC [37769][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-03-16 13:19:03.267 UTC [37769][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.271 UTC [37769][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_wEso/client.key$$ 2024-03-16 13:19:03.272 UTC [37769][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=54078 2024-03-16 13:19:03.277 UTC [37771][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54079 2024-03-16 13:19:03.282 UTC [37771][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-03-16 13:19:03.282 UTC [37771][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.283 UTC [37771][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_wEso/client-der.key$$ 2024-03-16 13:19:03.283 UTC [37771][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=54079 2024-03-16 13:19:03.288 UTC [37773][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54080 2024-03-16 13:19:03.294 UTC [37773][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-03-16 13:19:03.295 UTC [37773][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.295 UTC [37773][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_wEso/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-16 13:19:03.295 UTC [37773][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=54080 2024-03-16 13:19:03.300 UTC [37775][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54081 2024-03-16 13:19:03.306 UTC [37775][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-03-16 13:19:03.306 UTC [37775][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.306 UTC [37775][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_wEso/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-16 13:19:03.307 UTC [37775][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=54081 2024-03-16 13:19:03.312 UTC [37777][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54082 2024-03-16 13:19:03.317 UTC [37777][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-03-16 13:19:03.317 UTC [37777][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.318 UTC [37777][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_wEso/client.key$$ 2024-03-16 13:19:03.318 UTC [37777][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=54082 2024-03-16 13:19:03.323 UTC [37779][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54083 2024-03-16 13:19:03.328 UTC [37779][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-03-16 13:19:03.328 UTC [37779][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.329 UTC [37779][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_wEso/client.key$$ 2024-03-16 13:19:03.329 UTC [37779][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=54083 2024-03-16 13:19:03.334 UTC [37781][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54084 2024-03-16 13:19:03.338 UTC [37781][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-16 13:19:03.343 UTC [37783][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54085 2024-03-16 13:19:03.345 UTC [37783][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:19:03.350 UTC [37785][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54086 2024-03-16 13:19:03.356 UTC [37785][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-03-16 13:19:03.356 UTC [37785][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.360 UTC [37785][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_wEso/client-dn.key$$ 2024-03-16 13:19:03.360 UTC [37785][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=54086 2024-03-16 13:19:03.365 UTC [37787][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54087 2024-03-16 13:19:03.371 UTC [37787][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-03-16 13:19:03.371 UTC [37787][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.375 UTC [37787][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_wEso/client-dn.key$$ 2024-03-16 13:19:03.375 UTC [37787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn_re host=localhost port=54087 2024-03-16 13:19:03.380 UTC [37789][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54088 2024-03-16 13:19:03.386 UTC [37789][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-03-16 13:19:03.386 UTC [37789][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.390 UTC [37789][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_wEso/client-dn.key$$ 2024-03-16 13:19:03.390 UTC [37789][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_cn host=localhost port=54088 2024-03-16 13:19:03.410 UTC [37792][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54089 2024-03-16 13:19:03.416 UTC [37792][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-03-16 13:19:03.416 UTC [37792][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.416 UTC [37792][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-16 13:19:03.418 UTC [37792][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=54089 2024-03-16 13:19:03.423 UTC [37794][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54090 2024-03-16 13:19:03.424 UTC [37794][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:19:03.430 UTC [37796][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54091 2024-03-16 13:19:03.435 UTC [37796][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-03-16 13:19:03.435 UTC [37796][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-16 13:19:03.435 UTC [37796][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-16 13:19:03.435 UTC [37796][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-03-16 13:19:03.440 UTC [37798][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54092 2024-03-16 13:19:03.445 UTC [37798][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:19:03.445 UTC [37798][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-16 13:19:03.451 UTC [37800][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54093 2024-03-16 13:19:03.456 UTC [37800][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-03-16 13:19:03.456 UTC [37800][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.460 UTC [37800][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_wEso/client.key$$ 2024-03-16 13:19:03.461 UTC [37800][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=54093 2024-03-16 13:19:03.466 UTC [37802][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54094 2024-03-16 13:19:03.471 UTC [37802][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-16 13:19:03.471 UTC [37802][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-16 13:19:03.471 UTC [37802][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-16 13:19:03.471 UTC [37802][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-03-16 13:19:03.477 UTC [37804][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54095 2024-03-16 13:19:03.482 UTC [37804][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-03-16 13:19:03.483 UTC [37804][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.483 UTC [37804][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_wEso/client.key$$ 2024-03-16 13:19:03.484 UTC [37804][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=54095 2024-03-16 13:19:03.486 UTC [37746][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:03.486 UTC [37746][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:03.487 UTC [37746][postmaster] LOG: background worker "logical replication launcher" (PID 37752) exited with exit code 1 2024-03-16 13:19:03.487 UTC [37747][checkpointer] LOG: shutting down 2024-03-16 13:19:03.487 UTC [37747][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:03.488 UTC [37747][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/413D240, redo lsn=0/413D240 2024-03-16 13:19:03.489 UTC [37746][postmaster] LOG: database system is shut down 2024-03-16 13:19:03.603 UTC [37806][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:03.603 UTC [37806][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:03.603 UTC [37806][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:03.604 UTC [37809][startup] LOG: database system was shut down at 2024-03-16 13:19:03 UTC 2024-03-16 13:19:03.606 UTC [37806][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:03.705 UTC [37814][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54096 2024-03-16 13:19:03.710 UTC [37814][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-03-16 13:19:03.710 UTC [37814][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:03.714 UTC [37814][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_wEso/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-16 13:19:03.715 UTC [37814][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=54096 2024-03-16 13:19:03.720 UTC [37816][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54097 2024-03-16 13:19:03.725 UTC [37816][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:19:03.725 UTC [37816][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-16 13:19:03.730 UTC [37818][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54098 2024-03-16 13:19:03.736 UTC [37818][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:19:03.736 UTC [37818][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-16 13:19:03.739 UTC [37806][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:03.739 UTC [37806][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:03.740 UTC [37806][postmaster] LOG: background worker "logical replication launcher" (PID 37812) exited with exit code 1 2024-03-16 13:19:03.740 UTC [37807][checkpointer] LOG: shutting down 2024-03-16 13:19:03.740 UTC [37807][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:03.741 UTC [37807][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/413D2B8, redo lsn=0/413D2B8 2024-03-16 13:19:03.742 UTC [37806][postmaster] LOG: database system is shut down 2024-03-16 13:19:03.855 UTC [37820][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:03.855 UTC [37820][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:03.855 UTC [37820][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:03.857 UTC [37823][startup] LOG: database system was shut down at 2024-03-16 13:19:03 UTC 2024-03-16 13:19:03.860 UTC [37820][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:03.958 UTC [37828][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54099 2024-03-16 13:19:03.963 UTC [37828][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:19:03.963 UTC [37828][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-03-16 13:19:03.967 UTC [37820][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:03.967 UTC [37820][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:03.968 UTC [37820][postmaster] LOG: background worker "logical replication launcher" (PID 37826) exited with exit code 1 2024-03-16 13:19:03.968 UTC [37821][checkpointer] LOG: shutting down 2024-03-16 13:19:03.968 UTC [37821][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:03.969 UTC [37821][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/413D330, redo lsn=0/413D330 2024-03-16 13:19:03.970 UTC [37820][postmaster] LOG: database system is shut down 2024-03-16 13:19:04.085 UTC [37830][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:04.085 UTC [37830][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:04.085 UTC [37830][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:04.087 UTC [37833][startup] LOG: database system was shut down at 2024-03-16 13:19:03 UTC 2024-03-16 13:19:04.090 UTC [37830][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:04.186 UTC [37838][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54100 2024-03-16 13:19:04.192 UTC [37838][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:19:04.192 UTC [37838][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-16 13:19:04.198 UTC [37840][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54101 2024-03-16 13:19:04.203 UTC [37840][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:19:04.203 UTC [37840][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-16 13:19:04.205 UTC [37830][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:04.205 UTC [37830][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:04.206 UTC [37830][postmaster] LOG: background worker "logical replication launcher" (PID 37836) exited with exit code 1 2024-03-16 13:19:04.206 UTC [37831][checkpointer] LOG: shutting down 2024-03-16 13:19:04.206 UTC [37831][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:04.207 UTC [37831][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/413D3A8, redo lsn=0/413D3A8 2024-03-16 13:19:04.208 UTC [37830][postmaster] LOG: database system is shut down 2024-03-16 13:19:04.318 UTC [37842][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:04.318 UTC [37842][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:04.318 UTC [37842][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:04.320 UTC [37845][startup] LOG: database system was shut down at 2024-03-16 13:19:04 UTC 2024-03-16 13:19:04.322 UTC [37842][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:04.420 UTC [37850][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54102 2024-03-16 13:19:04.422 UTC [37850][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:04.422 UTC [37850][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:04.430 UTC [37852][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54103 2024-03-16 13:19:04.434 UTC [37852][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-03-16 13:19:04.434 UTC [37852][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:04.439 UTC [37852][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:04.439 UTC [37852][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54103 2024-03-16 13:19:04.442 UTC [37842][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:04.442 UTC [37842][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:04.443 UTC [37842][postmaster] LOG: background worker "logical replication launcher" (PID 37848) exited with exit code 1 2024-03-16 13:19:04.443 UTC [37843][checkpointer] LOG: shutting down 2024-03-16 13:19:04.443 UTC [37843][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:04.443 UTC [37843][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413D420, redo lsn=0/413D420 2024-03-16 13:19:04.445 UTC [37842][postmaster] LOG: database system is shut down 2024-03-16 13:19:04.559 UTC [37854][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:04.559 UTC [37854][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:04.559 UTC [37854][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:04.560 UTC [37857][startup] LOG: database system was shut down at 2024-03-16 13:19:04 UTC 2024-03-16 13:19:04.562 UTC [37854][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:04.661 UTC [37862][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54104 2024-03-16 13:19:04.662 UTC [37862][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:04.662 UTC [37862][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:04.668 UTC [37864][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54105 2024-03-16 13:19:04.672 UTC [37864][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-03-16 13:19:04.672 UTC [37864][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:04.676 UTC [37864][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:04.676 UTC [37864][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54105 2024-03-16 13:19:04.679 UTC [37854][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:04.679 UTC [37854][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:04.680 UTC [37854][postmaster] LOG: background worker "logical replication launcher" (PID 37860) exited with exit code 1 2024-03-16 13:19:04.680 UTC [37855][checkpointer] LOG: shutting down 2024-03-16 13:19:04.680 UTC [37855][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:04.680 UTC [37855][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/413D498, redo lsn=0/413D498 2024-03-16 13:19:04.681 UTC [37854][postmaster] LOG: database system is shut down 2024-03-16 13:19:04.795 UTC [37866][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:04.795 UTC [37866][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:04.795 UTC [37866][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:04.796 UTC [37869][startup] LOG: database system was shut down at 2024-03-16 13:19:04 UTC 2024-03-16 13:19:04.799 UTC [37866][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:04.898 UTC [37874][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54106 2024-03-16 13:19:04.899 UTC [37874][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:04.899 UTC [37874][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:04.905 UTC [37876][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54107 2024-03-16 13:19:04.909 UTC [37876][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-03-16 13:19:04.909 UTC [37876][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:04.914 UTC [37876][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:04.914 UTC [37876][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54107 2024-03-16 13:19:04.917 UTC [37866][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:04.917 UTC [37866][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:04.918 UTC [37866][postmaster] LOG: background worker "logical replication launcher" (PID 37872) exited with exit code 1 2024-03-16 13:19:04.918 UTC [37867][checkpointer] LOG: shutting down 2024-03-16 13:19:04.918 UTC [37867][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:04.918 UTC [37867][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/413D510, redo lsn=0/413D510 2024-03-16 13:19:04.920 UTC [37866][postmaster] LOG: database system is shut down 2024-03-16 13:19:05.031 UTC [37878][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:05.031 UTC [37878][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:05.031 UTC [37878][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:05.033 UTC [37881][startup] LOG: database system was shut down at 2024-03-16 13:19:04 UTC 2024-03-16 13:19:05.035 UTC [37878][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:05.134 UTC [37886][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54108 2024-03-16 13:19:05.135 UTC [37886][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:05.135 UTC [37886][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:05.141 UTC [37888][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54109 2024-03-16 13:19:05.144 UTC [37888][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-03-16 13:19:05.144 UTC [37888][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:05.148 UTC [37888][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:05.149 UTC [37888][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54109 2024-03-16 13:19:05.151 UTC [37878][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:05.151 UTC [37878][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:05.152 UTC [37878][postmaster] LOG: background worker "logical replication launcher" (PID 37884) exited with exit code 1 2024-03-16 13:19:05.152 UTC [37879][checkpointer] LOG: shutting down 2024-03-16 13:19:05.152 UTC [37879][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:05.153 UTC [37879][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/413D588, redo lsn=0/413D588 2024-03-16 13:19:05.154 UTC [37878][postmaster] LOG: database system is shut down 2024-03-16 13:19:05.268 UTC [37890][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:05.268 UTC [37890][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:05.268 UTC [37890][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:05.269 UTC [37893][startup] LOG: database system was shut down at 2024-03-16 13:19:05 UTC 2024-03-16 13:19:05.271 UTC [37890][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:05.370 UTC [37898][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54110 2024-03-16 13:19:05.371 UTC [37898][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:05.371 UTC [37898][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:05.378 UTC [37900][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54111 2024-03-16 13:19:05.381 UTC [37900][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-03-16 13:19:05.381 UTC [37900][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:05.385 UTC [37900][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:05.386 UTC [37900][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54111 2024-03-16 13:19:05.388 UTC [37890][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:05.388 UTC [37890][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:05.389 UTC [37890][postmaster] LOG: background worker "logical replication launcher" (PID 37896) exited with exit code 1 2024-03-16 13:19:05.389 UTC [37891][checkpointer] LOG: shutting down 2024-03-16 13:19:05.389 UTC [37891][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:05.389 UTC [37891][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/413D600, redo lsn=0/413D600 2024-03-16 13:19:05.390 UTC [37890][postmaster] LOG: database system is shut down 2024-03-16 13:19:05.505 UTC [37902][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:05.505 UTC [37902][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:05.505 UTC [37902][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:05.506 UTC [37905][startup] LOG: database system was shut down at 2024-03-16 13:19:05 UTC 2024-03-16 13:19:05.508 UTC [37902][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:05.607 UTC [37910][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54112 2024-03-16 13:19:05.609 UTC [37910][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:05.609 UTC [37910][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:05.616 UTC [37912][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54113 2024-03-16 13:19:05.620 UTC [37912][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-03-16 13:19:05.620 UTC [37912][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:05.624 UTC [37912][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:05.625 UTC [37912][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54113 2024-03-16 13:19:05.628 UTC [37902][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:05.628 UTC [37902][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:05.629 UTC [37902][postmaster] LOG: background worker "logical replication launcher" (PID 37908) exited with exit code 1 2024-03-16 13:19:05.629 UTC [37903][checkpointer] LOG: shutting down 2024-03-16 13:19:05.629 UTC [37903][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:05.630 UTC [37903][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/413D678, redo lsn=0/413D678 2024-03-16 13:19:05.631 UTC [37902][postmaster] LOG: database system is shut down 2024-03-16 13:19:05.744 UTC [37914][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:05.745 UTC [37914][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:05.745 UTC [37914][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:05.746 UTC [37917][startup] LOG: database system was shut down at 2024-03-16 13:19:05 UTC 2024-03-16 13:19:05.748 UTC [37914][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:05.848 UTC [37922][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54114 2024-03-16 13:19:05.849 UTC [37922][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:05.849 UTC [37922][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:05.856 UTC [37924][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54115 2024-03-16 13:19:05.860 UTC [37924][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-03-16 13:19:05.860 UTC [37924][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:05.864 UTC [37924][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:05.864 UTC [37924][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54115 2024-03-16 13:19:05.867 UTC [37914][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:05.867 UTC [37914][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:05.868 UTC [37914][postmaster] LOG: background worker "logical replication launcher" (PID 37920) exited with exit code 1 2024-03-16 13:19:05.868 UTC [37915][checkpointer] LOG: shutting down 2024-03-16 13:19:05.868 UTC [37915][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:05.868 UTC [37915][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413D6F0, redo lsn=0/413D6F0 2024-03-16 13:19:05.870 UTC [37914][postmaster] LOG: database system is shut down 2024-03-16 13:19:05.983 UTC [37926][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:05.983 UTC [37926][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:05.983 UTC [37926][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:05.985 UTC [37929][startup] LOG: database system was shut down at 2024-03-16 13:19:05 UTC 2024-03-16 13:19:05.987 UTC [37926][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:06.086 UTC [37934][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54116 2024-03-16 13:19:06.087 UTC [37934][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:06.087 UTC [37934][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:06.093 UTC [37936][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54117 2024-03-16 13:19:06.097 UTC [37936][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-03-16 13:19:06.097 UTC [37936][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:06.101 UTC [37936][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:06.102 UTC [37936][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54117 2024-03-16 13:19:06.105 UTC [37926][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:06.105 UTC [37926][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:06.106 UTC [37926][postmaster] LOG: background worker "logical replication launcher" (PID 37932) exited with exit code 1 2024-03-16 13:19:06.107 UTC [37927][checkpointer] LOG: shutting down 2024-03-16 13:19:06.107 UTC [37927][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:06.107 UTC [37927][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/413D768, redo lsn=0/413D768 2024-03-16 13:19:06.108 UTC [37926][postmaster] LOG: database system is shut down 2024-03-16 13:19:06.218 UTC [37938][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-16 13:19:06.218 UTC [37938][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60863 2024-03-16 13:19:06.218 UTC [37938][postmaster] LOG: listening on Unix socket "/tmp/R_9jImeSL6/.s.PGSQL.60863" 2024-03-16 13:19:06.220 UTC [37941][startup] LOG: database system was shut down at 2024-03-16 13:19:06 UTC 2024-03-16 13:19:06.223 UTC [37938][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:19:06.322 UTC [37946][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54118 2024-03-16 13:19:06.323 UTC [37946][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:19:06.323 UTC [37946][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:19:06.331 UTC [37948][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54119 2024-03-16 13:19:06.336 UTC [37948][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-03-16 13:19:06.336 UTC [37948][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:19:06.340 UTC [37948][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-16 13:19:06.340 UTC [37948][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54119 2024-03-16 13:19:06.344 UTC [37938][postmaster] LOG: received fast shutdown request 2024-03-16 13:19:06.344 UTC [37938][postmaster] LOG: aborting any active transactions 2024-03-16 13:19:06.345 UTC [37938][postmaster] LOG: background worker "logical replication launcher" (PID 37944) exited with exit code 1 2024-03-16 13:19:06.345 UTC [37939][checkpointer] LOG: shutting down 2024-03-16 13:19:06.345 UTC [37939][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:19:06.346 UTC [37939][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/413D7E0, redo lsn=0/413D7E0 2024-03-16 13:19:06.347 UTC [37938][postmaster] LOG: database system is shut down 2024-03-16 13:19:06.457 UTC [37950][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-16 13:19:06.457 UTC [37950][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:19:06.558](0.216s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"