[10:50:01.344](0.048s) # setting up data directory # Checking port 54976 # Found port 54976 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=54976 host=/tmp/YforlhpWvc Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [10:50:01.352](0.007s) # 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 49508 [10:50:01.588](0.236s) ok 1 - ssl_library parameter ### 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 49828 [10:50:02.568](0.980s) # 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-24 10:50:01.494 UTC [49508][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:01.494 UTC [49508][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:01.502 UTC [49518][startup] LOG: database system was shut down at 2024-03-24 10:47:45 UTC 2024-03-24 10:50:01.505 UTC [49508][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:01.581 UTC [49550][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.582 UTC [49550][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-24 10:50:01.582 UTC [49550][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.586 UTC [49550][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-24 10:50:01.587 UTC [49550][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.604 UTC [49558][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.605 UTC [49558][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-24 10:50:01.605 UTC [49558][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.606 UTC [49558][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-24 10:50:01.607 UTC [49558][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.639 UTC [49583][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.639 UTC [49583][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-24 10:50:01.639 UTC [49583][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.640 UTC [49583][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-24 10:50:01.641 UTC [49583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.682 UTC [49603][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.683 UTC [49603][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-24 10:50:01.683 UTC [49603][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.684 UTC [49603][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-24 10:50:01.684 UTC [49603][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.725 UTC [49627][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.726 UTC [49627][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-24 10:50:01.726 UTC [49627][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.727 UTC [49627][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-24 10:50:01.728 UTC [49627][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.767 UTC [49647][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.768 UTC [49647][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-24 10:50:01.768 UTC [49647][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.769 UTC [49647][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-24 10:50:01.798 UTC [49647][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.810 UTC [49661][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.810 UTC [49661][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-24 10:50:01.810 UTC [49661][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.811 UTC [49661][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-24 10:50:01.864 UTC [49661][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.054 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.934 UTC [49709][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.935 UTC [49709][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-24 10:50:01.935 UTC [49709][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.935 UTC [49709][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-24 10:50:01.965 UTC [49709][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.985 UTC [49725][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.986 UTC [49725][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-24 10:50:01.986 UTC [49725][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.987 UTC [49725][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-24 10:50:02.075 UTC [49725][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.117 UTC [49757][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:02.123 UTC [49757][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-24 10:50:02.123 UTC [49757][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:02.125 UTC [49757][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-24 10:50:02.172 UTC [49757][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.055 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.256 UTC [49787][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:02.256 UTC [49787][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-24 10:50:02.256 UTC [49787][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:02.257 UTC [49787][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-24 10:50:02.296 UTC [49787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.367 UTC [49508][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.367 UTC [49508][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.369 UTC [49508][postmaster] LOG: background worker "logical replication launcher" (PID 49524) exited with exit code 1 2024-03-24 10:50:02.369 UTC [49516][checkpointer] LOG: shutting down 2024-03-24 10:50:02.369 UTC [49516][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.409 UTC [49516][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 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=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100 2024-03-24 10:50:02.420 UTC [49508][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:02.485 UTC [49833][startup] LOG: database system was shut down at 2024-03-24 10:50:02 UTC 2024-03-24 10:50:02.487 UTC [49828][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:02.570 UTC [49828][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.570 UTC [49828][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.571 UTC [49828][postmaster] LOG: background worker "logical replication launcher" (PID 49836) exited with exit code 1 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: shutting down 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F178, redo lsn=0/414F178 2024-03-24 10:50:02.574 UTC [49828][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.690 UTC [49881][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-24 10:50:02.690 UTC [49881][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:50:02.771](0.203s) 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 49908 [10:50:02.875](0.104s) 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-24 10:50:01.494 UTC [49508][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:01.494 UTC [49508][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:01.502 UTC [49518][startup] LOG: database system was shut down at 2024-03-24 10:47:45 UTC 2024-03-24 10:50:01.505 UTC [49508][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:01.581 UTC [49550][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.582 UTC [49550][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-24 10:50:01.582 UTC [49550][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.586 UTC [49550][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-24 10:50:01.587 UTC [49550][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.604 UTC [49558][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.605 UTC [49558][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-24 10:50:01.605 UTC [49558][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.606 UTC [49558][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-24 10:50:01.607 UTC [49558][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.639 UTC [49583][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.639 UTC [49583][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-24 10:50:01.639 UTC [49583][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.640 UTC [49583][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-24 10:50:01.641 UTC [49583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.682 UTC [49603][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.683 UTC [49603][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-24 10:50:01.683 UTC [49603][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.684 UTC [49603][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-24 10:50:01.684 UTC [49603][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.725 UTC [49627][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.726 UTC [49627][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-24 10:50:01.726 UTC [49627][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.727 UTC [49627][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-24 10:50:01.728 UTC [49627][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.767 UTC [49647][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.768 UTC [49647][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-24 10:50:01.768 UTC [49647][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.769 UTC [49647][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-24 10:50:01.798 UTC [49647][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.810 UTC [49661][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.810 UTC [49661][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-24 10:50:01.810 UTC [49661][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.811 UTC [49661][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-24 10:50:01.864 UTC [49661][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.054 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.934 UTC [49709][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.935 UTC [49709][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-24 10:50:01.935 UTC [49709][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.935 UTC [49709][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-24 10:50:01.965 UTC [49709][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.985 UTC [49725][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.986 UTC [49725][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-24 10:50:01.986 UTC [49725][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.987 UTC [49725][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-24 10:50:02.075 UTC [49725][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.117 UTC [49757][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:02.123 UTC [49757][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-24 10:50:02.123 UTC [49757][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:02.125 UTC [49757][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-24 10:50:02.172 UTC [49757][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.055 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.256 UTC [49787][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:02.256 UTC [49787][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-24 10:50:02.256 UTC [49787][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:02.257 UTC [49787][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-24 10:50:02.296 UTC [49787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.367 UTC [49508][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.367 UTC [49508][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.369 UTC [49508][postmaster] LOG: background worker "logical replication launcher" (PID 49524) exited with exit code 1 2024-03-24 10:50:02.369 UTC [49516][checkpointer] LOG: shutting down 2024-03-24 10:50:02.369 UTC [49516][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.409 UTC [49516][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 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=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100 2024-03-24 10:50:02.420 UTC [49508][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:02.485 UTC [49833][startup] LOG: database system was shut down at 2024-03-24 10:50:02 UTC 2024-03-24 10:50:02.487 UTC [49828][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:02.570 UTC [49828][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.570 UTC [49828][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.571 UTC [49828][postmaster] LOG: background worker "logical replication launcher" (PID 49836) exited with exit code 1 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: shutting down 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F178, redo lsn=0/414F178 2024-03-24 10:50:02.574 UTC [49828][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.690 UTC [49881][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-24 10:50:02.690 UTC [49881][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.793 UTC [49908][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:02.793 UTC [49908][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:02.794 UTC [49908][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:02.795 UTC [49914][startup] LOG: database system was shut down at 2024-03-24 10:50:02 UTC 2024-03-24 10:50:02.798 UTC [49908][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:02.877 UTC [49908][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.877 UTC [49908][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.878 UTC [49908][postmaster] LOG: background worker "logical replication launcher" (PID 49917) exited with exit code 1 2024-03-24 10:50:02.879 UTC [49912][checkpointer] LOG: shutting down 2024-03-24 10:50:02.879 UTC [49912][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.880 UTC [49912][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F1F0, redo lsn=0/414F1F0 2024-03-24 10:50:02.882 UTC [49908][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.991 UTC [49956][postmaster] FATAL: could not set SSL protocol version range 2024-03-24 10:50:02.991 UTC [49956][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-24 10:50:02.991 UTC [49956][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:50:03.078](0.203s) 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 49980 [10:50:03.181](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [10:50:03.181](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 50028 [10:50:03.408](0.227s) ok 6 - server doesn't accept non-SSL connections [10:50:03.408](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [10:50:03.434](0.026s) ok 8 - connect without server root cert sslmode=require [10:50:03.434](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [10:50:03.443](0.009s) ok 10 - connect without server root cert sslmode=verify-ca [10:50:03.443](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [10:50:03.450](0.008s) ok 12 - connect without server root cert sslmode=verify-full [10:50:03.451](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [10:50:03.460](0.010s) ok 14 - connect with wrong server root cert sslmode=require [10:50:03.461](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [10:50:03.471](0.010s) ok 16 - connect with wrong server root cert sslmode=verify-ca [10:50:03.471](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [10:50:03.480](0.009s) ok 18 - connect with wrong server root cert sslmode=verify-full [10:50:03.480](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [10:50:03.489](0.009s) ok 20 - connect with server CA cert, without root CA [10:50:03.489](0.000s) ok 21 - connect with server CA cert, without root CA: matches [10:50:03.501](0.012s) ok 22 - connect with correct server CA cert file sslmode=require [10:50:03.501](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [10:50:03.514](0.012s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [10:50:03.514](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [10:50:03.525](0.011s) ok 26 - connect with correct server CA cert file sslmode=verify-full [10:50:03.525](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [10:50:03.540](0.015s) ok 28 - cert root file that contains two certificates, order 1 [10:50:03.540](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [10:50:03.553](0.012s) ok 30 - cert root file that contains two certificates, order 2 [10:50:03.553](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [10:50:03.574](0.021s) ok 32 - connect with sslcertmode=disable [10:50:03.575](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [10:50:03.601](0.027s) ok 34 - connect with sslcertmode=allow [10:50:03.601](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [10:50:03.616](0.015s) ok 36 - connect with sslcertmode=require fails without a client certificate [10:50:03.617](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [10:50:03.629](0.012s) ok 38 - sslcrl option with invalid file name [10:50:03.629](0.001s) ok 39 - sslcrl option with invalid file name: no stderr [10:50:03.640](0.010s) ok 40 - CRL belonging to a different CA [10:50:03.640](0.000s) ok 41 - CRL belonging to a different CA: matches [10:50:03.649](0.010s) ok 42 - directory CRL belonging to a different CA [10:50:03.650](0.000s) ok 43 - directory CRL belonging to a different CA: matches [10:50:03.661](0.012s) ok 44 - CRL with a non-revoked cert [10:50:03.662](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [10:50:03.673](0.011s) ok 46 - directory CRL with a non-revoked cert [10:50:03.673](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [10:50:03.684](0.011s) ok 48 - mismatch between host name and server certificate sslmode=require [10:50:03.684](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [10:50:03.695](0.011s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [10:50:03.696](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [10:50:03.706](0.010s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [10:50:03.706](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 50187 [10:50:03.926](0.219s) ok 54 - IP address in the Common Name [10:50:03.926](0.000s) ok 55 - IP address in the Common Name: no stderr [10:50:03.937](0.012s) ok 56 - mismatch between host name and server certificate IP address [10:50:03.937](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 50222 [10:50:04.157](0.220s) ok 58 - IP address in a dNSName [10:50:04.158](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 50246 [10:50:04.378](0.220s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [10:50:04.378](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [10:50:04.390](0.012s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [10:50:04.390](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [10:50:04.402](0.012s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [10:50:04.402](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [10:50:04.412](0.010s) ok 66 - host name not matching with X.509 Subject Alternative Names [10:50:04.412](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [10:50:04.423](0.011s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [10:50:04.423](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 50278 [10:50:04.643](0.220s) ok 70 - host name matching with a single X.509 Subject Alternative Name [10:50:04.643](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [10:50:04.654](0.011s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [10:50:04.654](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [10:50:04.665](0.011s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [10:50:04.665](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 50309 [10:50:04.889](0.224s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [10:50:04.889](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [10:50:04.902](0.013s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [10:50:04.902](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [10:50:04.912](0.010s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [10:50:04.912](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [10:50:04.923](0.011s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [10:50:04.924](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [10:50:04.935](0.012s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [10:50:04.936](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [10:50:04.947](0.011s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [10:50:04.947](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [10:50:04.956](0.009s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [10:50:04.956](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [10:50:04.966](0.009s) ok 90 - IPv6 host with CIDR mask does not match [10:50:04.966](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 50333 [10:50:05.186](0.220s) ok 92 - certificate with both a CN and SANs 1 [10:50:05.186](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [10:50:05.198](0.012s) ok 94 - certificate with both a CN and SANs 2 [10:50:05.199](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [10:50:05.208](0.010s) ok 96 - certificate with both a CN and SANs ignores CN [10:50:05.209](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 50348 [10:50:05.430](0.221s) ok 98 - certificate with both a CN and IP SANs matches CN [10:50:05.430](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [10:50:05.441](0.011s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [10:50:05.441](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [10:50:05.452](0.011s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [10:50:05.452](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 50362 [10:50:05.672](0.220s) ok 104 - certificate with both an IP CN and IP SANs 1 [10:50:05.672](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [10:50:05.683](0.011s) ok 106 - certificate with both an IP CN and IP SANs 2 [10:50:05.683](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [10:50:05.693](0.009s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [10:50:05.693](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 50376 [10:50:05.912](0.219s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [10:50:05.912](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [10:50:05.923](0.011s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [10:50:05.924](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [10:50:05.934](0.011s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [10:50:05.934](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 50390 [10:50:06.155](0.220s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [10:50:06.155](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [10:50:06.165](0.010s) ok 118 - server certificate without CN or SANs sslmode=verify-full [10:50:06.165](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 50402 [10:50:06.379](0.214s) ok 120 - sslrootcert=system does not connect with private CA [10:50:06.380](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [10:50:06.386](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [10:50:06.386](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [10:50:06.402](0.016s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [10:50:06.402](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [10:50:06.412](0.010s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [10:50:06.412](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 50417 [10:50:06.633](0.220s) ok 128 - connects without client-side CRL [10:50:06.633](0.000s) ok 129 - connects without client-side CRL: no stderr [10:50:06.643](0.010s) ok 130 - does not connect with client-side CRL file [10:50:06.643](0.000s) ok 131 - does not connect with client-side CRL file: matches [10:50:06.652](0.009s) ok 132 - does not connect with client-side CRL directory [10:50:06.653](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [10:50:06.664](0.012s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [10:50:06.664](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [10:50:06.664](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [10:50:06.675](0.011s) ok 137 - connection success with correct range of TLS protocol versions [10:50:06.675](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [10:50:06.681](0.005s) ok 139 - connection failure with incorrect range of TLS protocol versions [10:50:06.681](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [10:50:06.686](0.005s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [10:50:06.686](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [10:50:06.691](0.005s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [10:50:06.691](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [10:50:06.692](0.000s) # running server tests [10:50:06.701](0.010s) ok 145 - certificate authorization fails without client cert [10:50:06.702](0.000s) ok 146 - certificate authorization fails without client cert: matches [10:50:06.719](0.017s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [10:50:06.719](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [10:50:06.732](0.012s) ok 149 - certificate authorization succeeds with correct client cert in DER format [10:50:06.732](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [10:50:06.744](0.012s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [10:50:06.744](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [10:50:06.756](0.012s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [10:50:06.756](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [10:50:06.768](0.012s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [10:50:06.768](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [10:50:06.780](0.012s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [10:50:06.781](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [10:50:06.790](0.010s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [10:50:06.790](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [10:50:06.798](0.008s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [10:50:06.798](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [10:50:06.814](0.016s) ok 163 - certificate authorization succeeds with DN mapping [10:50:06.814](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [10:50:06.814](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [10:50:06.830](0.016s) ok 166 - certificate authorization succeeds with DN regex mapping [10:50:06.830](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [10:50:06.847](0.017s) ok 168 - certificate authorization succeeds with CN mapping [10:50:06.847](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [10:50:06.848](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [10:50:06.848](0.000s) not ok 171 # TODO & SKIP Need Pty support [10:50:06.848](0.000s) not ok 172 # TODO & SKIP Need Pty support [10:50:06.848](0.000s) not ok 173 # TODO & SKIP Need Pty support [10:50:06.848](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_Win4/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [10:50:06.869](0.021s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [10:50:06.869](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [10:50:06.870](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [10:50:06.877](0.007s) ok 178 - certificate authorization fails because of file permissions [10:50:06.877](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [10:50:06.888](0.011s) ok 180 - certificate authorization fails with client cert belonging to another user [10:50:06.888](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [10:50:06.899](0.010s) ok 182 - certificate authorization fails with revoked client cert [10:50:06.899](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [10:50:06.899](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [10:50:06.915](0.016s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [10:50:06.915](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [10:50:06.915](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [10:50:06.926](0.011s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [10:50:06.926](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [10:50:06.927](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [10:50:06.938](0.012s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [10:50:06.939](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [10:50:06.939](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 50477 [10:50:07.160](0.221s) ok 194 - intermediate client certificate is provided by client [10:50:07.160](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [10:50:07.171](0.011s) ok 196 - intermediate client certificate is missing [10:50:07.171](0.000s) ok 197 - intermediate client certificate is missing: matches [10:50:07.182](0.011s) ok 198 - logged client certificate Subjects are truncated if they're too long [10:50:07.182](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 50491 [10:50:07.397](0.215s) ok 200 - intermediate client certificate is untrusted [10:50:07.397](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 50501 [10:50:07.613](0.216s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [10:50:07.613](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [10:50:07.624](0.011s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [10:50:07.624](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 50513 [10:50:07.837](0.213s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [10:50:07.838](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [10:50:07.838](0.000s) # got: '2' # expected: '0' [10:50:07.838](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [10:50:07.838](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [10:50:07.838](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 54976 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [10:50:07.855](0.017s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [10:50:07.855](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 50525 [10:50:08.068](0.213s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [10:50:08.085](0.016s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [10:50:08.085](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 50537 [10:50:08.299](0.214s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [10:50:08.314](0.016s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [10:50:08.315](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 50549 [10:50:08.528](0.213s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [10:50:08.543](0.016s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [10:50:08.543](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 50561 [10:50:08.756](0.213s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [10:50:08.756](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [10:50:08.756](0.000s) # got: '2' # expected: '0' [10:50:08.757](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [10:50:08.757](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [10:50:08.757](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 54976 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [10:50:08.772](0.015s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [10:50:08.772](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 50573 [10:50:08.985](0.213s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [10:50:09.000](0.015s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [10:50:09.001](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 50585 [10:50:09.213](0.213s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [10:50:09.228](0.015s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [10:50:09.229](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 50597 [10:50:09.441](0.213s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [10:50:09.456](0.015s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [10:50:09.456](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 50609 [10:50:09.669](0.213s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [10:50:09.684](0.015s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [10:50:09.684](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-24 10:50:01.494 UTC [49508][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:01.494 UTC [49508][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:01.502 UTC [49518][startup] LOG: database system was shut down at 2024-03-24 10:47:45 UTC 2024-03-24 10:50:01.505 UTC [49508][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:01.581 UTC [49550][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.582 UTC [49550][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-24 10:50:01.582 UTC [49550][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.586 UTC [49550][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-24 10:50:01.587 UTC [49550][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.604 UTC [49558][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.605 UTC [49558][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-24 10:50:01.605 UTC [49558][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.606 UTC [49558][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-24 10:50:01.607 UTC [49558][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.639 UTC [49583][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.639 UTC [49583][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-24 10:50:01.639 UTC [49583][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.640 UTC [49583][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-24 10:50:01.641 UTC [49583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.682 UTC [49603][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.683 UTC [49603][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-24 10:50:01.683 UTC [49603][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.684 UTC [49603][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-24 10:50:01.684 UTC [49603][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.725 UTC [49627][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.726 UTC [49627][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-24 10:50:01.726 UTC [49627][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.727 UTC [49627][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-24 10:50:01.728 UTC [49627][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.767 UTC [49647][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.768 UTC [49647][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-24 10:50:01.768 UTC [49647][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.769 UTC [49647][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-24 10:50:01.798 UTC [49647][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.810 UTC [49661][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.810 UTC [49661][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-24 10:50:01.810 UTC [49661][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.811 UTC [49661][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-24 10:50:01.864 UTC [49661][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.054 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.934 UTC [49709][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.935 UTC [49709][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-24 10:50:01.935 UTC [49709][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.935 UTC [49709][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-24 10:50:01.965 UTC [49709][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local] 2024-03-24 10:50:01.985 UTC [49725][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:01.986 UTC [49725][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-24 10:50:01.986 UTC [49725][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:01.987 UTC [49725][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-24 10:50:02.075 UTC [49725][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.117 UTC [49757][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:02.123 UTC [49757][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-24 10:50:02.123 UTC [49757][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:02.125 UTC [49757][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-24 10:50:02.172 UTC [49757][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.055 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.256 UTC [49787][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-24 10:50:02.256 UTC [49787][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-24 10:50:02.256 UTC [49787][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-24 10:50:02.257 UTC [49787][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-24 10:50:02.296 UTC [49787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-24 10:50:02.367 UTC [49508][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.367 UTC [49508][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.369 UTC [49508][postmaster] LOG: background worker "logical replication launcher" (PID 49524) exited with exit code 1 2024-03-24 10:50:02.369 UTC [49516][checkpointer] LOG: shutting down 2024-03-24 10:50:02.369 UTC [49516][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.409 UTC [49516][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 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=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100 2024-03-24 10:50:02.420 UTC [49508][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:02.483 UTC [49828][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:02.485 UTC [49833][startup] LOG: database system was shut down at 2024-03-24 10:50:02 UTC 2024-03-24 10:50:02.487 UTC [49828][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:02.570 UTC [49828][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.570 UTC [49828][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.571 UTC [49828][postmaster] LOG: background worker "logical replication launcher" (PID 49836) exited with exit code 1 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: shutting down 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.572 UTC [49831][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F178, redo lsn=0/414F178 2024-03-24 10:50:02.574 UTC [49828][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.690 UTC [49881][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-24 10:50:02.690 UTC [49881][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.793 UTC [49908][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:02.793 UTC [49908][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:02.794 UTC [49908][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:02.795 UTC [49914][startup] LOG: database system was shut down at 2024-03-24 10:50:02 UTC 2024-03-24 10:50:02.798 UTC [49908][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:02.877 UTC [49908][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:02.877 UTC [49908][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:02.878 UTC [49908][postmaster] LOG: background worker "logical replication launcher" (PID 49917) exited with exit code 1 2024-03-24 10:50:02.879 UTC [49912][checkpointer] LOG: shutting down 2024-03-24 10:50:02.879 UTC [49912][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:02.880 UTC [49912][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F1F0, redo lsn=0/414F1F0 2024-03-24 10:50:02.882 UTC [49908][postmaster] LOG: database system is shut down 2024-03-24 10:50:02.991 UTC [49956][postmaster] FATAL: could not set SSL protocol version range 2024-03-24 10:50:02.991 UTC [49956][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-24 10:50:02.991 UTC [49956][postmaster] LOG: database system is shut down 2024-03-24 10:50:03.102 UTC [49980][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:03.102 UTC [49980][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:03.102 UTC [49980][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:03.104 UTC [49990][startup] LOG: database system was shut down at 2024-03-24 10:50:02 UTC 2024-03-24 10:50:03.107 UTC [49980][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:03.183 UTC [49980][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:03.183 UTC [49980][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:03.185 UTC [49980][postmaster] LOG: background worker "logical replication launcher" (PID 49993) exited with exit code 1 2024-03-24 10:50:03.186 UTC [49987][checkpointer] LOG: shutting down 2024-03-24 10:50:03.186 UTC [49987][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:03.186 UTC [49987][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F268, redo lsn=0/414F268 2024-03-24 10:50:03.188 UTC [49980][postmaster] LOG: database system is shut down 2024-03-24 10:50:03.301 UTC [50028][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:03.301 UTC [50028][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:03.301 UTC [50028][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:03.303 UTC [50039][startup] LOG: database system was shut down at 2024-03-24 10:50:03 UTC 2024-03-24 10:50:03.305 UTC [50028][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:03.406 UTC [50075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37818 2024-03-24 10:50:03.407 UTC [50075][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-03-24 10:50:03.407 UTC [50075][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-24 10:50:03.415 UTC [50082][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37826 2024-03-24 10:50:03.426 UTC [50082][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-24 10:50:03.426 UTC [50082][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.432 UTC [50082][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-03-24 10:50:03.435 UTC [50082][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=37826 2024-03-24 10:50:03.441 UTC [50093][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37830 2024-03-24 10:50:03.442 UTC [50093][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-24 10:50:03.449 UTC [50096][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37846 2024-03-24 10:50:03.449 UTC [50096][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-24 10:50:03.456 UTC [50098][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37856 2024-03-24 10:50:03.459 UTC [50098][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:03.467 UTC [50103][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37862 2024-03-24 10:50:03.470 UTC [50103][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:03.476 UTC [50108][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37864 2024-03-24 10:50:03.479 UTC [50108][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:03.485 UTC [50111][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37880 2024-03-24 10:50:03.488 UTC [50111][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:03.495 UTC [50116][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37892 2024-03-24 10:50:03.498 UTC [50116][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-24 10:50:03.498 UTC [50116][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.499 UTC [50116][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-03-24 10:50:03.500 UTC [50116][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37892 2024-03-24 10:50:03.508 UTC [50118][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37896 2024-03-24 10:50:03.511 UTC [50118][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-24 10:50:03.511 UTC [50118][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.512 UTC [50118][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-24 10:50:03.513 UTC [50118][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37896 2024-03-24 10:50:03.520 UTC [50121][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37898 2024-03-24 10:50:03.523 UTC [50121][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-24 10:50:03.523 UTC [50121][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.524 UTC [50121][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-03-24 10:50:03.524 UTC [50121][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37898 2024-03-24 10:50:03.534 UTC [50125][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37908 2024-03-24 10:50:03.538 UTC [50125][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-24 10:50:03.538 UTC [50125][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.539 UTC [50125][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-03-24 10:50:03.542 UTC [50125][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=37908 2024-03-24 10:50:03.547 UTC [50130][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37914 2024-03-24 10:50:03.551 UTC [50130][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-24 10:50:03.551 UTC [50130][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.551 UTC [50130][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-03-24 10:50:03.552 UTC [50130][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37914 2024-03-24 10:50:03.565 UTC [50136][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37926 2024-03-24 10:50:03.571 UTC [50136][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-24 10:50:03.571 UTC [50136][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.573 UTC [50136][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-03-24 10:50:03.574 UTC [50136][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37926 2024-03-24 10:50:03.590 UTC [50141][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37936 2024-03-24 10:50:03.596 UTC [50141][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-24 10:50:03.596 UTC [50141][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.599 UTC [50141][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-03-24 10:50:03.600 UTC [50141][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37936 2024-03-24 10:50:03.611 UTC [50146][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37938 2024-03-24 10:50:03.614 UTC [50146][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-24 10:50:03.614 UTC [50146][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.615 UTC [50146][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37938 2024-03-24 10:50:03.623 UTC [50148][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37946 2024-03-24 10:50:03.627 UTC [50148][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-24 10:50:03.627 UTC [50148][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.627 UTC [50148][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-03-24 10:50:03.628 UTC [50148][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37946 2024-03-24 10:50:03.635 UTC [50152][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37958 2024-03-24 10:50:03.639 UTC [50152][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:03.646 UTC [50155][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37966 2024-03-24 10:50:03.648 UTC [50155][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:03.655 UTC [50157][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37976 2024-03-24 10:50:03.659 UTC [50157][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-24 10:50:03.659 UTC [50157][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.660 UTC [50157][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-03-24 10:50:03.660 UTC [50157][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=37976 2024-03-24 10:50:03.668 UTC [50159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37988 2024-03-24 10:50:03.671 UTC [50159][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-24 10:50:03.671 UTC [50159][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.672 UTC [50159][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-03-24 10:50:03.672 UTC [50159][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37988 2024-03-24 10:50:03.678 UTC [50162][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=37998 2024-03-24 10:50:03.682 UTC [50162][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-24 10:50:03.682 UTC [50162][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.683 UTC [50162][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-03-24 10:50:03.683 UTC [50162][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=37998 2024-03-24 10:50:03.690 UTC [50165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38012 2024-03-24 10:50:03.693 UTC [50165][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-24 10:50:03.693 UTC [50165][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.694 UTC [50165][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-03-24 10:50:03.694 UTC [50165][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38012 2024-03-24 10:50:03.701 UTC [50168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38022 2024-03-24 10:50:03.708 UTC [50028][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:03.708 UTC [50028][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:03.709 UTC [50028][postmaster] LOG: background worker "logical replication launcher" (PID 50043) exited with exit code 1 2024-03-24 10:50:03.710 UTC [50037][checkpointer] LOG: shutting down 2024-03-24 10:50:03.710 UTC [50037][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:03.710 UTC [50037][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F2E0, redo lsn=0/414F2E0 2024-03-24 10:50:03.713 UTC [50028][postmaster] LOG: database system is shut down 2024-03-24 10:50:03.825 UTC [50187][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:03.825 UTC [50187][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:03.825 UTC [50187][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:03.827 UTC [50191][startup] LOG: database system was shut down at 2024-03-24 10:50:03 UTC 2024-03-24 10:50:03.829 UTC [50187][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:03.915 UTC [50202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38028 2024-03-24 10:50:03.919 UTC [50202][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-24 10:50:03.919 UTC [50202][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:03.924 UTC [50202][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-24 10:50:03.924 UTC [50202][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38028 2024-03-24 10:50:03.933 UTC [50210][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38036 2024-03-24 10:50:03.939 UTC [50187][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:03.939 UTC [50187][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:03.940 UTC [50187][postmaster] LOG: background worker "logical replication launcher" (PID 50194) exited with exit code 1 2024-03-24 10:50:03.941 UTC [50189][checkpointer] LOG: shutting down 2024-03-24 10:50:03.941 UTC [50189][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:03.942 UTC [50189][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F358, redo lsn=0/414F358 2024-03-24 10:50:03.944 UTC [50187][postmaster] LOG: database system is shut down 2024-03-24 10:50:04.056 UTC [50222][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:04.056 UTC [50222][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:04.056 UTC [50222][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:04.058 UTC [50226][startup] LOG: database system was shut down at 2024-03-24 10:50:03 UTC 2024-03-24 10:50:04.061 UTC [50222][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:04.147 UTC [50235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38046 2024-03-24 10:50:04.151 UTC [50235][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-24 10:50:04.151 UTC [50235][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.156 UTC [50235][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-24 10:50:04.156 UTC [50235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38046 2024-03-24 10:50:04.159 UTC [50222][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:04.159 UTC [50222][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:04.161 UTC [50222][postmaster] LOG: background worker "logical replication launcher" (PID 50230) exited with exit code 1 2024-03-24 10:50:04.161 UTC [50224][checkpointer] LOG: shutting down 2024-03-24 10:50:04.161 UTC [50224][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:04.161 UTC [50224][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F3D0, redo lsn=0/414F3D0 2024-03-24 10:50:04.164 UTC [50222][postmaster] LOG: database system is shut down 2024-03-24 10:50:04.278 UTC [50246][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:04.278 UTC [50246][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:04.278 UTC [50246][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:04.280 UTC [50249][startup] LOG: database system was shut down at 2024-03-24 10:50:04 UTC 2024-03-24 10:50:04.282 UTC [50246][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:04.368 UTC [50257][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38052 2024-03-24 10:50:04.371 UTC [50257][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-24 10:50:04.371 UTC [50257][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.376 UTC [50257][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:04.377 UTC [50257][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38052 2024-03-24 10:50:04.384 UTC [50261][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38064 2024-03-24 10:50:04.387 UTC [50261][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-24 10:50:04.388 UTC [50261][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.388 UTC [50261][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:04.389 UTC [50261][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=38064 2024-03-24 10:50:04.396 UTC [50263][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38068 2024-03-24 10:50:04.400 UTC [50263][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-24 10:50:04.400 UTC [50263][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.401 UTC [50263][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-24 10:50:04.401 UTC [50263][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=38068 2024-03-24 10:50:04.408 UTC [50265][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38070 2024-03-24 10:50:04.418 UTC [50268][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38084 2024-03-24 10:50:04.425 UTC [50246][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:04.425 UTC [50246][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:04.426 UTC [50246][postmaster] LOG: background worker "logical replication launcher" (PID 50252) exited with exit code 1 2024-03-24 10:50:04.427 UTC [50247][checkpointer] LOG: shutting down 2024-03-24 10:50:04.427 UTC [50247][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:04.428 UTC [50247][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F448, redo lsn=0/414F448 2024-03-24 10:50:04.430 UTC [50246][postmaster] LOG: database system is shut down 2024-03-24 10:50:04.544 UTC [50278][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:04.544 UTC [50278][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:04.544 UTC [50278][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:04.546 UTC [50284][startup] LOG: database system was shut down at 2024-03-24 10:50:04 UTC 2024-03-24 10:50:04.548 UTC [50278][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:04.633 UTC [50291][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38094 2024-03-24 10:50:04.637 UTC [50291][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-24 10:50:04.637 UTC [50291][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.641 UTC [50291][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:04.642 UTC [50291][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38094 2024-03-24 10:50:04.650 UTC [50294][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38098 2024-03-24 10:50:04.661 UTC [50302][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38112 2024-03-24 10:50:04.669 UTC [50278][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:04.669 UTC [50278][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:04.671 UTC [50278][postmaster] LOG: background worker "logical replication launcher" (PID 50287) exited with exit code 1 2024-03-24 10:50:04.671 UTC [50282][checkpointer] LOG: shutting down 2024-03-24 10:50:04.671 UTC [50282][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:04.672 UTC [50282][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F4C0, redo lsn=0/414F4C0 2024-03-24 10:50:04.674 UTC [50278][postmaster] LOG: database system is shut down 2024-03-24 10:50:04.787 UTC [50309][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:04.787 UTC [50309][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:04.787 UTC [50309][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:04.789 UTC [50312][startup] LOG: database system was shut down at 2024-03-24 10:50:04 UTC 2024-03-24 10:50:04.792 UTC [50309][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:04.877 UTC [50317][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38114 2024-03-24 10:50:04.881 UTC [50317][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-24 10:50:04.881 UTC [50317][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.886 UTC [50317][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-24 10:50:04.886 UTC [50317][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38114 2024-03-24 10:50:04.896 UTC [50319][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38122 2024-03-24 10:50:04.899 UTC [50319][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-24 10:50:04.899 UTC [50319][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.900 UTC [50319][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-03-24 10:50:04.901 UTC [50319][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=38122 2024-03-24 10:50:04.908 UTC [50321][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38132 2024-03-24 10:50:04.918 UTC [50323][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38146 2024-03-24 10:50:04.921 UTC [50323][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-24 10:50:04.921 UTC [50323][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.922 UTC [50323][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-03-24 10:50:04.922 UTC [50323][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38146 2024-03-24 10:50:04.930 UTC [50325][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38162 2024-03-24 10:50:04.933 UTC [50325][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-24 10:50:04.933 UTC [50325][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.934 UTC [50325][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-03-24 10:50:04.934 UTC [50325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38162 2024-03-24 10:50:04.941 UTC [50327][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38166 2024-03-24 10:50:04.945 UTC [50327][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-24 10:50:04.945 UTC [50327][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:04.945 UTC [50327][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-03-24 10:50:04.946 UTC [50327][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38166 2024-03-24 10:50:04.952 UTC [50329][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38178 2024-03-24 10:50:04.962 UTC [50331][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38182 2024-03-24 10:50:04.968 UTC [50309][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:04.968 UTC [50309][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:04.969 UTC [50309][postmaster] LOG: background worker "logical replication launcher" (PID 50315) exited with exit code 1 2024-03-24 10:50:04.969 UTC [50310][checkpointer] LOG: shutting down 2024-03-24 10:50:04.969 UTC [50310][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:04.969 UTC [50310][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F538, redo lsn=0/414F538 2024-03-24 10:50:04.972 UTC [50309][postmaster] LOG: database system is shut down 2024-03-24 10:50:05.086 UTC [50333][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:05.086 UTC [50333][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:05.086 UTC [50333][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:05.088 UTC [50336][startup] LOG: database system was shut down at 2024-03-24 10:50:04 UTC 2024-03-24 10:50:05.091 UTC [50333][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:05.175 UTC [50341][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38188 2024-03-24 10:50:05.179 UTC [50341][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-24 10:50:05.179 UTC [50341][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.184 UTC [50341][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:05.185 UTC [50341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38188 2024-03-24 10:50:05.193 UTC [50343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38198 2024-03-24 10:50:05.196 UTC [50343][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-24 10:50:05.196 UTC [50343][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.197 UTC [50343][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:05.198 UTC [50343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=38198 2024-03-24 10:50:05.205 UTC [50345][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38206 2024-03-24 10:50:05.213 UTC [50333][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:05.213 UTC [50333][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:05.214 UTC [50333][postmaster] LOG: background worker "logical replication launcher" (PID 50339) exited with exit code 1 2024-03-24 10:50:05.214 UTC [50334][checkpointer] LOG: shutting down 2024-03-24 10:50:05.214 UTC [50334][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:05.214 UTC [50334][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F5B0, redo lsn=0/414F5B0 2024-03-24 10:50:05.217 UTC [50333][postmaster] LOG: database system is shut down 2024-03-24 10:50:05.337 UTC [50348][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:05.337 UTC [50348][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:05.337 UTC [50348][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:05.339 UTC [50351][startup] LOG: database system was shut down at 2024-03-24 10:50:05 UTC 2024-03-24 10:50:05.341 UTC [50348][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:05.420 UTC [50356][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38222 2024-03-24 10:50:05.424 UTC [50356][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-24 10:50:05.424 UTC [50356][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.428 UTC [50356][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-24 10:50:05.429 UTC [50356][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=38222 2024-03-24 10:50:05.436 UTC [50358][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38238 2024-03-24 10:50:05.439 UTC [50358][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-24 10:50:05.439 UTC [50358][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.440 UTC [50358][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-24 10:50:05.440 UTC [50358][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38238 2024-03-24 10:50:05.446 UTC [50360][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38240 2024-03-24 10:50:05.450 UTC [50360][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-24 10:50:05.450 UTC [50360][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.450 UTC [50360][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-24 10:50:05.451 UTC [50360][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38240 2024-03-24 10:50:05.454 UTC [50348][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:05.454 UTC [50348][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:05.455 UTC [50348][postmaster] LOG: background worker "logical replication launcher" (PID 50354) exited with exit code 1 2024-03-24 10:50:05.455 UTC [50349][checkpointer] LOG: shutting down 2024-03-24 10:50:05.455 UTC [50349][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:05.455 UTC [50349][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F628, redo lsn=0/414F628 2024-03-24 10:50:05.458 UTC [50348][postmaster] LOG: database system is shut down 2024-03-24 10:50:05.571 UTC [50362][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:05.571 UTC [50362][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:05.571 UTC [50362][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:05.573 UTC [50365][startup] LOG: database system was shut down at 2024-03-24 10:50:05 UTC 2024-03-24 10:50:05.576 UTC [50362][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:05.662 UTC [50370][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38252 2024-03-24 10:50:05.665 UTC [50370][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-24 10:50:05.665 UTC [50370][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.670 UTC [50370][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-03-24 10:50:05.671 UTC [50370][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38252 2024-03-24 10:50:05.678 UTC [50372][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38264 2024-03-24 10:50:05.681 UTC [50372][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-24 10:50:05.681 UTC [50372][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.682 UTC [50372][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-24 10:50:05.682 UTC [50372][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38264 2024-03-24 10:50:05.689 UTC [50374][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38278 2024-03-24 10:50:05.694 UTC [50362][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:05.694 UTC [50362][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:05.696 UTC [50362][postmaster] LOG: background worker "logical replication launcher" (PID 50368) exited with exit code 1 2024-03-24 10:50:05.696 UTC [50363][checkpointer] LOG: shutting down 2024-03-24 10:50:05.696 UTC [50363][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:05.696 UTC [50363][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F6A0, redo lsn=0/414F6A0 2024-03-24 10:50:05.698 UTC [50362][postmaster] LOG: database system is shut down 2024-03-24 10:50:05.812 UTC [50376][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:05.812 UTC [50376][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:05.812 UTC [50376][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:05.814 UTC [50379][startup] LOG: database system was shut down at 2024-03-24 10:50:05 UTC 2024-03-24 10:50:05.817 UTC [50376][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:05.902 UTC [50384][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38284 2024-03-24 10:50:05.906 UTC [50384][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-24 10:50:05.906 UTC [50384][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.911 UTC [50384][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-24 10:50:05.911 UTC [50384][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38284 2024-03-24 10:50:05.918 UTC [50386][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38290 2024-03-24 10:50:05.922 UTC [50386][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-24 10:50:05.922 UTC [50386][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.922 UTC [50386][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:05.923 UTC [50386][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38290 2024-03-24 10:50:05.929 UTC [50388][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38298 2024-03-24 10:50:05.932 UTC [50388][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-24 10:50:05.932 UTC [50388][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:05.933 UTC [50388][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-24 10:50:05.933 UTC [50388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38298 2024-03-24 10:50:05.936 UTC [50376][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:05.936 UTC [50376][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:05.937 UTC [50376][postmaster] LOG: background worker "logical replication launcher" (PID 50382) exited with exit code 1 2024-03-24 10:50:05.937 UTC [50377][checkpointer] LOG: shutting down 2024-03-24 10:50:05.937 UTC [50377][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:05.938 UTC [50377][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F718, redo lsn=0/414F718 2024-03-24 10:50:05.940 UTC [50376][postmaster] LOG: database system is shut down 2024-03-24 10:50:06.054 UTC [50390][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:06.054 UTC [50390][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:06.054 UTC [50390][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:06.056 UTC [50393][startup] LOG: database system was shut down at 2024-03-24 10:50:05 UTC 2024-03-24 10:50:06.058 UTC [50390][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:06.144 UTC [50398][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38304 2024-03-24 10:50:06.148 UTC [50398][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-24 10:50:06.148 UTC [50398][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.153 UTC [50398][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-24 10:50:06.153 UTC [50398][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38304 2024-03-24 10:50:06.161 UTC [50400][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38310 2024-03-24 10:50:06.167 UTC [50390][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:06.167 UTC [50390][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:06.168 UTC [50390][postmaster] LOG: background worker "logical replication launcher" (PID 50396) exited with exit code 1 2024-03-24 10:50:06.168 UTC [50391][checkpointer] LOG: shutting down 2024-03-24 10:50:06.168 UTC [50391][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:06.169 UTC [50391][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F790, redo lsn=0/414F790 2024-03-24 10:50:06.171 UTC [50390][postmaster] LOG: database system is shut down 2024-03-24 10:50:06.285 UTC [50402][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:06.285 UTC [50402][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:06.285 UTC [50402][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:06.287 UTC [50405][startup] LOG: database system was shut down at 2024-03-24 10:50:06 UTC 2024-03-24 10:50:06.289 UTC [50402][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:06.375 UTC [50410][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38312 2024-03-24 10:50:06.378 UTC [50410][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-24 10:50:06.392 UTC [50413][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38316 2024-03-24 10:50:06.395 UTC [50413][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-24 10:50:06.395 UTC [50413][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.400 UTC [50413][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-24 10:50:06.401 UTC [50413][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38316 2024-03-24 10:50:06.408 UTC [50415][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38326 2024-03-24 10:50:06.414 UTC [50402][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:06.414 UTC [50402][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:06.416 UTC [50402][postmaster] LOG: background worker "logical replication launcher" (PID 50408) exited with exit code 1 2024-03-24 10:50:06.416 UTC [50403][checkpointer] LOG: shutting down 2024-03-24 10:50:06.416 UTC [50403][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:06.416 UTC [50403][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F808, redo lsn=0/414F808 2024-03-24 10:50:06.419 UTC [50402][postmaster] LOG: database system is shut down 2024-03-24 10:50:06.532 UTC [50417][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:06.532 UTC [50417][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:06.533 UTC [50417][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:06.534 UTC [50420][startup] LOG: database system was shut down at 2024-03-24 10:50:06 UTC 2024-03-24 10:50:06.537 UTC [50417][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:06.623 UTC [50425][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38336 2024-03-24 10:50:06.626 UTC [50425][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-24 10:50:06.626 UTC [50425][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.631 UTC [50425][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-24 10:50:06.631 UTC [50425][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38336 2024-03-24 10:50:06.639 UTC [50427][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38348 2024-03-24 10:50:06.642 UTC [50427][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-24 10:50:06.649 UTC [50429][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38350 2024-03-24 10:50:06.651 UTC [50429][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-24 10:50:06.658 UTC [50431][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38366 2024-03-24 10:50:06.661 UTC [50431][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-24 10:50:06.661 UTC [50431][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.662 UTC [50431][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-24 10:50:06.663 UTC [50431][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=38366 2024-03-24 10:50:06.670 UTC [50433][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38380 2024-03-24 10:50:06.673 UTC [50433][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-24 10:50:06.673 UTC [50433][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-03-24 10:50:06.674 UTC [50433][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-03-24 10:50:06.674 UTC [50433][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=38380 2024-03-24 10:50:06.697 UTC [50438][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38396 2024-03-24 10:50:06.700 UTC [50438][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-24 10:50:06.708 UTC [50440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38406 2024-03-24 10:50:06.713 UTC [50440][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-24 10:50:06.713 UTC [50440][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.718 UTC [50440][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_Win4/client.key$$ 2024-03-24 10:50:06.718 UTC [50440][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=38406 2024-03-24 10:50:06.725 UTC [50442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38418 2024-03-24 10:50:06.730 UTC [50442][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-24 10:50:06.730 UTC [50442][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.730 UTC [50442][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_Win4/client-der.key$$ 2024-03-24 10:50:06.731 UTC [50442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=38418 2024-03-24 10:50:06.737 UTC [50444][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38424 2024-03-24 10:50:06.742 UTC [50444][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-24 10:50:06.742 UTC [50444][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.743 UTC [50444][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_Win4/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-24 10:50:06.743 UTC [50444][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=38424 2024-03-24 10:50:06.750 UTC [50446][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38434 2024-03-24 10:50:06.754 UTC [50446][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-24 10:50:06.754 UTC [50446][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.755 UTC [50446][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_Win4/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-24 10:50:06.755 UTC [50446][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=38434 2024-03-24 10:50:06.762 UTC [50448][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38436 2024-03-24 10:50:06.766 UTC [50448][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-24 10:50:06.766 UTC [50448][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.767 UTC [50448][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_Win4/client.key$$ 2024-03-24 10:50:06.767 UTC [50448][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=certdb host=localhost port=38436 2024-03-24 10:50:06.774 UTC [50450][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38444 2024-03-24 10:50:06.778 UTC [50450][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-24 10:50:06.778 UTC [50450][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.779 UTC [50450][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_Win4/client.key$$ 2024-03-24 10:50:06.779 UTC [50450][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=38444 2024-03-24 10:50:06.786 UTC [50452][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38452 2024-03-24 10:50:06.789 UTC [50452][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-24 10:50:06.796 UTC [50454][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38468 2024-03-24 10:50:06.797 UTC [50454][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-24 10:50:06.803 UTC [50456][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38476 2024-03-24 10:50:06.808 UTC [50456][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-24 10:50:06.808 UTC [50456][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.812 UTC [50456][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_Win4/client-dn.key$$ 2024-03-24 10:50:06.813 UTC [50456][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn host=localhost port=38476 2024-03-24 10:50:06.819 UTC [50458][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38480 2024-03-24 10:50:06.824 UTC [50458][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-24 10:50:06.824 UTC [50458][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.828 UTC [50458][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_Win4/client-dn.key$$ 2024-03-24 10:50:06.829 UTC [50458][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_dn_re host=localhost port=38480 2024-03-24 10:50:06.836 UTC [50460][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38494 2024-03-24 10:50:06.841 UTC [50460][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-24 10:50:06.841 UTC [50460][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.846 UTC [50460][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_Win4/client-dn.key$$ 2024-03-24 10:50:06.846 UTC [50460][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb_cn host=localhost port=38494 2024-03-24 10:50:06.862 UTC [50463][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38508 2024-03-24 10:50:06.866 UTC [50463][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-24 10:50:06.866 UTC [50463][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.867 UTC [50463][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-24 10:50:06.869 UTC [50463][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=38508 2024-03-24 10:50:06.875 UTC [50465][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38510 2024-03-24 10:50:06.876 UTC [50465][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-24 10:50:06.882 UTC [50467][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38512 2024-03-24 10:50:06.887 UTC [50467][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-24 10:50:06.887 UTC [50467][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-24 10:50:06.887 UTC [50467][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-24 10:50:06.887 UTC [50467][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-24 10:50:06.894 UTC [50469][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38528 2024-03-24 10:50:06.898 UTC [50469][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-24 10:50:06.898 UTC [50469][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-24 10:50:06.904 UTC [50471][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38542 2024-03-24 10:50:06.909 UTC [50471][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-24 10:50:06.909 UTC [50471][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.913 UTC [50471][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_Win4/client.key$$ 2024-03-24 10:50:06.914 UTC [50471][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=verifydb host=localhost port=38542 2024-03-24 10:50:06.921 UTC [50473][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38550 2024-03-24 10:50:06.925 UTC [50473][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-24 10:50:06.925 UTC [50473][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-24 10:50:06.925 UTC [50473][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-24 10:50:06.925 UTC [50473][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-24 10:50:06.932 UTC [50475][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38556 2024-03-24 10:50:06.936 UTC [50475][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-24 10:50:06.936 UTC [50475][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:06.937 UTC [50475][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_Win4/client.key$$ 2024-03-24 10:50:06.938 UTC [50475][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=38556 2024-03-24 10:50:06.940 UTC [50417][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:06.940 UTC [50417][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:06.941 UTC [50417][postmaster] LOG: background worker "logical replication launcher" (PID 50423) exited with exit code 1 2024-03-24 10:50:06.941 UTC [50418][checkpointer] LOG: shutting down 2024-03-24 10:50:06.941 UTC [50418][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:06.942 UTC [50418][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F880, redo lsn=0/414F880 2024-03-24 10:50:06.944 UTC [50417][postmaster] LOG: database system is shut down 2024-03-24 10:50:07.058 UTC [50477][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:07.058 UTC [50477][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:07.058 UTC [50477][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:07.060 UTC [50480][startup] LOG: database system was shut down at 2024-03-24 10:50:06 UTC 2024-03-24 10:50:07.063 UTC [50477][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:07.149 UTC [50485][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38566 2024-03-24 10:50:07.154 UTC [50485][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-24 10:50:07.154 UTC [50485][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:07.158 UTC [50485][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_Win4/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-24 10:50:07.159 UTC [50485][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=38566 2024-03-24 10:50:07.166 UTC [50487][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38582 2024-03-24 10:50:07.170 UTC [50487][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-24 10:50:07.170 UTC [50487][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-24 10:50:07.177 UTC [50489][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38590 2024-03-24 10:50:07.181 UTC [50489][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-24 10:50:07.181 UTC [50489][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-24 10:50:07.184 UTC [50477][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:07.184 UTC [50477][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:07.185 UTC [50477][postmaster] LOG: background worker "logical replication launcher" (PID 50483) exited with exit code 1 2024-03-24 10:50:07.185 UTC [50478][checkpointer] LOG: shutting down 2024-03-24 10:50:07.185 UTC [50478][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:07.186 UTC [50478][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F8F8, redo lsn=0/414F8F8 2024-03-24 10:50:07.188 UTC [50477][postmaster] LOG: database system is shut down 2024-03-24 10:50:07.302 UTC [50491][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:07.302 UTC [50491][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:07.302 UTC [50491][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:07.304 UTC [50494][startup] LOG: database system was shut down at 2024-03-24 10:50:07 UTC 2024-03-24 10:50:07.306 UTC [50491][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:07.392 UTC [50499][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38594 2024-03-24 10:50:07.396 UTC [50499][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-24 10:50:07.396 UTC [50499][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-03-24 10:50:07.399 UTC [50491][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:07.399 UTC [50491][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:07.400 UTC [50491][postmaster] LOG: background worker "logical replication launcher" (PID 50497) exited with exit code 1 2024-03-24 10:50:07.400 UTC [50492][checkpointer] LOG: shutting down 2024-03-24 10:50:07.400 UTC [50492][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:07.401 UTC [50492][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F970, redo lsn=0/414F970 2024-03-24 10:50:07.403 UTC [50491][postmaster] LOG: database system is shut down 2024-03-24 10:50:07.517 UTC [50501][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:07.517 UTC [50501][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:07.517 UTC [50501][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:07.519 UTC [50504][startup] LOG: database system was shut down at 2024-03-24 10:50:07 UTC 2024-03-24 10:50:07.522 UTC [50501][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:07.607 UTC [50509][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38610 2024-03-24 10:50:07.612 UTC [50509][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-24 10:50:07.612 UTC [50509][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-24 10:50:07.619 UTC [50511][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38612 2024-03-24 10:50:07.623 UTC [50511][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-24 10:50:07.623 UTC [50511][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-24 10:50:07.626 UTC [50501][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:07.626 UTC [50501][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:07.627 UTC [50501][postmaster] LOG: background worker "logical replication launcher" (PID 50507) exited with exit code 1 2024-03-24 10:50:07.627 UTC [50502][checkpointer] LOG: shutting down 2024-03-24 10:50:07.627 UTC [50502][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:07.628 UTC [50502][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414F9E8, redo lsn=0/414F9E8 2024-03-24 10:50:07.630 UTC [50501][postmaster] LOG: database system is shut down 2024-03-24 10:50:07.744 UTC [50513][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:07.744 UTC [50513][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:07.744 UTC [50513][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:07.746 UTC [50516][startup] LOG: database system was shut down at 2024-03-24 10:50:07 UTC 2024-03-24 10:50:07.748 UTC [50513][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:07.834 UTC [50521][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38618 2024-03-24 10:50:07.835 UTC [50521][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:07.835 UTC [50521][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:07.844 UTC [50523][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38632 2024-03-24 10:50:07.848 UTC [50523][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-24 10:50:07.848 UTC [50523][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:07.854 UTC [50523][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:07.854 UTC [50523][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=38632 2024-03-24 10:50:07.857 UTC [50513][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:07.857 UTC [50513][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:07.858 UTC [50513][postmaster] LOG: background worker "logical replication launcher" (PID 50519) exited with exit code 1 2024-03-24 10:50:07.858 UTC [50514][checkpointer] LOG: shutting down 2024-03-24 10:50:07.858 UTC [50514][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:07.859 UTC [50514][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/414FA60, redo lsn=0/414FA60 2024-03-24 10:50:07.861 UTC [50513][postmaster] LOG: database system is shut down 2024-03-24 10:50:07.975 UTC [50525][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:07.975 UTC [50525][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:07.975 UTC [50525][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:07.977 UTC [50528][startup] LOG: database system was shut down at 2024-03-24 10:50:07 UTC 2024-03-24 10:50:07.979 UTC [50525][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:08.065 UTC [50533][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38644 2024-03-24 10:50:08.066 UTC [50533][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:08.066 UTC [50533][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:08.074 UTC [50535][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38650 2024-03-24 10:50:08.078 UTC [50535][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-24 10:50:08.078 UTC [50535][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:08.083 UTC [50535][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:08.083 UTC [50535][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=38650 2024-03-24 10:50:08.087 UTC [50525][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:08.087 UTC [50525][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:08.089 UTC [50525][postmaster] LOG: background worker "logical replication launcher" (PID 50531) exited with exit code 1 2024-03-24 10:50:08.089 UTC [50526][checkpointer] LOG: shutting down 2024-03-24 10:50:08.089 UTC [50526][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:08.089 UTC [50526][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FAD8, redo lsn=0/414FAD8 2024-03-24 10:50:08.092 UTC [50525][postmaster] LOG: database system is shut down 2024-03-24 10:50:08.205 UTC [50537][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:08.205 UTC [50537][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:08.206 UTC [50537][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:08.208 UTC [50540][startup] LOG: database system was shut down at 2024-03-24 10:50:08 UTC 2024-03-24 10:50:08.210 UTC [50537][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:08.296 UTC [50545][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38662 2024-03-24 10:50:08.297 UTC [50545][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:08.297 UTC [50545][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:08.305 UTC [50547][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38670 2024-03-24 10:50:08.308 UTC [50547][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-24 10:50:08.308 UTC [50547][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:08.313 UTC [50547][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:08.313 UTC [50547][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=38670 2024-03-24 10:50:08.316 UTC [50537][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:08.316 UTC [50537][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:08.317 UTC [50537][postmaster] LOG: background worker "logical replication launcher" (PID 50543) exited with exit code 1 2024-03-24 10:50:08.317 UTC [50538][checkpointer] LOG: shutting down 2024-03-24 10:50:08.317 UTC [50538][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:08.318 UTC [50538][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FB50, redo lsn=0/414FB50 2024-03-24 10:50:08.320 UTC [50537][postmaster] LOG: database system is shut down 2024-03-24 10:50:08.434 UTC [50549][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:08.434 UTC [50549][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:08.434 UTC [50549][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:08.436 UTC [50552][startup] LOG: database system was shut down at 2024-03-24 10:50:08 UTC 2024-03-24 10:50:08.438 UTC [50549][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:08.524 UTC [50557][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38676 2024-03-24 10:50:08.526 UTC [50557][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:08.526 UTC [50557][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:08.534 UTC [50559][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38684 2024-03-24 10:50:08.537 UTC [50559][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-24 10:50:08.537 UTC [50559][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:08.542 UTC [50559][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:08.542 UTC [50559][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=38684 2024-03-24 10:50:08.546 UTC [50549][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:08.546 UTC [50549][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:08.547 UTC [50549][postmaster] LOG: background worker "logical replication launcher" (PID 50555) exited with exit code 1 2024-03-24 10:50:08.547 UTC [50550][checkpointer] LOG: shutting down 2024-03-24 10:50:08.547 UTC [50550][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:08.547 UTC [50550][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FBC8, redo lsn=0/414FBC8 2024-03-24 10:50:08.549 UTC [50549][postmaster] LOG: database system is shut down 2024-03-24 10:50:08.663 UTC [50561][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:08.663 UTC [50561][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:08.663 UTC [50561][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:08.665 UTC [50564][startup] LOG: database system was shut down at 2024-03-24 10:50:08 UTC 2024-03-24 10:50:08.668 UTC [50561][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:08.753 UTC [50569][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=38694 2024-03-24 10:50:08.754 UTC [50569][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:08.754 UTC [50569][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:08.762 UTC [50571][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54802 2024-03-24 10:50:08.766 UTC [50571][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-24 10:50:08.766 UTC [50571][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:08.770 UTC [50571][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:08.771 UTC [50571][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54802 2024-03-24 10:50:08.774 UTC [50561][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:08.774 UTC [50561][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:08.776 UTC [50561][postmaster] LOG: background worker "logical replication launcher" (PID 50567) exited with exit code 1 2024-03-24 10:50:08.776 UTC [50562][checkpointer] LOG: shutting down 2024-03-24 10:50:08.776 UTC [50562][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:08.776 UTC [50562][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FC40, redo lsn=0/414FC40 2024-03-24 10:50:08.778 UTC [50561][postmaster] LOG: database system is shut down 2024-03-24 10:50:08.892 UTC [50573][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:08.892 UTC [50573][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:08.892 UTC [50573][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:08.894 UTC [50576][startup] LOG: database system was shut down at 2024-03-24 10:50:08 UTC 2024-03-24 10:50:08.897 UTC [50573][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:08.982 UTC [50581][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54810 2024-03-24 10:50:08.983 UTC [50581][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:08.983 UTC [50581][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:08.991 UTC [50583][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54814 2024-03-24 10:50:08.994 UTC [50583][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-24 10:50:08.994 UTC [50583][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:08.999 UTC [50583][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:08.999 UTC [50583][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54814 2024-03-24 10:50:09.002 UTC [50573][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:09.002 UTC [50573][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:09.003 UTC [50573][postmaster] LOG: background worker "logical replication launcher" (PID 50579) exited with exit code 1 2024-03-24 10:50:09.003 UTC [50574][checkpointer] LOG: shutting down 2024-03-24 10:50:09.003 UTC [50574][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:09.004 UTC [50574][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FCB8, redo lsn=0/414FCB8 2024-03-24 10:50:09.006 UTC [50573][postmaster] LOG: database system is shut down 2024-03-24 10:50:09.120 UTC [50585][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:09.120 UTC [50585][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:09.120 UTC [50585][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:09.122 UTC [50588][startup] LOG: database system was shut down at 2024-03-24 10:50:08 UTC 2024-03-24 10:50:09.125 UTC [50585][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:09.210 UTC [50593][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54818 2024-03-24 10:50:09.211 UTC [50593][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:09.211 UTC [50593][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:09.218 UTC [50595][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54834 2024-03-24 10:50:09.222 UTC [50595][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-24 10:50:09.222 UTC [50595][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:09.227 UTC [50595][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:09.227 UTC [50595][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54834 2024-03-24 10:50:09.230 UTC [50585][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:09.230 UTC [50585][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:09.231 UTC [50585][postmaster] LOG: background worker "logical replication launcher" (PID 50591) exited with exit code 1 2024-03-24 10:50:09.232 UTC [50586][checkpointer] LOG: shutting down 2024-03-24 10:50:09.232 UTC [50586][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:09.232 UTC [50586][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FD30, redo lsn=0/414FD30 2024-03-24 10:50:09.234 UTC [50585][postmaster] LOG: database system is shut down 2024-03-24 10:50:09.348 UTC [50597][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:09.348 UTC [50597][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:09.348 UTC [50597][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:09.350 UTC [50600][startup] LOG: database system was shut down at 2024-03-24 10:50:09 UTC 2024-03-24 10:50:09.353 UTC [50597][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:09.438 UTC [50605][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54838 2024-03-24 10:50:09.439 UTC [50605][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:09.439 UTC [50605][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:09.447 UTC [50607][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54848 2024-03-24 10:50:09.450 UTC [50607][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-24 10:50:09.450 UTC [50607][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:09.455 UTC [50607][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:09.455 UTC [50607][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=54848 2024-03-24 10:50:09.458 UTC [50597][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:09.458 UTC [50597][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:09.459 UTC [50597][postmaster] LOG: background worker "logical replication launcher" (PID 50603) exited with exit code 1 2024-03-24 10:50:09.459 UTC [50598][checkpointer] LOG: shutting down 2024-03-24 10:50:09.459 UTC [50598][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:09.460 UTC [50598][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FDA8, redo lsn=0/414FDA8 2024-03-24 10:50:09.462 UTC [50597][postmaster] LOG: database system is shut down 2024-03-24 10:50:09.576 UTC [50609][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-24 10:50:09.576 UTC [50609][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54976 2024-03-24 10:50:09.576 UTC [50609][postmaster] LOG: listening on Unix socket "/tmp/YforlhpWvc/.s.PGSQL.54976" 2024-03-24 10:50:09.578 UTC [50612][startup] LOG: database system was shut down at 2024-03-24 10:50:09 UTC 2024-03-24 10:50:09.581 UTC [50609][postmaster] LOG: database system is ready to accept connections 2024-03-24 10:50:09.666 UTC [50617][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54854 2024-03-24 10:50:09.667 UTC [50617][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-24 10:50:09.667 UTC [50617][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-24 10:50:09.674 UTC [50619][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=54856 2024-03-24 10:50:09.678 UTC [50619][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-24 10:50:09.678 UTC [50619][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-24 10:50:09.683 UTC [50619][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-24 10:50:09.683 UTC [50619][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=54856 2024-03-24 10:50:09.686 UTC [50609][postmaster] LOG: received fast shutdown request 2024-03-24 10:50:09.686 UTC [50609][postmaster] LOG: aborting any active transactions 2024-03-24 10:50:09.687 UTC [50609][postmaster] LOG: background worker "logical replication launcher" (PID 50615) exited with exit code 1 2024-03-24 10:50:09.687 UTC [50610][checkpointer] LOG: shutting down 2024-03-24 10:50:09.687 UTC [50610][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-24 10:50:09.688 UTC [50610][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414FE20, redo lsn=0/414FE20 2024-03-24 10:50:09.690 UTC [50609][postmaster] LOG: database system is shut down 2024-03-24 10:50:09.798 UTC [50621][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-24 10:50:09.798 UTC [50621][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:50:09.888](0.203s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"