[08:40:36.805](0.025s) # setting up data directory # Checking port 61771 # Found port 61771 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=61771 host=/tmp/CBd5MFkyc5 Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [08:40:36.808](0.004s) # 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 37470 [08:40:36.980](0.172s) 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 37715 [08:40:37.945](0.965s) # 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-26 08:40:36.874 UTC [37470][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:36.874 UTC [37470][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:36.876 UTC [37477][startup] LOG: database system was shut down at 2024-03-26 08:38:12 UTC 2024-03-26 08:40:36.878 UTC [37470][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:36.974 UTC [37502][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:36.974 UTC [37502][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-26 08:40:36.974 UTC [37502][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:36.978 UTC [37502][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-26 08:40:36.979 UTC [37502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-26 08:40:36.986 UTC [37506][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:36.986 UTC [37506][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-26 08:40:36.986 UTC [37506][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:36.987 UTC [37506][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-26 08:40:36.987 UTC [37506][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:36.988 UTC [37506][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.033 UTC [37520][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.034 UTC [37520][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-26 08:40:37.034 UTC [37520][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.036 UTC [37520][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-26 08:40:37.036 UTC [37520][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.037 UTC [37520][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.078 UTC [37534][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.079 UTC [37534][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-26 08:40:37.079 UTC [37534][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.079 UTC [37534][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-26 08:40:37.079 UTC [37534][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.080 UTC [37534][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.134 UTC [37553][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.134 UTC [37553][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-26 08:40:37.134 UTC [37553][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.135 UTC [37553][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-26 08:40:37.135 UTC [37553][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.136 UTC [37553][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.175 UTC [37563][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.175 UTC [37563][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-26 08:40:37.175 UTC [37563][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.176 UTC [37563][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-26 08:40:37.176 UTC [37563][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.196 UTC [37563][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.241 UTC [37583][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.246 UTC [37583][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-26 08:40:37.246 UTC [37583][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.248 UTC [37583][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-26 08:40:37.248 UTC [37583][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.297 UTC [37583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.382 UTC [37607][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.382 UTC [37607][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-26 08:40:37.382 UTC [37607][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.383 UTC [37607][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-26 08:40:37.383 UTC [37607][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.412 UTC [37607][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.421 UTC [37613][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.422 UTC [37613][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-26 08:40:37.422 UTC [37613][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.422 UTC [37613][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-26 08:40:37.422 UTC [37613][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.522 UTC [37613][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.101 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.562 UTC [37644][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.562 UTC [37644][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-26 08:40:37.562 UTC [37644][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.565 UTC [37644][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-26 08:40:37.566 UTC [37644][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.597 UTC [37644][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.609 UTC [37660][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.609 UTC [37660][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-26 08:40:37.609 UTC [37660][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.610 UTC [37660][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-26 08:40:37.610 UTC [37660][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.653 UTC [37660][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.739 UTC [37470][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:37.739 UTC [37470][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:37.742 UTC [37470][postmaster] LOG: background worker "logical replication launcher" (PID 37481) exited with exit code 1 2024-03-26 08:40:37.742 UTC [37475][checkpointer] LOG: shutting down 2024-03-26 08:40:37.742 UTC [37475][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:37.782 UTC [37475][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.040 s, sync=0.001 s, total=0.040 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102190, redo lsn=0/4102190 2024-03-26 08:40:37.786 UTC [37470][postmaster] LOG: database system is shut down 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:37.853 UTC [37719][startup] LOG: database system was shut down at 2024-03-26 08:40:37 UTC 2024-03-26 08:40:37.856 UTC [37715][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:37.951 UTC [37715][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:37.951 UTC [37715][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:37.955 UTC [37715][postmaster] LOG: background worker "logical replication launcher" (PID 37722) exited with exit code 1 2024-03-26 08:40:37.955 UTC [37717][checkpointer] LOG: shutting down 2024-03-26 08:40:37.955 UTC [37717][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:37.956 UTC [37717][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/4102208, redo lsn=0/4102208 2024-03-26 08:40:37.957 UTC [37715][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.064 UTC [37762][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-26 08:40:38.064 UTC [37762][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:40:38.155](0.211s) 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 37781 [08:40:38.261](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-26 08:40:36.874 UTC [37470][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:36.874 UTC [37470][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:36.876 UTC [37477][startup] LOG: database system was shut down at 2024-03-26 08:38:12 UTC 2024-03-26 08:40:36.878 UTC [37470][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:36.974 UTC [37502][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:36.974 UTC [37502][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-26 08:40:36.974 UTC [37502][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:36.978 UTC [37502][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-26 08:40:36.979 UTC [37502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-26 08:40:36.986 UTC [37506][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:36.986 UTC [37506][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-26 08:40:36.986 UTC [37506][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:36.987 UTC [37506][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-26 08:40:36.987 UTC [37506][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:36.988 UTC [37506][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.033 UTC [37520][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.034 UTC [37520][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-26 08:40:37.034 UTC [37520][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.036 UTC [37520][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-26 08:40:37.036 UTC [37520][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.037 UTC [37520][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.078 UTC [37534][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.079 UTC [37534][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-26 08:40:37.079 UTC [37534][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.079 UTC [37534][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-26 08:40:37.079 UTC [37534][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.080 UTC [37534][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.134 UTC [37553][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.134 UTC [37553][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-26 08:40:37.134 UTC [37553][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.135 UTC [37553][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-26 08:40:37.135 UTC [37553][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.136 UTC [37553][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.175 UTC [37563][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.175 UTC [37563][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-26 08:40:37.175 UTC [37563][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.176 UTC [37563][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-26 08:40:37.176 UTC [37563][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.196 UTC [37563][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.241 UTC [37583][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.246 UTC [37583][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-26 08:40:37.246 UTC [37583][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.248 UTC [37583][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-26 08:40:37.248 UTC [37583][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.297 UTC [37583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.382 UTC [37607][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.382 UTC [37607][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-26 08:40:37.382 UTC [37607][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.383 UTC [37607][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-26 08:40:37.383 UTC [37607][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.412 UTC [37607][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.421 UTC [37613][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.422 UTC [37613][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-26 08:40:37.422 UTC [37613][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.422 UTC [37613][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-26 08:40:37.422 UTC [37613][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.522 UTC [37613][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.101 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.562 UTC [37644][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.562 UTC [37644][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-26 08:40:37.562 UTC [37644][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.565 UTC [37644][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-26 08:40:37.566 UTC [37644][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.597 UTC [37644][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.609 UTC [37660][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.609 UTC [37660][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-26 08:40:37.609 UTC [37660][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.610 UTC [37660][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-26 08:40:37.610 UTC [37660][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.653 UTC [37660][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.739 UTC [37470][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:37.739 UTC [37470][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:37.742 UTC [37470][postmaster] LOG: background worker "logical replication launcher" (PID 37481) exited with exit code 1 2024-03-26 08:40:37.742 UTC [37475][checkpointer] LOG: shutting down 2024-03-26 08:40:37.742 UTC [37475][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:37.782 UTC [37475][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.040 s, sync=0.001 s, total=0.040 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102190, redo lsn=0/4102190 2024-03-26 08:40:37.786 UTC [37470][postmaster] LOG: database system is shut down 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:37.853 UTC [37719][startup] LOG: database system was shut down at 2024-03-26 08:40:37 UTC 2024-03-26 08:40:37.856 UTC [37715][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:37.951 UTC [37715][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:37.951 UTC [37715][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:37.955 UTC [37715][postmaster] LOG: background worker "logical replication launcher" (PID 37722) exited with exit code 1 2024-03-26 08:40:37.955 UTC [37717][checkpointer] LOG: shutting down 2024-03-26 08:40:37.955 UTC [37717][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:37.956 UTC [37717][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/4102208, redo lsn=0/4102208 2024-03-26 08:40:37.957 UTC [37715][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.064 UTC [37762][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-26 08:40:38.064 UTC [37762][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.171 UTC [37781][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:38.171 UTC [37781][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:38.171 UTC [37781][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:38.173 UTC [37787][startup] LOG: database system was shut down at 2024-03-26 08:40:37 UTC 2024-03-26 08:40:38.176 UTC [37781][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:38.263 UTC [37781][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:38.263 UTC [37781][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:38.264 UTC [37781][postmaster] LOG: background worker "logical replication launcher" (PID 37790) exited with exit code 1 2024-03-26 08:40:38.264 UTC [37785][checkpointer] LOG: shutting down 2024-03-26 08:40:38.264 UTC [37785][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:38.265 UTC [37785][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/4102280, redo lsn=0/4102280 2024-03-26 08:40:38.266 UTC [37781][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.376 UTC [37813][postmaster] FATAL: could not set SSL protocol version range 2024-03-26 08:40:38.376 UTC [37813][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-26 08:40:38.376 UTC [37813][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:40:38.475](0.214s) 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 37846 [08:40:38.581](0.107s) ok 5 - restart succeeds with correct SSL protocol bounds [08:40:38.582](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 37907 [08:40:38.794](0.213s) ok 6 - server doesn't accept non-SSL connections [08:40:38.794](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [08:40:38.812](0.017s) ok 8 - connect without server root cert sslmode=require [08:40:38.812](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [08:40:38.821](0.010s) ok 10 - connect without server root cert sslmode=verify-ca [08:40:38.822](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [08:40:38.832](0.010s) ok 12 - connect without server root cert sslmode=verify-full [08:40:38.832](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [08:40:38.845](0.013s) ok 14 - connect with wrong server root cert sslmode=require [08:40:38.845](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [08:40:38.857](0.012s) ok 16 - connect with wrong server root cert sslmode=verify-ca [08:40:38.857](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [08:40:38.869](0.012s) ok 18 - connect with wrong server root cert sslmode=verify-full [08:40:38.870](0.001s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [08:40:38.883](0.013s) ok 20 - connect with server CA cert, without root CA [08:40:38.884](0.001s) ok 21 - connect with server CA cert, without root CA: matches [08:40:38.913](0.030s) ok 22 - connect with correct server CA cert file sslmode=require [08:40:38.914](0.001s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [08:40:38.928](0.014s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [08:40:38.928](0.001s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [08:40:38.945](0.016s) ok 26 - connect with correct server CA cert file sslmode=verify-full [08:40:38.945](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [08:40:38.960](0.015s) ok 28 - cert root file that contains two certificates, order 1 [08:40:38.960](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [08:40:38.976](0.016s) ok 30 - cert root file that contains two certificates, order 2 [08:40:38.977](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [08:40:38.991](0.015s) ok 32 - connect with sslcertmode=disable [08:40:38.992](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [08:40:39.006](0.015s) ok 34 - connect with sslcertmode=allow [08:40:39.007](0.001s) ok 35 - connect with sslcertmode=allow: no stderr [08:40:39.021](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [08:40:39.022](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [08:40:39.036](0.015s) ok 38 - sslcrl option with invalid file name [08:40:39.037](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [08:40:39.050](0.013s) ok 40 - CRL belonging to a different CA [08:40:39.050](0.000s) ok 41 - CRL belonging to a different CA: matches [08:40:39.063](0.013s) ok 42 - directory CRL belonging to a different CA [08:40:39.063](0.000s) ok 43 - directory CRL belonging to a different CA: matches [08:40:39.078](0.015s) ok 44 - CRL with a non-revoked cert [08:40:39.079](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [08:40:39.093](0.014s) ok 46 - directory CRL with a non-revoked cert [08:40:39.093](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [08:40:39.108](0.015s) ok 48 - mismatch between host name and server certificate sslmode=require [08:40:39.108](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [08:40:39.122](0.014s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [08:40:39.122](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [08:40:39.135](0.013s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [08:40:39.136](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 38017 [08:40:39.364](0.228s) ok 54 - IP address in the Common Name [08:40:39.365](0.001s) ok 55 - IP address in the Common Name: no stderr [08:40:39.378](0.013s) ok 56 - mismatch between host name and server certificate IP address [08:40:39.378](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 38047 [08:40:39.607](0.229s) ok 58 - IP address in a dNSName [08:40:39.608](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 38076 [08:40:39.834](0.227s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [08:40:39.834](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [08:40:39.847](0.012s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [08:40:39.847](0.001s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [08:40:39.861](0.014s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [08:40:39.861](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [08:40:39.875](0.013s) ok 66 - host name not matching with X.509 Subject Alternative Names [08:40:39.875](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [08:40:39.888](0.013s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [08:40:39.888](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 38115 [08:40:40.111](0.223s) ok 70 - host name matching with a single X.509 Subject Alternative Name [08:40:40.112](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [08:40:40.124](0.012s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [08:40:40.124](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [08:40:40.137](0.013s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [08:40:40.137](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 38146 [08:40:40.369](0.231s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [08:40:40.369](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [08:40:40.382](0.013s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [08:40:40.382](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [08:40:40.394](0.012s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [08:40:40.394](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [08:40:40.408](0.013s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [08:40:40.408](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [08:40:40.423](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [08:40:40.424](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [08:40:40.439](0.015s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [08:40:40.439](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [08:40:40.452](0.013s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [08:40:40.452](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [08:40:40.465](0.013s) ok 90 - IPv6 host with CIDR mask does not match [08:40:40.465](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 38187 [08:40:40.696](0.231s) ok 92 - certificate with both a CN and SANs 1 [08:40:40.696](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [08:40:40.711](0.015s) ok 94 - certificate with both a CN and SANs 2 [08:40:40.711](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [08:40:40.724](0.013s) ok 96 - certificate with both a CN and SANs ignores CN [08:40:40.724](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 38216 [08:40:40.961](0.236s) ok 98 - certificate with both a CN and IP SANs matches CN [08:40:40.961](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [08:40:40.977](0.016s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [08:40:40.977](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [08:40:40.994](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [08:40:40.994](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 38242 [08:40:41.228](0.233s) ok 104 - certificate with both an IP CN and IP SANs 1 [08:40:41.228](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [08:40:41.244](0.016s) ok 106 - certificate with both an IP CN and IP SANs 2 [08:40:41.244](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [08:40:41.258](0.014s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [08:40:41.258](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 38274 [08:40:41.489](0.231s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [08:40:41.489](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [08:40:41.505](0.016s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [08:40:41.506](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [08:40:41.524](0.018s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [08:40:41.524](0.001s) 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 38291 [08:40:41.760](0.236s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [08:40:41.760](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [08:40:41.773](0.013s) ok 118 - server certificate without CN or SANs sslmode=verify-full [08:40:41.773](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 38303 [08:40:42.001](0.228s) ok 120 - sslrootcert=system does not connect with private CA [08:40:42.001](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [08:40:42.006](0.005s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [08:40:42.007](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [08:40:42.022](0.015s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [08:40:42.022](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [08:40:42.032](0.010s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [08:40:42.033](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 38318 [08:40:42.264](0.232s) ok 128 - connects without client-side CRL [08:40:42.265](0.000s) ok 129 - connects without client-side CRL: no stderr [08:40:42.276](0.011s) ok 130 - does not connect with client-side CRL file [08:40:42.276](0.000s) ok 131 - does not connect with client-side CRL file: matches [08:40:42.286](0.010s) ok 132 - does not connect with client-side CRL directory [08:40:42.286](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() [08:40:42.298](0.012s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [08:40:42.298](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [08:40:42.298](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [08:40:42.311](0.012s) ok 137 - connection success with correct range of TLS protocol versions [08:40:42.311](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [08:40:42.317](0.006s) ok 139 - connection failure with incorrect range of TLS protocol versions [08:40:42.317](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [08:40:42.323](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [08:40:42.323](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [08:40:42.329](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [08:40:42.329](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [08:40:42.329](0.000s) # running server tests [08:40:42.342](0.012s) ok 145 - certificate authorization fails without client cert [08:40:42.342](0.000s) ok 146 - certificate authorization fails without client cert: matches [08:40:42.362](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [08:40:42.362](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [08:40:42.380](0.017s) ok 149 - certificate authorization succeeds with correct client cert in DER format [08:40:42.380](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [08:40:42.398](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [08:40:42.398](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [08:40:42.415](0.017s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [08:40:42.416](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [08:40:42.431](0.015s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [08:40:42.431](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [08:40:42.447](0.016s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [08:40:42.447](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [08:40:42.461](0.014s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [08:40:42.461](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [08:40:42.473](0.011s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [08:40:42.473](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [08:40:42.494](0.021s) ok 163 - certificate authorization succeeds with DN mapping [08:40:42.494](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [08:40:42.495](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [08:40:42.516](0.021s) ok 166 - certificate authorization succeeds with DN regex mapping [08:40:42.516](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [08:40:42.537](0.021s) ok 168 - certificate authorization succeeds with CN mapping [08:40:42.538](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [08:40:42.538](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [08:40:42.538](0.000s) not ok 171 # TODO & SKIP Need Pty support [08:40:42.538](0.000s) not ok 172 # TODO & SKIP Need Pty support [08:40:42.538](0.000s) not ok 173 # TODO & SKIP Need Pty support [08:40:42.539](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_Oe5T/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [08:40:42.575](0.036s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [08:40:42.575](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [08:40:42.576](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [08:40:42.585](0.009s) ok 178 - certificate authorization fails because of file permissions [08:40:42.585](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [08:40:42.599](0.013s) ok 180 - certificate authorization fails with client cert belonging to another user [08:40:42.599](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [08:40:42.612](0.013s) ok 182 - certificate authorization fails with revoked client cert [08:40:42.612](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [08:40:42.613](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [08:40:42.631](0.018s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [08:40:42.631](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [08:40:42.631](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [08:40:42.645](0.013s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [08:40:42.645](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [08:40:42.645](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [08:40:42.660](0.014s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [08:40:42.660](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [08:40:42.660](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 38378 [08:40:42.895](0.235s) ok 194 - intermediate client certificate is provided by client [08:40:42.895](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [08:40:42.908](0.013s) ok 196 - intermediate client certificate is missing [08:40:42.908](0.000s) ok 197 - intermediate client certificate is missing: matches [08:40:42.921](0.012s) ok 198 - logged client certificate Subjects are truncated if they're too long [08:40:42.921](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 38392 [08:40:43.149](0.228s) ok 200 - intermediate client certificate is untrusted [08:40:43.149](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 38402 [08:40:43.376](0.227s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [08:40:43.376](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [08:40:43.387](0.011s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [08:40:43.387](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 38414 [08:40:43.612](0.225s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [08:40:43.612](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. [08:40:43.612](0.000s) # got: '2' # expected: '0' [08:40:43.612](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [08:40:43.613](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. [08:40:43.613](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 61771 failed: SSL SYSCALL error: EOF detected' # expected: '' [08:40:43.628](0.015s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [08:40:43.628](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 38426 [08:40:43.846](0.218s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [08:40:43.862](0.015s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [08:40:43.862](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 38438 [08:40:44.085](0.223s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [08:40:44.100](0.015s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [08:40:44.100](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 38450 [08:40:44.326](0.226s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [08:40:44.343](0.018s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [08:40:44.343](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 38462 [08:40:44.570](0.226s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [08:40:44.570](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. [08:40:44.570](0.000s) # got: '2' # expected: '0' [08:40:44.570](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [08:40:44.570](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. [08:40:44.570](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 61771 failed: SSL SYSCALL error: EOF detected' # expected: '' [08:40:44.586](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [08:40:44.586](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 38474 [08:40:44.812](0.225s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [08:40:44.827](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [08:40:44.828](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 38486 [08:40:45.053](0.225s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [08:40:45.071](0.018s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [08:40:45.071](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 38498 [08:40:45.298](0.226s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [08:40:45.316](0.018s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [08:40:45.316](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 38510 [08:40:45.538](0.222s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [08:40:45.555](0.016s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [08:40:45.555](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-26 08:40:36.874 UTC [37470][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:36.874 UTC [37470][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:36.876 UTC [37477][startup] LOG: database system was shut down at 2024-03-26 08:38:12 UTC 2024-03-26 08:40:36.878 UTC [37470][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:36.974 UTC [37502][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:36.974 UTC [37502][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-26 08:40:36.974 UTC [37502][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:36.978 UTC [37502][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-26 08:40:36.979 UTC [37502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-26 08:40:36.986 UTC [37506][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:36.986 UTC [37506][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-26 08:40:36.986 UTC [37506][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:36.987 UTC [37506][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-26 08:40:36.987 UTC [37506][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:36.988 UTC [37506][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.033 UTC [37520][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.034 UTC [37520][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-26 08:40:37.034 UTC [37520][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.036 UTC [37520][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-26 08:40:37.036 UTC [37520][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.037 UTC [37520][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.078 UTC [37534][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.079 UTC [37534][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-26 08:40:37.079 UTC [37534][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.079 UTC [37534][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-26 08:40:37.079 UTC [37534][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.080 UTC [37534][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.134 UTC [37553][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.134 UTC [37553][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-26 08:40:37.134 UTC [37553][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.135 UTC [37553][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-26 08:40:37.135 UTC [37553][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-26 08:40:37.136 UTC [37553][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.175 UTC [37563][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.175 UTC [37563][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-26 08:40:37.175 UTC [37563][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.176 UTC [37563][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-26 08:40:37.176 UTC [37563][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.196 UTC [37563][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.241 UTC [37583][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.246 UTC [37583][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-26 08:40:37.246 UTC [37583][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.248 UTC [37583][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-26 08:40:37.248 UTC [37583][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.297 UTC [37583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.382 UTC [37607][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.382 UTC [37607][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-26 08:40:37.382 UTC [37607][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.383 UTC [37607][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-26 08:40:37.383 UTC [37607][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.412 UTC [37607][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.421 UTC [37613][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.422 UTC [37613][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-26 08:40:37.422 UTC [37613][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.422 UTC [37613][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-26 08:40:37.422 UTC [37613][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.522 UTC [37613][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.101 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.562 UTC [37644][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.562 UTC [37644][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-26 08:40:37.562 UTC [37644][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.565 UTC [37644][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-26 08:40:37.566 UTC [37644][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.597 UTC [37644][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.609 UTC [37660][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:40:37.609 UTC [37660][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-26 08:40:37.609 UTC [37660][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-26 08:40:37.610 UTC [37660][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-26 08:40:37.610 UTC [37660][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-26 08:40:37.653 UTC [37660][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local] 2024-03-26 08:40:37.739 UTC [37470][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:37.739 UTC [37470][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:37.742 UTC [37470][postmaster] LOG: background worker "logical replication launcher" (PID 37481) exited with exit code 1 2024-03-26 08:40:37.742 UTC [37475][checkpointer] LOG: shutting down 2024-03-26 08:40:37.742 UTC [37475][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:37.782 UTC [37475][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.040 s, sync=0.001 s, total=0.040 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102190, redo lsn=0/4102190 2024-03-26 08:40:37.786 UTC [37470][postmaster] LOG: database system is shut down 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:37.851 UTC [37715][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:37.853 UTC [37719][startup] LOG: database system was shut down at 2024-03-26 08:40:37 UTC 2024-03-26 08:40:37.856 UTC [37715][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:37.951 UTC [37715][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:37.951 UTC [37715][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:37.955 UTC [37715][postmaster] LOG: background worker "logical replication launcher" (PID 37722) exited with exit code 1 2024-03-26 08:40:37.955 UTC [37717][checkpointer] LOG: shutting down 2024-03-26 08:40:37.955 UTC [37717][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:37.956 UTC [37717][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/4102208, redo lsn=0/4102208 2024-03-26 08:40:37.957 UTC [37715][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.064 UTC [37762][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-26 08:40:38.064 UTC [37762][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.171 UTC [37781][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:38.171 UTC [37781][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:38.171 UTC [37781][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:38.173 UTC [37787][startup] LOG: database system was shut down at 2024-03-26 08:40:37 UTC 2024-03-26 08:40:38.176 UTC [37781][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:38.263 UTC [37781][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:38.263 UTC [37781][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:38.264 UTC [37781][postmaster] LOG: background worker "logical replication launcher" (PID 37790) exited with exit code 1 2024-03-26 08:40:38.264 UTC [37785][checkpointer] LOG: shutting down 2024-03-26 08:40:38.264 UTC [37785][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:38.265 UTC [37785][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/4102280, redo lsn=0/4102280 2024-03-26 08:40:38.266 UTC [37781][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.376 UTC [37813][postmaster] FATAL: could not set SSL protocol version range 2024-03-26 08:40:38.376 UTC [37813][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-26 08:40:38.376 UTC [37813][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.495 UTC [37846][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:38.495 UTC [37846][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:38.495 UTC [37846][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:38.500 UTC [37855][startup] LOG: database system was shut down at 2024-03-26 08:40:38 UTC 2024-03-26 08:40:38.502 UTC [37846][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:38.584 UTC [37846][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:38.584 UTC [37846][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:38.586 UTC [37846][postmaster] LOG: background worker "logical replication launcher" (PID 37858) exited with exit code 1 2024-03-26 08:40:38.586 UTC [37852][checkpointer] LOG: shutting down 2024-03-26 08:40:38.586 UTC [37852][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:38.586 UTC [37852][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/41022F8, redo lsn=0/41022F8 2024-03-26 08:40:38.588 UTC [37846][postmaster] LOG: database system is shut down 2024-03-26 08:40:38.696 UTC [37907][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:38.697 UTC [37907][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:38.697 UTC [37907][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:38.699 UTC [37912][startup] LOG: database system was shut down at 2024-03-26 08:40:38 UTC 2024-03-26 08:40:38.701 UTC [37907][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:38.792 UTC [37938][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35013 2024-03-26 08:40:38.793 UTC [37938][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-26 08:40:38.793 UTC [37938][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-26 08:40:38.801 UTC [37940][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35014 2024-03-26 08:40:38.805 UTC [37940][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-26 08:40:38.805 UTC [37940][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-26 08:40:38.810 UTC [37940][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-26 08:40:38.810 UTC [37940][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35014 2024-03-26 08:40:38.818 UTC [37943][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35015 2024-03-26 08:40:38.820 UTC [37943][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:40:38.829 UTC [37946][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35016 2024-03-26 08:40:38.831 UTC [37946][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:40:38.839 UTC [37948][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35017 2024-03-26 08:40:38.843 UTC [37948][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:38.851 UTC [37953][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35019 2024-03-26 08:40:38.855 UTC [37953][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:38.863 UTC [37957][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35021 2024-03-26 08:40:38.867 UTC [37957][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:38.877 UTC [37960][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35023 2024-03-26 08:40:38.882 UTC [37960][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:38.892 UTC [37964][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35025 2024-03-26 08:40:38.910 UTC [37964][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-26 08:40:38.910 UTC [37964][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-26 08:40:38.911 UTC [37964][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-26 08:40:38.916 UTC [37964][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=35025 2024-03-26 08:40:38.920 UTC [37969][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35026 2024-03-26 08:40:38.925 UTC [37969][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-26 08:40:38.925 UTC [37969][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-26 08:40:38.926 UTC [37969][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-26 08:40:38.926 UTC [37969][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35026 2024-03-26 08:40:38.936 UTC [37971][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35027 2024-03-26 08:40:38.942 UTC [37971][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-26 08:40:38.942 UTC [37971][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-26 08:40:38.942 UTC [37971][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-26 08:40:38.943 UTC [37971][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35027 2024-03-26 08:40:38.951 UTC [37973][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35028 2024-03-26 08:40:38.957 UTC [37973][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-26 08:40:38.957 UTC [37973][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-26 08:40:38.958 UTC [37973][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-26 08:40:38.958 UTC [37973][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35028 2024-03-26 08:40:38.968 UTC [37975][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35029 2024-03-26 08:40:38.973 UTC [37975][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-26 08:40:38.973 UTC [37975][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-26 08:40:38.974 UTC [37975][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-26 08:40:38.975 UTC [37975][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35029 2024-03-26 08:40:38.983 UTC [37979][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35030 2024-03-26 08:40:38.989 UTC [37979][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-26 08:40:38.989 UTC [37979][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-26 08:40:38.989 UTC [37979][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-26 08:40:38.990 UTC [37979][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35030 2024-03-26 08:40:38.998 UTC [37981][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35031 2024-03-26 08:40:39.003 UTC [37981][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-26 08:40:39.003 UTC [37981][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-26 08:40:39.004 UTC [37981][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-26 08:40:39.005 UTC [37981][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35031 2024-03-26 08:40:39.014 UTC [37984][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35032 2024-03-26 08:40:39.019 UTC [37984][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-26 08:40:39.019 UTC [37984][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-26 08:40:39.020 UTC [37984][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35032 2024-03-26 08:40:39.029 UTC [37992][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35033 2024-03-26 08:40:39.034 UTC [37992][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-26 08:40:39.034 UTC [37992][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-26 08:40:39.034 UTC [37992][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-26 08:40:39.035 UTC [37992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35033 2024-03-26 08:40:39.044 UTC [37994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35034 2024-03-26 08:40:39.048 UTC [37994][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:39.057 UTC [37996][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35035 2024-03-26 08:40:39.061 UTC [37996][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:39.070 UTC [37998][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35036 2024-03-26 08:40:39.075 UTC [37998][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-26 08:40:39.075 UTC [37998][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-26 08:40:39.076 UTC [37998][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-26 08:40:39.077 UTC [37998][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35036 2024-03-26 08:40:39.086 UTC [38000][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35037 2024-03-26 08:40:39.090 UTC [38000][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-26 08:40:39.090 UTC [38000][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-26 08:40:39.091 UTC [38000][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-26 08:40:39.091 UTC [38000][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35037 2024-03-26 08:40:39.100 UTC [38002][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35038 2024-03-26 08:40:39.105 UTC [38002][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-26 08:40:39.105 UTC [38002][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-26 08:40:39.105 UTC [38002][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-26 08:40:39.106 UTC [38002][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35038 2024-03-26 08:40:39.114 UTC [38006][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35039 2024-03-26 08:40:39.119 UTC [38006][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-26 08:40:39.119 UTC [38006][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-26 08:40:39.119 UTC [38006][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-26 08:40:39.120 UTC [38006][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35039 2024-03-26 08:40:39.129 UTC [38011][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35042 2024-03-26 08:40:39.138 UTC [37907][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:39.138 UTC [37907][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:39.140 UTC [37907][postmaster] LOG: background worker "logical replication launcher" (PID 37915) exited with exit code 1 2024-03-26 08:40:39.141 UTC [37910][checkpointer] LOG: shutting down 2024-03-26 08:40:39.141 UTC [37910][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:39.141 UTC [37910][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/4102370, redo lsn=0/4102370 2024-03-26 08:40:39.143 UTC [37907][postmaster] LOG: database system is shut down 2024-03-26 08:40:39.257 UTC [38017][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:39.257 UTC [38017][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:39.257 UTC [38017][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:39.260 UTC [38026][startup] LOG: database system was shut down at 2024-03-26 08:40:39 UTC 2024-03-26 08:40:39.263 UTC [38017][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:39.352 UTC [38035][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35044 2024-03-26 08:40:39.357 UTC [38035][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-26 08:40:39.357 UTC [38035][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-26 08:40:39.361 UTC [38035][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-26 08:40:39.362 UTC [38035][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35044 2024-03-26 08:40:39.372 UTC [38042][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35047 2024-03-26 08:40:39.381 UTC [38017][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:39.381 UTC [38017][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:39.384 UTC [38017][postmaster] LOG: background worker "logical replication launcher" (PID 38030) exited with exit code 1 2024-03-26 08:40:39.384 UTC [38022][checkpointer] LOG: shutting down 2024-03-26 08:40:39.384 UTC [38022][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:39.384 UTC [38022][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/41023E8, redo lsn=0/41023E8 2024-03-26 08:40:39.386 UTC [38017][postmaster] LOG: database system is shut down 2024-03-26 08:40:39.495 UTC [38047][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:39.495 UTC [38047][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:39.495 UTC [38047][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:39.497 UTC [38054][startup] LOG: database system was shut down at 2024-03-26 08:40:39 UTC 2024-03-26 08:40:39.501 UTC [38047][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:39.596 UTC [38063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35049 2024-03-26 08:40:39.600 UTC [38063][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-26 08:40:39.601 UTC [38063][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-26 08:40:39.605 UTC [38063][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-26 08:40:39.606 UTC [38063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35049 2024-03-26 08:40:39.610 UTC [38047][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:39.610 UTC [38047][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:39.611 UTC [38047][postmaster] LOG: background worker "logical replication launcher" (PID 38060) exited with exit code 1 2024-03-26 08:40:39.612 UTC [38052][checkpointer] LOG: shutting down 2024-03-26 08:40:39.612 UTC [38052][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:39.612 UTC [38052][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/4102460, redo lsn=0/4102460 2024-03-26 08:40:39.614 UTC [38047][postmaster] LOG: database system is shut down 2024-03-26 08:40:39.722 UTC [38076][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:39.722 UTC [38076][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:39.722 UTC [38076][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:39.724 UTC [38079][startup] LOG: database system was shut down at 2024-03-26 08:40:39 UTC 2024-03-26 08:40:39.727 UTC [38076][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:39.823 UTC [38091][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35053 2024-03-26 08:40:39.828 UTC [38091][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-26 08:40:39.828 UTC [38091][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-26 08:40:39.832 UTC [38091][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-26 08:40:39.833 UTC [38091][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35053 2024-03-26 08:40:39.840 UTC [38097][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35054 2024-03-26 08:40:39.844 UTC [38097][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-26 08:40:39.844 UTC [38097][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-26 08:40:39.845 UTC [38097][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-26 08:40:39.845 UTC [38097][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=35054 2024-03-26 08:40:39.853 UTC [38100][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35056 2024-03-26 08:40:39.858 UTC [38100][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-26 08:40:39.858 UTC [38100][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-26 08:40:39.859 UTC [38100][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-26 08:40:39.859 UTC [38100][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35056 2024-03-26 08:40:39.869 UTC [38104][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35058 2024-03-26 08:40:39.882 UTC [38106][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35059 2024-03-26 08:40:39.890 UTC [38076][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:39.890 UTC [38076][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:39.892 UTC [38076][postmaster] LOG: background worker "logical replication launcher" (PID 38082) exited with exit code 1 2024-03-26 08:40:39.892 UTC [38077][checkpointer] LOG: shutting down 2024-03-26 08:40:39.892 UTC [38077][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:39.893 UTC [38077][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/41024D8, redo lsn=0/41024D8 2024-03-26 08:40:39.894 UTC [38076][postmaster] LOG: database system is shut down 2024-03-26 08:40:40.005 UTC [38115][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:40.005 UTC [38115][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:40.005 UTC [38115][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:40.006 UTC [38120][startup] LOG: database system was shut down at 2024-03-26 08:40:39 UTC 2024-03-26 08:40:40.009 UTC [38115][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:40.100 UTC [38129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35062 2024-03-26 08:40:40.105 UTC [38129][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-26 08:40:40.105 UTC [38129][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-26 08:40:40.109 UTC [38129][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-26 08:40:40.110 UTC [38129][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35062 2024-03-26 08:40:40.118 UTC [38132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35063 2024-03-26 08:40:40.131 UTC [38134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35064 2024-03-26 08:40:40.144 UTC [38115][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:40.144 UTC [38115][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:40.145 UTC [38115][postmaster] LOG: background worker "logical replication launcher" (PID 38123) exited with exit code 1 2024-03-26 08:40:40.145 UTC [38118][checkpointer] LOG: shutting down 2024-03-26 08:40:40.145 UTC [38118][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:40.146 UTC [38118][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/4102550, redo lsn=0/4102550 2024-03-26 08:40:40.148 UTC [38115][postmaster] LOG: database system is shut down 2024-03-26 08:40:40.262 UTC [38146][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:40.262 UTC [38146][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:40.262 UTC [38146][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:40.264 UTC [38149][startup] LOG: database system was shut down at 2024-03-26 08:40:40 UTC 2024-03-26 08:40:40.268 UTC [38146][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:40.357 UTC [38159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35066 2024-03-26 08:40:40.362 UTC [38159][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-26 08:40:40.362 UTC [38159][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-26 08:40:40.367 UTC [38159][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-26 08:40:40.367 UTC [38159][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35066 2024-03-26 08:40:40.375 UTC [38161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35067 2024-03-26 08:40:40.379 UTC [38161][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-26 08:40:40.379 UTC [38161][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-26 08:40:40.380 UTC [38161][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-26 08:40:40.380 UTC [38161][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35067 2024-03-26 08:40:40.388 UTC [38163][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35068 2024-03-26 08:40:40.400 UTC [38165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35069 2024-03-26 08:40:40.405 UTC [38165][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-26 08:40:40.405 UTC [38165][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-26 08:40:40.406 UTC [38165][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-26 08:40:40.406 UTC [38165][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35069 2024-03-26 08:40:40.415 UTC [38167][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35070 2024-03-26 08:40:40.420 UTC [38167][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-26 08:40:40.420 UTC [38167][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-26 08:40:40.421 UTC [38167][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-26 08:40:40.422 UTC [38167][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35070 2024-03-26 08:40:40.430 UTC [38169][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35071 2024-03-26 08:40:40.436 UTC [38169][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-26 08:40:40.436 UTC [38169][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-26 08:40:40.437 UTC [38169][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-26 08:40:40.437 UTC [38169][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35071 2024-03-26 08:40:40.446 UTC [38172][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35072 2024-03-26 08:40:40.458 UTC [38180][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35073 2024-03-26 08:40:40.468 UTC [38146][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:40.468 UTC [38146][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:40.469 UTC [38146][postmaster] LOG: background worker "logical replication launcher" (PID 38152) exited with exit code 1 2024-03-26 08:40:40.469 UTC [38147][checkpointer] LOG: shutting down 2024-03-26 08:40:40.469 UTC [38147][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:40.470 UTC [38147][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/41025C8, redo lsn=0/41025C8 2024-03-26 08:40:40.472 UTC [38146][postmaster] LOG: database system is shut down 2024-03-26 08:40:40.587 UTC [38187][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:40.587 UTC [38187][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:40.587 UTC [38187][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:40.589 UTC [38190][startup] LOG: database system was shut down at 2024-03-26 08:40:40 UTC 2024-03-26 08:40:40.592 UTC [38187][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:40.684 UTC [38204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35075 2024-03-26 08:40:40.689 UTC [38204][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-26 08:40:40.689 UTC [38204][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-26 08:40:40.694 UTC [38204][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-26 08:40:40.694 UTC [38204][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35075 2024-03-26 08:40:40.703 UTC [38206][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35076 2024-03-26 08:40:40.708 UTC [38206][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-26 08:40:40.708 UTC [38206][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-26 08:40:40.709 UTC [38206][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-26 08:40:40.709 UTC [38206][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35076 2024-03-26 08:40:40.718 UTC [38208][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35077 2024-03-26 08:40:40.731 UTC [38187][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:40.731 UTC [38187][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:40.733 UTC [38187][postmaster] LOG: background worker "logical replication launcher" (PID 38193) exited with exit code 1 2024-03-26 08:40:40.733 UTC [38188][checkpointer] LOG: shutting down 2024-03-26 08:40:40.733 UTC [38188][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:40.733 UTC [38188][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/4102640, redo lsn=0/4102640 2024-03-26 08:40:40.735 UTC [38187][postmaster] LOG: database system is shut down 2024-03-26 08:40:40.847 UTC [38216][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:40.847 UTC [38216][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:40.847 UTC [38216][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:40.849 UTC [38219][startup] LOG: database system was shut down at 2024-03-26 08:40:40 UTC 2024-03-26 08:40:40.853 UTC [38216][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:40.949 UTC [38231][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35079 2024-03-26 08:40:40.954 UTC [38231][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-26 08:40:40.954 UTC [38231][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-26 08:40:40.958 UTC [38231][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-26 08:40:40.959 UTC [38231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35079 2024-03-26 08:40:40.968 UTC [38233][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35080 2024-03-26 08:40:40.974 UTC [38233][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-26 08:40:40.974 UTC [38233][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-26 08:40:40.975 UTC [38233][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-26 08:40:40.975 UTC [38233][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35080 2024-03-26 08:40:40.985 UTC [38235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35081 2024-03-26 08:40:40.991 UTC [38235][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-26 08:40:40.991 UTC [38235][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-26 08:40:40.991 UTC [38235][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-26 08:40:40.992 UTC [38235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35081 2024-03-26 08:40:40.997 UTC [38216][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:40.997 UTC [38216][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:40.999 UTC [38216][postmaster] LOG: background worker "logical replication launcher" (PID 38222) exited with exit code 1 2024-03-26 08:40:40.999 UTC [38217][checkpointer] LOG: shutting down 2024-03-26 08:40:40.999 UTC [38217][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:40.999 UTC [38217][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/41026B8, redo lsn=0/41026B8 2024-03-26 08:40:41.001 UTC [38216][postmaster] LOG: database system is shut down 2024-03-26 08:40:41.116 UTC [38242][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:41.116 UTC [38242][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:41.116 UTC [38242][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:41.118 UTC [38246][startup] LOG: database system was shut down at 2024-03-26 08:40:40 UTC 2024-03-26 08:40:41.122 UTC [38242][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:41.216 UTC [38258][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35083 2024-03-26 08:40:41.221 UTC [38258][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-26 08:40:41.221 UTC [38258][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-26 08:40:41.225 UTC [38258][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-26 08:40:41.226 UTC [38258][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35083 2024-03-26 08:40:41.234 UTC [38262][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35085 2024-03-26 08:40:41.241 UTC [38262][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-26 08:40:41.241 UTC [38262][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-26 08:40:41.242 UTC [38262][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-26 08:40:41.243 UTC [38262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=35085 2024-03-26 08:40:41.251 UTC [38265][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35086 2024-03-26 08:40:41.260 UTC [38242][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:41.261 UTC [38242][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:41.262 UTC [38242][postmaster] LOG: background worker "logical replication launcher" (PID 38249) exited with exit code 1 2024-03-26 08:40:41.263 UTC [38243][checkpointer] LOG: shutting down 2024-03-26 08:40:41.263 UTC [38243][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:41.263 UTC [38243][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/4102730, redo lsn=0/4102730 2024-03-26 08:40:41.265 UTC [38242][postmaster] LOG: database system is shut down 2024-03-26 08:40:41.379 UTC [38274][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:41.379 UTC [38274][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:41.379 UTC [38274][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:41.381 UTC [38277][startup] LOG: database system was shut down at 2024-03-26 08:40:41 UTC 2024-03-26 08:40:41.384 UTC [38274][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:41.476 UTC [38285][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35088 2024-03-26 08:40:41.482 UTC [38285][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-26 08:40:41.482 UTC [38285][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-26 08:40:41.486 UTC [38285][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-26 08:40:41.487 UTC [38285][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35088 2024-03-26 08:40:41.497 UTC [38287][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35089 2024-03-26 08:40:41.502 UTC [38287][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-26 08:40:41.502 UTC [38287][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-26 08:40:41.503 UTC [38287][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-26 08:40:41.503 UTC [38287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35089 2024-03-26 08:40:41.515 UTC [38289][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35090 2024-03-26 08:40:41.520 UTC [38289][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-26 08:40:41.520 UTC [38289][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-26 08:40:41.521 UTC [38289][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-26 08:40:41.522 UTC [38289][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=35090 2024-03-26 08:40:41.527 UTC [38274][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:41.527 UTC [38274][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:41.529 UTC [38274][postmaster] LOG: background worker "logical replication launcher" (PID 38280) exited with exit code 1 2024-03-26 08:40:41.529 UTC [38275][checkpointer] LOG: shutting down 2024-03-26 08:40:41.529 UTC [38275][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:41.530 UTC [38275][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/41027A8, redo lsn=0/41027A8 2024-03-26 08:40:41.532 UTC [38274][postmaster] LOG: database system is shut down 2024-03-26 08:40:41.646 UTC [38291][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:41.646 UTC [38291][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:41.647 UTC [38291][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:41.648 UTC [38294][startup] LOG: database system was shut down at 2024-03-26 08:40:41 UTC 2024-03-26 08:40:41.652 UTC [38291][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:41.748 UTC [38299][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35091 2024-03-26 08:40:41.753 UTC [38299][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-26 08:40:41.753 UTC [38299][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-26 08:40:41.758 UTC [38299][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-26 08:40:41.758 UTC [38299][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35091 2024-03-26 08:40:41.767 UTC [38301][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35092 2024-03-26 08:40:41.775 UTC [38291][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:41.775 UTC [38291][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:41.777 UTC [38291][postmaster] LOG: background worker "logical replication launcher" (PID 38297) exited with exit code 1 2024-03-26 08:40:41.777 UTC [38292][checkpointer] LOG: shutting down 2024-03-26 08:40:41.777 UTC [38292][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:41.777 UTC [38292][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/4102820, redo lsn=0/4102820 2024-03-26 08:40:41.778 UTC [38291][postmaster] LOG: database system is shut down 2024-03-26 08:40:41.893 UTC [38303][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:41.893 UTC [38303][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:41.893 UTC [38303][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:41.895 UTC [38306][startup] LOG: database system was shut down at 2024-03-26 08:40:41 UTC 2024-03-26 08:40:41.898 UTC [38303][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:41.995 UTC [38311][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35093 2024-03-26 08:40:41.999 UTC [38311][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:40:42.011 UTC [38314][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35094 2024-03-26 08:40:42.016 UTC [38314][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-26 08:40:42.016 UTC [38314][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-26 08:40:42.020 UTC [38314][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-26 08:40:42.021 UTC [38314][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35094 2024-03-26 08:40:42.027 UTC [38316][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35095 2024-03-26 08:40:42.035 UTC [38303][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:42.035 UTC [38303][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:42.036 UTC [38303][postmaster] LOG: background worker "logical replication launcher" (PID 38309) exited with exit code 1 2024-03-26 08:40:42.037 UTC [38304][checkpointer] LOG: shutting down 2024-03-26 08:40:42.037 UTC [38304][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:42.037 UTC [38304][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/4102898, redo lsn=0/4102898 2024-03-26 08:40:42.039 UTC [38303][postmaster] LOG: database system is shut down 2024-03-26 08:40:42.153 UTC [38318][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:42.153 UTC [38318][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:42.154 UTC [38318][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:42.155 UTC [38321][startup] LOG: database system was shut down at 2024-03-26 08:40:42 UTC 2024-03-26 08:40:42.158 UTC [38318][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:42.254 UTC [38326][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35096 2024-03-26 08:40:42.258 UTC [38326][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-26 08:40:42.258 UTC [38326][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-26 08:40:42.262 UTC [38326][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-26 08:40:42.263 UTC [38326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35096 2024-03-26 08:40:42.270 UTC [38328][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35097 2024-03-26 08:40:42.274 UTC [38328][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-26 08:40:42.281 UTC [38330][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35098 2024-03-26 08:40:42.285 UTC [38330][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-26 08:40:42.291 UTC [38332][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35099 2024-03-26 08:40:42.294 UTC [38332][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-26 08:40:42.294 UTC [38332][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-26 08:40:42.295 UTC [38332][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-26 08:40:42.297 UTC [38332][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35099 2024-03-26 08:40:42.304 UTC [38334][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35100 2024-03-26 08:40:42.308 UTC [38334][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-26 08:40:42.308 UTC [38334][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-26 08:40:42.309 UTC [38334][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-26 08:40:42.309 UTC [38334][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=35100 2024-03-26 08:40:42.335 UTC [38339][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35101 2024-03-26 08:40:42.340 UTC [38339][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-26 08:40:42.348 UTC [38341][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35102 2024-03-26 08:40:42.355 UTC [38341][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-26 08:40:42.355 UTC [38341][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-26 08:40:42.360 UTC [38341][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_Oe5T/client.key$$ 2024-03-26 08:40:42.361 UTC [38341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=35102 2024-03-26 08:40:42.369 UTC [38343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35103 2024-03-26 08:40:42.377 UTC [38343][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-26 08:40:42.377 UTC [38343][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-26 08:40:42.377 UTC [38343][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_Oe5T/client-der.key$$ 2024-03-26 08:40:42.378 UTC [38343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=35103 2024-03-26 08:40:42.387 UTC [38345][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35104 2024-03-26 08:40:42.395 UTC [38345][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-26 08:40:42.395 UTC [38345][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-26 08:40:42.396 UTC [38345][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_Oe5T/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-26 08:40:42.397 UTC [38345][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=35104 2024-03-26 08:40:42.406 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35105 2024-03-26 08:40:42.412 UTC [38347][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-26 08:40:42.412 UTC [38347][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-26 08:40:42.413 UTC [38347][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_Oe5T/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-26 08:40:42.414 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=35105 2024-03-26 08:40:42.421 UTC [38349][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35106 2024-03-26 08:40:42.428 UTC [38349][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-26 08:40:42.428 UTC [38349][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-26 08:40:42.429 UTC [38349][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_Oe5T/client.key$$ 2024-03-26 08:40:42.429 UTC [38349][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=35106 2024-03-26 08:40:42.438 UTC [38351][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35107 2024-03-26 08:40:42.444 UTC [38351][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-26 08:40:42.444 UTC [38351][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-26 08:40:42.445 UTC [38351][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_Oe5T/client.key$$ 2024-03-26 08:40:42.446 UTC [38351][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=35107 2024-03-26 08:40:42.454 UTC [38353][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35108 2024-03-26 08:40:42.459 UTC [38353][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-26 08:40:42.468 UTC [38355][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35109 2024-03-26 08:40:42.471 UTC [38355][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:40:42.480 UTC [38357][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35110 2024-03-26 08:40:42.487 UTC [38357][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-26 08:40:42.487 UTC [38357][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-26 08:40:42.492 UTC [38357][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_Oe5T/client-dn.key$$ 2024-03-26 08:40:42.492 UTC [38357][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=35110 2024-03-26 08:40:42.502 UTC [38359][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35111 2024-03-26 08:40:42.509 UTC [38359][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-26 08:40:42.509 UTC [38359][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-26 08:40:42.513 UTC [38359][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_Oe5T/client-dn.key$$ 2024-03-26 08:40:42.514 UTC [38359][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn_re host=localhost port=35111 2024-03-26 08:40:42.523 UTC [38361][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35112 2024-03-26 08:40:42.530 UTC [38361][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-26 08:40:42.530 UTC [38361][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-26 08:40:42.535 UTC [38361][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_Oe5T/client-dn.key$$ 2024-03-26 08:40:42.535 UTC [38361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_cn host=localhost port=35112 2024-03-26 08:40:42.563 UTC [38364][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35113 2024-03-26 08:40:42.571 UTC [38364][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-26 08:40:42.571 UTC [38364][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-26 08:40:42.572 UTC [38364][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-26 08:40:42.574 UTC [38364][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=35113 2024-03-26 08:40:42.582 UTC [38366][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35114 2024-03-26 08:40:42.584 UTC [38366][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:40:42.591 UTC [38368][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35115 2024-03-26 08:40:42.597 UTC [38368][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-26 08:40:42.597 UTC [38368][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-26 08:40:42.597 UTC [38368][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-26 08:40:42.597 UTC [38368][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-26 08:40:42.605 UTC [38370][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35116 2024-03-26 08:40:42.611 UTC [38370][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:40:42.611 UTC [38370][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-26 08:40:42.618 UTC [38372][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35117 2024-03-26 08:40:42.625 UTC [38372][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-26 08:40:42.625 UTC [38372][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-26 08:40:42.629 UTC [38372][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_Oe5T/client.key$$ 2024-03-26 08:40:42.630 UTC [38372][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=35117 2024-03-26 08:40:42.637 UTC [38374][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35118 2024-03-26 08:40:42.643 UTC [38374][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-26 08:40:42.643 UTC [38374][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-26 08:40:42.643 UTC [38374][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-26 08:40:42.643 UTC [38374][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-26 08:40:42.651 UTC [38376][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35119 2024-03-26 08:40:42.657 UTC [38376][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-26 08:40:42.657 UTC [38376][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-26 08:40:42.658 UTC [38376][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_Oe5T/client.key$$ 2024-03-26 08:40:42.659 UTC [38376][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=35119 2024-03-26 08:40:42.663 UTC [38318][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:42.663 UTC [38318][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:42.664 UTC [38318][postmaster] LOG: background worker "logical replication launcher" (PID 38324) exited with exit code 1 2024-03-26 08:40:42.664 UTC [38319][checkpointer] LOG: shutting down 2024-03-26 08:40:42.664 UTC [38319][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:42.664 UTC [38319][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/4102910, redo lsn=0/4102910 2024-03-26 08:40:42.666 UTC [38318][postmaster] LOG: database system is shut down 2024-03-26 08:40:42.781 UTC [38378][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:42.781 UTC [38378][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:42.781 UTC [38378][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:42.783 UTC [38381][startup] LOG: database system was shut down at 2024-03-26 08:40:42 UTC 2024-03-26 08:40:42.786 UTC [38378][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:42.883 UTC [38386][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35120 2024-03-26 08:40:42.889 UTC [38386][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-26 08:40:42.889 UTC [38386][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-26 08:40:42.893 UTC [38386][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_Oe5T/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-26 08:40:42.894 UTC [38386][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=35120 2024-03-26 08:40:42.901 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35121 2024-03-26 08:40:42.907 UTC [38388][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:40:42.907 UTC [38388][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-26 08:40:42.914 UTC [38390][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35122 2024-03-26 08:40:42.919 UTC [38390][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:40:42.919 UTC [38390][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-26 08:40:42.922 UTC [38378][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:42.922 UTC [38378][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:42.923 UTC [38378][postmaster] LOG: background worker "logical replication launcher" (PID 38384) exited with exit code 1 2024-03-26 08:40:42.924 UTC [38379][checkpointer] LOG: shutting down 2024-03-26 08:40:42.924 UTC [38379][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:42.924 UTC [38379][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/4102988, redo lsn=0/4102988 2024-03-26 08:40:42.926 UTC [38378][postmaster] LOG: database system is shut down 2024-03-26 08:40:43.040 UTC [38392][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:43.040 UTC [38392][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:43.040 UTC [38392][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:43.042 UTC [38395][startup] LOG: database system was shut down at 2024-03-26 08:40:42 UTC 2024-03-26 08:40:43.044 UTC [38392][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:43.142 UTC [38400][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35123 2024-03-26 08:40:43.147 UTC [38400][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:40:43.147 UTC [38400][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-26 08:40:43.150 UTC [38392][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:43.150 UTC [38392][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:43.151 UTC [38392][postmaster] LOG: background worker "logical replication launcher" (PID 38398) exited with exit code 1 2024-03-26 08:40:43.151 UTC [38393][checkpointer] LOG: shutting down 2024-03-26 08:40:43.151 UTC [38393][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:43.152 UTC [38393][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/4102A00, redo lsn=0/4102A00 2024-03-26 08:40:43.153 UTC [38392][postmaster] LOG: database system is shut down 2024-03-26 08:40:43.268 UTC [38402][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:43.268 UTC [38402][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:43.268 UTC [38402][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:43.270 UTC [38405][startup] LOG: database system was shut down at 2024-03-26 08:40:43 UTC 2024-03-26 08:40:43.272 UTC [38402][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:43.369 UTC [38410][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35124 2024-03-26 08:40:43.375 UTC [38410][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:40:43.375 UTC [38410][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-26 08:40:43.381 UTC [38412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35125 2024-03-26 08:40:43.386 UTC [38412][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:40:43.386 UTC [38412][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-26 08:40:43.389 UTC [38402][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:43.389 UTC [38402][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:43.390 UTC [38402][postmaster] LOG: background worker "logical replication launcher" (PID 38408) exited with exit code 1 2024-03-26 08:40:43.390 UTC [38403][checkpointer] LOG: shutting down 2024-03-26 08:40:43.390 UTC [38403][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:43.390 UTC [38403][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/4102A78, redo lsn=0/4102A78 2024-03-26 08:40:43.391 UTC [38402][postmaster] LOG: database system is shut down 2024-03-26 08:40:43.506 UTC [38414][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:43.507 UTC [38414][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:43.507 UTC [38414][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:43.508 UTC [38417][startup] LOG: database system was shut down at 2024-03-26 08:40:43 UTC 2024-03-26 08:40:43.510 UTC [38414][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:43.608 UTC [38422][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35126 2024-03-26 08:40:43.610 UTC [38422][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:43.610 UTC [38422][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:43.618 UTC [38424][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35127 2024-03-26 08:40:43.622 UTC [38424][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-26 08:40:43.622 UTC [38424][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-26 08:40:43.626 UTC [38424][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-26 08:40:43.627 UTC [38424][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35127 2024-03-26 08:40:43.630 UTC [38414][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:43.630 UTC [38414][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:43.631 UTC [38414][postmaster] LOG: background worker "logical replication launcher" (PID 38420) exited with exit code 1 2024-03-26 08:40:43.631 UTC [38415][checkpointer] LOG: shutting down 2024-03-26 08:40:43.631 UTC [38415][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:43.631 UTC [38415][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/4102AF0, redo lsn=0/4102AF0 2024-03-26 08:40:43.632 UTC [38414][postmaster] LOG: database system is shut down 2024-03-26 08:40:43.741 UTC [38426][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:43.741 UTC [38426][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:43.741 UTC [38426][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:43.742 UTC [38429][startup] LOG: database system was shut down at 2024-03-26 08:40:43 UTC 2024-03-26 08:40:43.745 UTC [38426][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:43.843 UTC [38434][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35128 2024-03-26 08:40:43.845 UTC [38434][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:43.845 UTC [38434][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:43.852 UTC [38436][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35129 2024-03-26 08:40:43.856 UTC [38436][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-26 08:40:43.856 UTC [38436][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-26 08:40:43.860 UTC [38436][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-26 08:40:43.861 UTC [38436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35129 2024-03-26 08:40:43.864 UTC [38426][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:43.864 UTC [38426][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:43.864 UTC [38426][postmaster] LOG: background worker "logical replication launcher" (PID 38432) exited with exit code 1 2024-03-26 08:40:43.865 UTC [38427][checkpointer] LOG: shutting down 2024-03-26 08:40:43.865 UTC [38427][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:43.865 UTC [38427][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/4102B68, redo lsn=0/4102B68 2024-03-26 08:40:43.866 UTC [38426][postmaster] LOG: database system is shut down 2024-03-26 08:40:43.979 UTC [38438][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:43.979 UTC [38438][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:43.979 UTC [38438][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:43.981 UTC [38441][startup] LOG: database system was shut down at 2024-03-26 08:40:43 UTC 2024-03-26 08:40:43.983 UTC [38438][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:44.081 UTC [38446][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35130 2024-03-26 08:40:44.083 UTC [38446][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:44.083 UTC [38446][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:44.090 UTC [38448][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35131 2024-03-26 08:40:44.094 UTC [38448][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-26 08:40:44.094 UTC [38448][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-26 08:40:44.098 UTC [38448][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-26 08:40:44.099 UTC [38448][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35131 2024-03-26 08:40:44.101 UTC [38438][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:44.101 UTC [38438][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:44.102 UTC [38438][postmaster] LOG: background worker "logical replication launcher" (PID 38444) exited with exit code 1 2024-03-26 08:40:44.102 UTC [38439][checkpointer] LOG: shutting down 2024-03-26 08:40:44.102 UTC [38439][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:44.103 UTC [38439][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/4102BE0, redo lsn=0/4102BE0 2024-03-26 08:40:44.104 UTC [38438][postmaster] LOG: database system is shut down 2024-03-26 08:40:44.219 UTC [38450][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:44.219 UTC [38450][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:44.219 UTC [38450][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:44.221 UTC [38453][startup] LOG: database system was shut down at 2024-03-26 08:40:44 UTC 2024-03-26 08:40:44.223 UTC [38450][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:44.321 UTC [38458][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35132 2024-03-26 08:40:44.323 UTC [38458][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:44.323 UTC [38458][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:44.332 UTC [38460][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35133 2024-03-26 08:40:44.337 UTC [38460][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-26 08:40:44.337 UTC [38460][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-26 08:40:44.341 UTC [38460][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-26 08:40:44.342 UTC [38460][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35133 2024-03-26 08:40:44.346 UTC [38450][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:44.346 UTC [38450][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:44.347 UTC [38450][postmaster] LOG: background worker "logical replication launcher" (PID 38456) exited with exit code 1 2024-03-26 08:40:44.347 UTC [38451][checkpointer] LOG: shutting down 2024-03-26 08:40:44.347 UTC [38451][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:44.348 UTC [38451][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/4102C58, redo lsn=0/4102C58 2024-03-26 08:40:44.350 UTC [38450][postmaster] LOG: database system is shut down 2024-03-26 08:40:44.464 UTC [38462][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:44.464 UTC [38462][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:44.464 UTC [38462][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:44.466 UTC [38465][startup] LOG: database system was shut down at 2024-03-26 08:40:44 UTC 2024-03-26 08:40:44.469 UTC [38462][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:44.565 UTC [38470][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35134 2024-03-26 08:40:44.567 UTC [38470][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:44.568 UTC [38470][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:44.576 UTC [38472][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35135 2024-03-26 08:40:44.580 UTC [38472][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-26 08:40:44.580 UTC [38472][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-26 08:40:44.584 UTC [38472][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-26 08:40:44.585 UTC [38472][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35135 2024-03-26 08:40:44.588 UTC [38462][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:44.588 UTC [38462][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:44.589 UTC [38462][postmaster] LOG: background worker "logical replication launcher" (PID 38468) exited with exit code 1 2024-03-26 08:40:44.589 UTC [38463][checkpointer] LOG: shutting down 2024-03-26 08:40:44.589 UTC [38463][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:44.589 UTC [38463][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/4102CD0, redo lsn=0/4102CD0 2024-03-26 08:40:44.590 UTC [38462][postmaster] LOG: database system is shut down 2024-03-26 08:40:44.706 UTC [38474][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:44.706 UTC [38474][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:44.706 UTC [38474][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:44.708 UTC [38477][startup] LOG: database system was shut down at 2024-03-26 08:40:44 UTC 2024-03-26 08:40:44.710 UTC [38474][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:44.808 UTC [38482][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35136 2024-03-26 08:40:44.810 UTC [38482][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:44.810 UTC [38482][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:44.817 UTC [38484][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35137 2024-03-26 08:40:44.821 UTC [38484][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-26 08:40:44.822 UTC [38484][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-26 08:40:44.826 UTC [38484][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-26 08:40:44.826 UTC [38484][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35137 2024-03-26 08:40:44.830 UTC [38474][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:44.830 UTC [38474][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:44.831 UTC [38474][postmaster] LOG: background worker "logical replication launcher" (PID 38480) exited with exit code 1 2024-03-26 08:40:44.831 UTC [38475][checkpointer] LOG: shutting down 2024-03-26 08:40:44.831 UTC [38475][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:44.831 UTC [38475][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/4102D48, redo lsn=0/4102D48 2024-03-26 08:40:44.832 UTC [38474][postmaster] LOG: database system is shut down 2024-03-26 08:40:44.948 UTC [38486][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:44.948 UTC [38486][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:44.948 UTC [38486][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:44.949 UTC [38489][startup] LOG: database system was shut down at 2024-03-26 08:40:44 UTC 2024-03-26 08:40:44.952 UTC [38486][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:45.049 UTC [38494][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35138 2024-03-26 08:40:45.051 UTC [38494][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:45.051 UTC [38494][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:45.060 UTC [38496][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35139 2024-03-26 08:40:45.064 UTC [38496][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-26 08:40:45.064 UTC [38496][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-26 08:40:45.069 UTC [38496][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-26 08:40:45.069 UTC [38496][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35139 2024-03-26 08:40:45.074 UTC [38486][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:45.074 UTC [38486][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:45.075 UTC [38486][postmaster] LOG: background worker "logical replication launcher" (PID 38492) exited with exit code 1 2024-03-26 08:40:45.075 UTC [38487][checkpointer] LOG: shutting down 2024-03-26 08:40:45.075 UTC [38487][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:45.076 UTC [38487][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/4102DC0, redo lsn=0/4102DC0 2024-03-26 08:40:45.077 UTC [38486][postmaster] LOG: database system is shut down 2024-03-26 08:40:45.192 UTC [38498][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:45.192 UTC [38498][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:45.192 UTC [38498][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:45.194 UTC [38501][startup] LOG: database system was shut down at 2024-03-26 08:40:45 UTC 2024-03-26 08:40:45.197 UTC [38498][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:45.294 UTC [38506][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35140 2024-03-26 08:40:45.296 UTC [38506][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:45.296 UTC [38506][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:45.304 UTC [38508][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35141 2024-03-26 08:40:45.309 UTC [38508][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-26 08:40:45.310 UTC [38508][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-26 08:40:45.314 UTC [38508][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-26 08:40:45.315 UTC [38508][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=35141 2024-03-26 08:40:45.319 UTC [38498][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:45.319 UTC [38498][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:45.320 UTC [38498][postmaster] LOG: background worker "logical replication launcher" (PID 38504) exited with exit code 1 2024-03-26 08:40:45.320 UTC [38499][checkpointer] LOG: shutting down 2024-03-26 08:40:45.320 UTC [38499][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:45.321 UTC [38499][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/4102E38, redo lsn=0/4102E38 2024-03-26 08:40:45.322 UTC [38498][postmaster] LOG: database system is shut down 2024-03-26 08:40:45.437 UTC [38510][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-26 08:40:45.437 UTC [38510][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61771 2024-03-26 08:40:45.437 UTC [38510][postmaster] LOG: listening on Unix socket "/tmp/CBd5MFkyc5/.s.PGSQL.61771" 2024-03-26 08:40:45.439 UTC [38513][startup] LOG: database system was shut down at 2024-03-26 08:40:45 UTC 2024-03-26 08:40:45.442 UTC [38510][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:40:45.535 UTC [38518][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35142 2024-03-26 08:40:45.536 UTC [38518][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:40:45.536 UTC [38518][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:40:45.544 UTC [38520][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=35143 2024-03-26 08:40:45.549 UTC [38520][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-26 08:40:45.549 UTC [38520][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-26 08:40:45.553 UTC [38520][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-26 08:40:45.553 UTC [38520][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=35143 2024-03-26 08:40:45.557 UTC [38510][postmaster] LOG: received fast shutdown request 2024-03-26 08:40:45.557 UTC [38510][postmaster] LOG: aborting any active transactions 2024-03-26 08:40:45.557 UTC [38510][postmaster] LOG: background worker "logical replication launcher" (PID 38516) exited with exit code 1 2024-03-26 08:40:45.558 UTC [38511][checkpointer] LOG: shutting down 2024-03-26 08:40:45.558 UTC [38511][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:40:45.558 UTC [38511][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/4102EB0, redo lsn=0/4102EB0 2024-03-26 08:40:45.559 UTC [38510][postmaster] LOG: database system is shut down 2024-03-26 08:40:45.666 UTC [38522][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-26 08:40:45.666 UTC [38522][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:40:45.766](0.211s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"