[13:50:02.444](0.029s) # setting up data directory # Checking port 59853 # Found port 59853 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=59853 host=/tmp/JpWUA3RNuQ Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [13:50:02.454](0.010s) # 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 50845 [13:50:02.750](0.297s) 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 51152 [13:50:03.925](1.174s) # 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-22 13:50:02.636 UTC [50845][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:02.636 UTC [50845][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:02.642 UTC [50854][startup] LOG: database system was shut down at 2024-03-22 13:47:25 UTC 2024-03-22 13:50:02.650 UTC [50845][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:02.736 UTC [50873][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.736 UTC [50873][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-22 13:50:02.736 UTC [50873][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.748 UTC [50873][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-22 13:50:02.752 UTC [50873][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.786 UTC [50896][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.787 UTC [50896][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-22 13:50:02.787 UTC [50896][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.788 UTC [50896][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-22 13:50:02.789 UTC [50896][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.822 UTC [50916][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.823 UTC [50916][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-22 13:50:02.823 UTC [50916][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.824 UTC [50916][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-22 13:50:02.825 UTC [50916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.877 UTC [50944][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.878 UTC [50944][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-22 13:50:02.878 UTC [50944][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.879 UTC [50944][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-22 13:50:02.879 UTC [50944][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.932 UTC [50954][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.933 UTC [50954][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-22 13:50:02.933 UTC [50954][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.936 UTC [50954][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-22 13:50:02.938 UTC [50954][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.970 UTC [50965][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.970 UTC [50965][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-22 13:50:02.970 UTC [50965][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.971 UTC [50965][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-22 13:50:03.007 UTC [50965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.109 UTC [51006][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.110 UTC [51006][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-22 13:50:03.110 UTC [51006][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.111 UTC [51006][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-22 13:50:03.192 UTC [51006][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.225 UTC [51035][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.227 UTC [51035][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-22 13:50:03.227 UTC [51035][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.228 UTC [51035][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-22 13:50:03.266 UTC [51035][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.349 UTC [51051][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.350 UTC [51051][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-22 13:50:03.350 UTC [51051][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.351 UTC [51051][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-22 13:50:03.424 UTC [51051][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.074 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.478 UTC [51073][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.478 UTC [51073][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-22 13:50:03.478 UTC [51073][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.479 UTC [51073][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-22 13:50:03.518 UTC [51073][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.601 UTC [51097][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.601 UTC [51097][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-22 13:50:03.601 UTC [51097][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.602 UTC [51097][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-22 13:50:03.645 UTC [51097][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.723 UTC [50845][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:03.723 UTC [50845][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:03.725 UTC [50845][postmaster] LOG: background worker "logical replication launcher" (PID 50860) exited with exit code 1 2024-03-22 13:50:03.726 UTC [50852][checkpointer] LOG: shutting down 2024-03-22 13:50:03.726 UTC [50852][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:03.772 UTC [50852][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.046 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-22 13:50:03.785 UTC [50845][postmaster] LOG: database system is shut down 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:03.846 UTC [51157][startup] LOG: database system was shut down at 2024-03-22 13:50:03 UTC 2024-03-22 13:50:03.850 UTC [51152][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:03.932 UTC [51152][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:03.932 UTC [51152][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:03.934 UTC [51152][postmaster] LOG: background worker "logical replication launcher" (PID 51160) exited with exit code 1 2024-03-22 13:50:03.936 UTC [51155][checkpointer] LOG: shutting down 2024-03-22 13:50:03.936 UTC [51155][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:03.936 UTC [51155][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-22 13:50:03.939 UTC [51152][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.058 UTC [51216][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-22 13:50:04.058 UTC [51216][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:50:04.135](0.211s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 51236 [13:50:04.239](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-22 13:50:02.636 UTC [50845][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:02.636 UTC [50845][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:02.642 UTC [50854][startup] LOG: database system was shut down at 2024-03-22 13:47:25 UTC 2024-03-22 13:50:02.650 UTC [50845][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:02.736 UTC [50873][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.736 UTC [50873][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-22 13:50:02.736 UTC [50873][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.748 UTC [50873][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-22 13:50:02.752 UTC [50873][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.786 UTC [50896][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.787 UTC [50896][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-22 13:50:02.787 UTC [50896][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.788 UTC [50896][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-22 13:50:02.789 UTC [50896][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.822 UTC [50916][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.823 UTC [50916][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-22 13:50:02.823 UTC [50916][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.824 UTC [50916][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-22 13:50:02.825 UTC [50916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.877 UTC [50944][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.878 UTC [50944][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-22 13:50:02.878 UTC [50944][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.879 UTC [50944][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-22 13:50:02.879 UTC [50944][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.932 UTC [50954][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.933 UTC [50954][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-22 13:50:02.933 UTC [50954][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.936 UTC [50954][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-22 13:50:02.938 UTC [50954][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.970 UTC [50965][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.970 UTC [50965][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-22 13:50:02.970 UTC [50965][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.971 UTC [50965][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-22 13:50:03.007 UTC [50965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.109 UTC [51006][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.110 UTC [51006][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-22 13:50:03.110 UTC [51006][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.111 UTC [51006][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-22 13:50:03.192 UTC [51006][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.225 UTC [51035][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.227 UTC [51035][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-22 13:50:03.227 UTC [51035][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.228 UTC [51035][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-22 13:50:03.266 UTC [51035][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.349 UTC [51051][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.350 UTC [51051][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-22 13:50:03.350 UTC [51051][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.351 UTC [51051][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-22 13:50:03.424 UTC [51051][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.074 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.478 UTC [51073][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.478 UTC [51073][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-22 13:50:03.478 UTC [51073][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.479 UTC [51073][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-22 13:50:03.518 UTC [51073][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.601 UTC [51097][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.601 UTC [51097][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-22 13:50:03.601 UTC [51097][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.602 UTC [51097][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-22 13:50:03.645 UTC [51097][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.723 UTC [50845][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:03.723 UTC [50845][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:03.725 UTC [50845][postmaster] LOG: background worker "logical replication launcher" (PID 50860) exited with exit code 1 2024-03-22 13:50:03.726 UTC [50852][checkpointer] LOG: shutting down 2024-03-22 13:50:03.726 UTC [50852][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:03.772 UTC [50852][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.046 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-22 13:50:03.785 UTC [50845][postmaster] LOG: database system is shut down 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:03.846 UTC [51157][startup] LOG: database system was shut down at 2024-03-22 13:50:03 UTC 2024-03-22 13:50:03.850 UTC [51152][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:03.932 UTC [51152][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:03.932 UTC [51152][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:03.934 UTC [51152][postmaster] LOG: background worker "logical replication launcher" (PID 51160) exited with exit code 1 2024-03-22 13:50:03.936 UTC [51155][checkpointer] LOG: shutting down 2024-03-22 13:50:03.936 UTC [51155][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:03.936 UTC [51155][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-22 13:50:03.939 UTC [51152][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.058 UTC [51216][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-22 13:50:04.058 UTC [51216][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.158 UTC [51236][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:04.158 UTC [51236][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:04.158 UTC [51236][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:04.165 UTC [51242][startup] LOG: database system was shut down at 2024-03-22 13:50:03 UTC 2024-03-22 13:50:04.169 UTC [51236][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:04.242 UTC [51236][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:04.242 UTC [51236][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:04.244 UTC [51236][postmaster] LOG: background worker "logical replication launcher" (PID 51245) exited with exit code 1 2024-03-22 13:50:04.245 UTC [51240][checkpointer] LOG: shutting down 2024-03-22 13:50:04.245 UTC [51240][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:04.246 UTC [51240][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-22 13:50:04.248 UTC [51236][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.365 UTC [51292][postmaster] FATAL: could not set SSL protocol version range 2024-03-22 13:50:04.365 UTC [51292][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-22 13:50:04.365 UTC [51292][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:50:04.446](0.206s) 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 51314 [13:50:04.555](0.109s) ok 5 - restart succeeds with correct SSL protocol bounds [13:50:04.555](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 51360 [13:50:04.770](0.214s) ok 6 - server doesn't accept non-SSL connections [13:50:04.770](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [13:50:04.791](0.021s) ok 8 - connect without server root cert sslmode=require [13:50:04.791](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [13:50:04.802](0.011s) ok 10 - connect without server root cert sslmode=verify-ca [13:50:04.802](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [13:50:04.814](0.011s) ok 12 - connect without server root cert sslmode=verify-full [13:50:04.814](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [13:50:04.829](0.015s) ok 14 - connect with wrong server root cert sslmode=require [13:50:04.829](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [13:50:04.843](0.014s) ok 16 - connect with wrong server root cert sslmode=verify-ca [13:50:04.844](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [13:50:04.858](0.015s) ok 18 - connect with wrong server root cert sslmode=verify-full [13:50:04.859](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [13:50:04.871](0.012s) ok 20 - connect with server CA cert, without root CA [13:50:04.871](0.000s) ok 21 - connect with server CA cert, without root CA: matches [13:50:04.886](0.015s) ok 22 - connect with correct server CA cert file sslmode=require [13:50:04.886](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [13:50:04.902](0.016s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [13:50:04.902](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [13:50:04.928](0.026s) ok 26 - connect with correct server CA cert file sslmode=verify-full [13:50:04.928](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [13:50:04.945](0.017s) ok 28 - cert root file that contains two certificates, order 1 [13:50:04.945](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [13:50:04.962](0.017s) ok 30 - cert root file that contains two certificates, order 2 [13:50:04.962](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [13:50:04.977](0.015s) ok 32 - connect with sslcertmode=disable [13:50:04.978](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [13:50:04.992](0.015s) ok 34 - connect with sslcertmode=allow [13:50:04.992](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [13:50:05.006](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [13:50:05.007](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [13:50:05.022](0.015s) ok 38 - sslcrl option with invalid file name [13:50:05.022](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [13:50:05.038](0.016s) ok 40 - CRL belonging to a different CA [13:50:05.038](0.000s) ok 41 - CRL belonging to a different CA: matches [13:50:05.052](0.014s) ok 42 - directory CRL belonging to a different CA [13:50:05.052](0.000s) ok 43 - directory CRL belonging to a different CA: matches [13:50:05.069](0.017s) ok 44 - CRL with a non-revoked cert [13:50:05.069](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [13:50:05.085](0.016s) ok 46 - directory CRL with a non-revoked cert [13:50:05.085](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [13:50:05.101](0.016s) ok 48 - mismatch between host name and server certificate sslmode=require [13:50:05.101](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [13:50:05.117](0.016s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [13:50:05.117](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [13:50:05.132](0.015s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [13:50:05.132](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 51481 [13:50:05.359](0.227s) ok 54 - IP address in the Common Name [13:50:05.359](0.000s) ok 55 - IP address in the Common Name: no stderr [13:50:05.375](0.016s) ok 56 - mismatch between host name and server certificate IP address [13:50:05.375](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 51508 [13:50:05.602](0.227s) ok 58 - IP address in a dNSName [13:50:05.603](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 51531 [13:50:05.830](0.227s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [13:50:05.830](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [13:50:05.847](0.017s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [13:50:05.847](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [13:50:05.863](0.017s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [13:50:05.864](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [13:50:05.877](0.014s) ok 66 - host name not matching with X.509 Subject Alternative Names [13:50:05.878](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [13:50:05.892](0.014s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [13:50:05.892](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 51561 [13:50:06.116](0.224s) ok 70 - host name matching with a single X.509 Subject Alternative Name [13:50:06.117](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [13:50:06.130](0.014s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [13:50:06.131](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [13:50:06.144](0.013s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [13:50:06.144](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 51586 [13:50:06.373](0.229s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [13:50:06.373](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [13:50:06.391](0.018s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [13:50:06.391](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [13:50:06.406](0.015s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [13:50:06.407](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [13:50:06.423](0.016s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [13:50:06.423](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [13:50:06.439](0.016s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [13:50:06.440](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [13:50:06.457](0.018s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [13:50:06.457](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [13:50:06.472](0.014s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [13:50:06.472](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [13:50:06.486](0.014s) ok 90 - IPv6 host with CIDR mask does not match [13:50:06.486](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 51610 [13:50:06.713](0.227s) ok 92 - certificate with both a CN and SANs 1 [13:50:06.713](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [13:50:06.730](0.017s) ok 94 - certificate with both a CN and SANs 2 [13:50:06.731](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [13:50:06.744](0.014s) ok 96 - certificate with both a CN and SANs ignores CN [13:50:06.745](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 51625 [13:50:06.974](0.229s) ok 98 - certificate with both a CN and IP SANs matches CN [13:50:06.974](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [13:50:06.990](0.016s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [13:50:06.991](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [13:50:07.008](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [13:50:07.008](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 51639 [13:50:07.234](0.226s) ok 104 - certificate with both an IP CN and IP SANs 1 [13:50:07.234](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [13:50:07.253](0.019s) ok 106 - certificate with both an IP CN and IP SANs 2 [13:50:07.254](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [13:50:07.268](0.014s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [13:50:07.268](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 51653 [13:50:07.494](0.226s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [13:50:07.495](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [13:50:07.511](0.017s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [13:50:07.512](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [13:50:07.528](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [13:50:07.529](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 51667 [13:50:07.755](0.226s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [13:50:07.755](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [13:50:07.771](0.015s) ok 118 - server certificate without CN or SANs sslmode=verify-full [13:50:07.771](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 51679 [13:50:07.990](0.219s) ok 120 - sslrootcert=system does not connect with private CA [13:50:07.990](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [13:50:07.998](0.008s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [13:50:07.998](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [13:50:08.018](0.020s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [13:50:08.019](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [13:50:08.033](0.015s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [13:50:08.034](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 51696 [13:50:08.260](0.227s) ok 128 - connects without client-side CRL [13:50:08.261](0.000s) ok 129 - connects without client-side CRL: no stderr [13:50:08.276](0.015s) ok 130 - does not connect with client-side CRL file [13:50:08.276](0.000s) ok 131 - does not connect with client-side CRL file: matches [13:50:08.291](0.015s) ok 132 - does not connect with client-side CRL directory [13:50:08.291](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [13:50:08.309](0.018s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [13:50:08.309](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [13:50:08.309](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [13:50:08.326](0.017s) ok 137 - connection success with correct range of TLS protocol versions [13:50:08.327](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [13:50:08.335](0.009s) ok 139 - connection failure with incorrect range of TLS protocol versions [13:50:08.336](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [13:50:08.344](0.008s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [13:50:08.344](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [13:50:08.353](0.009s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [13:50:08.353](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [13:50:08.353](0.000s) # running server tests [13:50:08.367](0.014s) ok 145 - certificate authorization fails without client cert [13:50:08.367](0.000s) ok 146 - certificate authorization fails without client cert: matches [13:50:08.390](0.022s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [13:50:08.390](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [13:50:08.409](0.019s) ok 149 - certificate authorization succeeds with correct client cert in DER format [13:50:08.409](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [13:50:08.429](0.020s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [13:50:08.430](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [13:50:08.450](0.020s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [13:50:08.450](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [13:50:08.472](0.022s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [13:50:08.472](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [13:50:08.492](0.020s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [13:50:08.492](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [13:50:08.508](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [13:50:08.508](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [13:50:08.521](0.012s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [13:50:08.521](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [13:50:08.543](0.022s) ok 163 - certificate authorization succeeds with DN mapping [13:50:08.544](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [13:50:08.544](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [13:50:08.567](0.023s) ok 166 - certificate authorization succeeds with DN regex mapping [13:50:08.567](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [13:50:08.592](0.024s) ok 168 - certificate authorization succeeds with CN mapping [13:50:08.592](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [13:50:08.592](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [13:50:08.592](0.000s) not ok 171 # TODO & SKIP Need Pty support [13:50:08.593](0.000s) not ok 172 # TODO & SKIP Need Pty support [13:50:08.593](0.000s) not ok 173 # TODO & SKIP Need Pty support [13:50:08.593](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_THuR/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [13:50:08.628](0.035s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [13:50:08.628](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [13:50:08.628](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [13:50:08.640](0.012s) ok 178 - certificate authorization fails because of file permissions [13:50:08.640](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [13:50:08.659](0.019s) ok 180 - certificate authorization fails with client cert belonging to another user [13:50:08.659](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [13:50:08.675](0.016s) ok 182 - certificate authorization fails with revoked client cert [13:50:08.675](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [13:50:08.676](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [13:50:08.700](0.024s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [13:50:08.700](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [13:50:08.701](0.001s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [13:50:08.718](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [13:50:08.719](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [13:50:08.719](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [13:50:08.735](0.016s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [13:50:08.735](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [13:50:08.736](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 51756 [13:50:08.964](0.228s) ok 194 - intermediate client certificate is provided by client [13:50:08.964](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [13:50:08.981](0.017s) ok 196 - intermediate client certificate is missing [13:50:08.981](0.000s) ok 197 - intermediate client certificate is missing: matches [13:50:08.998](0.017s) ok 198 - logged client certificate Subjects are truncated if they're too long [13:50:08.998](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 51770 [13:50:09.218](0.220s) ok 200 - intermediate client certificate is untrusted [13:50:09.219](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 51780 [13:50:09.440](0.222s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [13:50:09.441](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [13:50:09.458](0.018s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [13:50:09.458](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 51792 [13:50:09.678](0.220s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [13:50:09.678](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [13:50:09.679](0.000s) # got: '2' # expected: '0' [13:50:09.679](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:50:09.679](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [13:50:09.679](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 59853 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: '' [13:50:09.701](0.022s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [13:50:09.701](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 51804 [13:50:09.920](0.219s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:09.941](0.021s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [13:50:09.942](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 51816 [13:50:10.160](0.219s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:10.182](0.022s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [13:50:10.183](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 51828 [13:50:10.402](0.219s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [13:50:10.423](0.021s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [13:50:10.423](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 51840 [13:50:10.642](0.219s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [13:50:10.642](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [13:50:10.642](0.000s) # got: '2' # expected: '0' [13:50:10.643](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:50:10.643](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [13:50:10.643](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 59853 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: '' [13:50:10.666](0.023s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [13:50:10.666](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 51852 [13:50:10.884](0.218s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:10.906](0.022s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [13:50:10.906](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 51864 [13:50:11.124](0.218s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:11.146](0.022s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [13:50:11.146](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 51876 [13:50:11.366](0.219s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [13:50:11.388](0.022s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [13:50:11.388](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 51888 [13:50:11.606](0.218s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [13:50:11.626](0.021s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [13:50:11.626](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-22 13:50:02.636 UTC [50845][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:02.636 UTC [50845][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:02.642 UTC [50854][startup] LOG: database system was shut down at 2024-03-22 13:47:25 UTC 2024-03-22 13:50:02.650 UTC [50845][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:02.736 UTC [50873][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.736 UTC [50873][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-22 13:50:02.736 UTC [50873][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.748 UTC [50873][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-22 13:50:02.752 UTC [50873][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.786 UTC [50896][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.787 UTC [50896][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-22 13:50:02.787 UTC [50896][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.788 UTC [50896][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-22 13:50:02.789 UTC [50896][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.822 UTC [50916][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.823 UTC [50916][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-22 13:50:02.823 UTC [50916][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.824 UTC [50916][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-22 13:50:02.825 UTC [50916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.877 UTC [50944][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.878 UTC [50944][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-22 13:50:02.878 UTC [50944][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.879 UTC [50944][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-22 13:50:02.879 UTC [50944][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.932 UTC [50954][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.933 UTC [50954][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-22 13:50:02.933 UTC [50954][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.936 UTC [50954][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-22 13:50:02.938 UTC [50954][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-22 13:50:02.970 UTC [50965][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:02.970 UTC [50965][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-22 13:50:02.970 UTC [50965][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:02.971 UTC [50965][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-22 13:50:03.007 UTC [50965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.109 UTC [51006][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.110 UTC [51006][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-22 13:50:03.110 UTC [51006][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.111 UTC [51006][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-22 13:50:03.192 UTC [51006][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.225 UTC [51035][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.227 UTC [51035][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-22 13:50:03.227 UTC [51035][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.228 UTC [51035][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-22 13:50:03.266 UTC [51035][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.349 UTC [51051][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.350 UTC [51051][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-22 13:50:03.350 UTC [51051][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.351 UTC [51051][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-22 13:50:03.424 UTC [51051][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.074 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.478 UTC [51073][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.478 UTC [51073][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-22 13:50:03.478 UTC [51073][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.479 UTC [51073][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-22 13:50:03.518 UTC [51073][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.601 UTC [51097][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:03.601 UTC [51097][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-22 13:50:03.601 UTC [51097][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:03.602 UTC [51097][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-22 13:50:03.645 UTC [51097][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local] 2024-03-22 13:50:03.723 UTC [50845][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:03.723 UTC [50845][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:03.725 UTC [50845][postmaster] LOG: background worker "logical replication launcher" (PID 50860) exited with exit code 1 2024-03-22 13:50:03.726 UTC [50852][checkpointer] LOG: shutting down 2024-03-22 13:50:03.726 UTC [50852][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:03.772 UTC [50852][checkpointer] LOG: checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.046 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-22 13:50:03.785 UTC [50845][postmaster] LOG: database system is shut down 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:03.844 UTC [51152][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:03.846 UTC [51157][startup] LOG: database system was shut down at 2024-03-22 13:50:03 UTC 2024-03-22 13:50:03.850 UTC [51152][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:03.932 UTC [51152][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:03.932 UTC [51152][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:03.934 UTC [51152][postmaster] LOG: background worker "logical replication launcher" (PID 51160) exited with exit code 1 2024-03-22 13:50:03.936 UTC [51155][checkpointer] LOG: shutting down 2024-03-22 13:50:03.936 UTC [51155][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:03.936 UTC [51155][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-22 13:50:03.939 UTC [51152][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.058 UTC [51216][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-22 13:50:04.058 UTC [51216][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.158 UTC [51236][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:04.158 UTC [51236][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:04.158 UTC [51236][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:04.165 UTC [51242][startup] LOG: database system was shut down at 2024-03-22 13:50:03 UTC 2024-03-22 13:50:04.169 UTC [51236][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:04.242 UTC [51236][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:04.242 UTC [51236][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:04.244 UTC [51236][postmaster] LOG: background worker "logical replication launcher" (PID 51245) exited with exit code 1 2024-03-22 13:50:04.245 UTC [51240][checkpointer] LOG: shutting down 2024-03-22 13:50:04.245 UTC [51240][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:04.246 UTC [51240][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-22 13:50:04.248 UTC [51236][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.365 UTC [51292][postmaster] FATAL: could not set SSL protocol version range 2024-03-22 13:50:04.365 UTC [51292][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-22 13:50:04.365 UTC [51292][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.475 UTC [51314][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:04.475 UTC [51314][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:04.475 UTC [51314][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:04.479 UTC [51323][startup] LOG: database system was shut down at 2024-03-22 13:50:04 UTC 2024-03-22 13:50:04.482 UTC [51314][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:04.559 UTC [51314][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:04.559 UTC [51314][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:04.562 UTC [51314][postmaster] LOG: background worker "logical replication launcher" (PID 51330) exited with exit code 1 2024-03-22 13:50:04.563 UTC [51320][checkpointer] LOG: shutting down 2024-03-22 13:50:04.563 UTC [51320][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:04.563 UTC [51320][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-22 13:50:04.567 UTC [51314][postmaster] LOG: database system is shut down 2024-03-22 13:50:04.678 UTC [51360][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:04.678 UTC [51360][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:04.678 UTC [51360][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:04.681 UTC [51366][startup] LOG: database system was shut down at 2024-03-22 13:50:04 UTC 2024-03-22 13:50:04.685 UTC [51360][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:04.767 UTC [51389][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36472 2024-03-22 13:50:04.768 UTC [51389][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-22 13:50:04.768 UTC [51389][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-22 13:50:04.779 UTC [51392][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36488 2024-03-22 13:50:04.783 UTC [51392][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-22 13:50:04.783 UTC [51392][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-22 13:50:04.788 UTC [51392][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-22 13:50:04.789 UTC [51392][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=36488 2024-03-22 13:50:04.800 UTC [51398][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36504 2024-03-22 13:50:04.801 UTC [51398][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:04.811 UTC [51403][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36514 2024-03-22 13:50:04.812 UTC [51403][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:04.823 UTC [51406][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36528 2024-03-22 13:50:04.827 UTC [51406][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:04.838 UTC [51410][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36540 2024-03-22 13:50:04.841 UTC [51410][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:04.854 UTC [51415][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36546 2024-03-22 13:50:04.858 UTC [51415][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:04.867 UTC [51419][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36550 2024-03-22 13:50:04.872 UTC [51419][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:04.879 UTC [51422][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36560 2024-03-22 13:50:04.883 UTC [51422][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-22 13:50:04.883 UTC [51422][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-22 13:50:04.884 UTC [51422][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-22 13:50:04.887 UTC [51422][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=36560 2024-03-22 13:50:04.895 UTC [51426][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36570 2024-03-22 13:50:04.899 UTC [51426][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-22 13:50:04.899 UTC [51426][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-22 13:50:04.900 UTC [51426][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-22 13:50:04.903 UTC [51426][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=36570 2024-03-22 13:50:04.921 UTC [51431][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36574 2024-03-22 13:50:04.925 UTC [51431][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-22 13:50:04.925 UTC [51431][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-22 13:50:04.926 UTC [51431][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-22 13:50:04.927 UTC [51431][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=36574 2024-03-22 13:50:04.936 UTC [51438][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36576 2024-03-22 13:50:04.942 UTC [51438][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-22 13:50:04.942 UTC [51438][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-22 13:50:04.943 UTC [51438][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-22 13:50:04.943 UTC [51438][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36576 2024-03-22 13:50:04.954 UTC [51443][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36584 2024-03-22 13:50:04.959 UTC [51443][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-22 13:50:04.959 UTC [51443][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-22 13:50:04.960 UTC [51443][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-22 13:50:04.961 UTC [51443][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36584 2024-03-22 13:50:04.970 UTC [51445][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36592 2024-03-22 13:50:04.975 UTC [51445][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-22 13:50:04.975 UTC [51445][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-22 13:50:04.976 UTC [51445][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-22 13:50:04.976 UTC [51445][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36592 2024-03-22 13:50:04.985 UTC [51447][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36604 2024-03-22 13:50:04.990 UTC [51447][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-22 13:50:04.990 UTC [51447][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-22 13:50:04.991 UTC [51447][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-22 13:50:04.991 UTC [51447][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36604 2024-03-22 13:50:05.000 UTC [51450][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36618 2024-03-22 13:50:05.004 UTC [51450][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-22 13:50:05.004 UTC [51450][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-22 13:50:05.005 UTC [51450][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=36618 2024-03-22 13:50:05.014 UTC [51454][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36632 2024-03-22 13:50:05.019 UTC [51454][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-22 13:50:05.019 UTC [51454][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-22 13:50:05.020 UTC [51454][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-22 13:50:05.021 UTC [51454][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36632 2024-03-22 13:50:05.031 UTC [51458][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36644 2024-03-22 13:50:05.037 UTC [51458][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:05.046 UTC [51460][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36646 2024-03-22 13:50:05.050 UTC [51460][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:05.060 UTC [51462][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36658 2024-03-22 13:50:05.065 UTC [51462][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-22 13:50:05.065 UTC [51462][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-22 13:50:05.066 UTC [51462][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-22 13:50:05.067 UTC [51462][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36658 2024-03-22 13:50:05.076 UTC [51464][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36660 2024-03-22 13:50:05.081 UTC [51464][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-22 13:50:05.081 UTC [51464][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-22 13:50:05.082 UTC [51464][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-22 13:50:05.083 UTC [51464][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36660 2024-03-22 13:50:05.093 UTC [51466][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36668 2024-03-22 13:50:05.097 UTC [51466][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-22 13:50:05.097 UTC [51466][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-22 13:50:05.098 UTC [51466][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-22 13:50:05.099 UTC [51466][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36668 2024-03-22 13:50:05.109 UTC [51468][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36670 2024-03-22 13:50:05.114 UTC [51468][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-22 13:50:05.114 UTC [51468][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-22 13:50:05.115 UTC [51468][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-22 13:50:05.115 UTC [51468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36670 2024-03-22 13:50:05.126 UTC [51470][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36684 2024-03-22 13:50:05.135 UTC [51360][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:05.135 UTC [51360][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:05.137 UTC [51360][postmaster] LOG: background worker "logical replication launcher" (PID 51370) exited with exit code 1 2024-03-22 13:50:05.138 UTC [51364][checkpointer] LOG: shutting down 2024-03-22 13:50:05.138 UTC [51364][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:05.138 UTC [51364][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-22 13:50:05.142 UTC [51360][postmaster] LOG: database system is shut down 2024-03-22 13:50:05.257 UTC [51481][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:05.257 UTC [51481][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:05.257 UTC [51481][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:05.259 UTC [51487][startup] LOG: database system was shut down at 2024-03-22 13:50:05 UTC 2024-03-22 13:50:05.263 UTC [51481][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:05.345 UTC [51494][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36692 2024-03-22 13:50:05.351 UTC [51494][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-22 13:50:05.351 UTC [51494][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-22 13:50:05.356 UTC [51494][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-22 13:50:05.357 UTC [51494][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=36692 2024-03-22 13:50:05.368 UTC [51497][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36708 2024-03-22 13:50:05.378 UTC [51481][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:05.378 UTC [51481][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:05.381 UTC [51481][postmaster] LOG: background worker "logical replication launcher" (PID 51490) exited with exit code 1 2024-03-22 13:50:05.381 UTC [51485][checkpointer] LOG: shutting down 2024-03-22 13:50:05.381 UTC [51485][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:05.382 UTC [51485][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-22 13:50:05.385 UTC [51481][postmaster] LOG: database system is shut down 2024-03-22 13:50:05.498 UTC [51508][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:05.498 UTC [51508][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:05.498 UTC [51508][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:05.500 UTC [51513][startup] LOG: database system was shut down at 2024-03-22 13:50:05 UTC 2024-03-22 13:50:05.503 UTC [51508][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:05.589 UTC [51519][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36724 2024-03-22 13:50:05.594 UTC [51519][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-22 13:50:05.594 UTC [51519][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-22 13:50:05.600 UTC [51519][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-22 13:50:05.601 UTC [51519][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=36724 2024-03-22 13:50:05.606 UTC [51508][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:05.606 UTC [51508][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:05.608 UTC [51508][postmaster] LOG: background worker "logical replication launcher" (PID 51516) exited with exit code 1 2024-03-22 13:50:05.608 UTC [51511][checkpointer] LOG: shutting down 2024-03-22 13:50:05.608 UTC [51511][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:05.608 UTC [51511][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-22 13:50:05.612 UTC [51508][postmaster] LOG: database system is shut down 2024-03-22 13:50:05.726 UTC [51531][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:05.726 UTC [51531][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:05.726 UTC [51531][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:05.729 UTC [51535][startup] LOG: database system was shut down at 2024-03-22 13:50:05 UTC 2024-03-22 13:50:05.732 UTC [51531][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:05.817 UTC [51541][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36730 2024-03-22 13:50:05.822 UTC [51541][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-22 13:50:05.822 UTC [51541][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-22 13:50:05.827 UTC [51541][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-22 13:50:05.828 UTC [51541][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=36730 2024-03-22 13:50:05.838 UTC [51544][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36732 2024-03-22 13:50:05.843 UTC [51544][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-22 13:50:05.843 UTC [51544][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-22 13:50:05.845 UTC [51544][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-22 13:50:05.845 UTC [51544][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36732 2024-03-22 13:50:05.855 UTC [51552][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36748 2024-03-22 13:50:05.860 UTC [51552][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-22 13:50:05.860 UTC [51552][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-22 13:50:05.861 UTC [51552][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-22 13:50:05.862 UTC [51552][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36748 2024-03-22 13:50:05.872 UTC [51554][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36752 2024-03-22 13:50:05.886 UTC [51556][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36758 2024-03-22 13:50:05.895 UTC [51531][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:05.895 UTC [51531][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:05.897 UTC [51531][postmaster] LOG: background worker "logical replication launcher" (PID 51538) exited with exit code 1 2024-03-22 13:50:05.899 UTC [51533][checkpointer] LOG: shutting down 2024-03-22 13:50:05.899 UTC [51533][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:05.899 UTC [51533][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-22 13:50:05.903 UTC [51531][postmaster] LOG: database system is shut down 2024-03-22 13:50:06.016 UTC [51561][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:06.016 UTC [51561][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:06.016 UTC [51561][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:06.018 UTC [51564][startup] LOG: database system was shut down at 2024-03-22 13:50:05 UTC 2024-03-22 13:50:06.022 UTC [51561][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:06.105 UTC [51576][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36770 2024-03-22 13:50:06.109 UTC [51576][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-22 13:50:06.109 UTC [51576][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-22 13:50:06.115 UTC [51576][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-22 13:50:06.115 UTC [51576][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=36770 2024-03-22 13:50:06.125 UTC [51578][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36778 2024-03-22 13:50:06.138 UTC [51580][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36792 2024-03-22 13:50:06.150 UTC [51561][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:06.150 UTC [51561][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:06.152 UTC [51561][postmaster] LOG: background worker "logical replication launcher" (PID 51567) exited with exit code 1 2024-03-22 13:50:06.152 UTC [51562][checkpointer] LOG: shutting down 2024-03-22 13:50:06.152 UTC [51562][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:06.153 UTC [51562][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-22 13:50:06.156 UTC [51561][postmaster] LOG: database system is shut down 2024-03-22 13:50:06.271 UTC [51586][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:06.271 UTC [51586][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:06.271 UTC [51586][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:06.273 UTC [51589][startup] LOG: database system was shut down at 2024-03-22 13:50:06 UTC 2024-03-22 13:50:06.276 UTC [51586][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:06.360 UTC [51594][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36796 2024-03-22 13:50:06.365 UTC [51594][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-22 13:50:06.365 UTC [51594][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-22 13:50:06.370 UTC [51594][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-22 13:50:06.371 UTC [51594][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=36796 2024-03-22 13:50:06.382 UTC [51596][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36802 2024-03-22 13:50:06.387 UTC [51596][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-22 13:50:06.387 UTC [51596][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-22 13:50:06.388 UTC [51596][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-22 13:50:06.389 UTC [51596][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=36802 2024-03-22 13:50:06.400 UTC [51598][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36804 2024-03-22 13:50:06.415 UTC [51600][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36810 2024-03-22 13:50:06.420 UTC [51600][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-22 13:50:06.420 UTC [51600][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-22 13:50:06.421 UTC [51600][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-22 13:50:06.422 UTC [51600][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36810 2024-03-22 13:50:06.431 UTC [51602][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36820 2024-03-22 13:50:06.437 UTC [51602][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-22 13:50:06.437 UTC [51602][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-22 13:50:06.438 UTC [51602][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-22 13:50:06.438 UTC [51602][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36820 2024-03-22 13:50:06.449 UTC [51604][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36828 2024-03-22 13:50:06.454 UTC [51604][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-22 13:50:06.454 UTC [51604][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-22 13:50:06.455 UTC [51604][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-22 13:50:06.456 UTC [51604][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=36828 2024-03-22 13:50:06.466 UTC [51606][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36834 2024-03-22 13:50:06.480 UTC [51608][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36838 2024-03-22 13:50:06.488 UTC [51586][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:06.488 UTC [51586][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:06.490 UTC [51586][postmaster] LOG: background worker "logical replication launcher" (PID 51592) exited with exit code 1 2024-03-22 13:50:06.490 UTC [51587][checkpointer] LOG: shutting down 2024-03-22 13:50:06.490 UTC [51587][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:06.490 UTC [51587][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-22 13:50:06.493 UTC [51586][postmaster] LOG: database system is shut down 2024-03-22 13:50:06.608 UTC [51610][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:06.608 UTC [51610][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:06.608 UTC [51610][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:06.611 UTC [51613][startup] LOG: database system was shut down at 2024-03-22 13:50:06 UTC 2024-03-22 13:50:06.614 UTC [51610][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:06.700 UTC [51618][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=36844 2024-03-22 13:50:06.705 UTC [51618][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-22 13:50:06.705 UTC [51618][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-22 13:50:06.711 UTC [51618][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-22 13:50:06.711 UTC [51618][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=36844 2024-03-22 13:50:06.722 UTC [51620][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58762 2024-03-22 13:50:06.727 UTC [51620][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-22 13:50:06.727 UTC [51620][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-22 13:50:06.728 UTC [51620][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-22 13:50:06.729 UTC [51620][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=58762 2024-03-22 13:50:06.739 UTC [51622][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58774 2024-03-22 13:50:06.751 UTC [51610][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:06.751 UTC [51610][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:06.753 UTC [51610][postmaster] LOG: background worker "logical replication launcher" (PID 51616) exited with exit code 1 2024-03-22 13:50:06.753 UTC [51611][checkpointer] LOG: shutting down 2024-03-22 13:50:06.753 UTC [51611][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:06.753 UTC [51611][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-22 13:50:06.757 UTC [51610][postmaster] LOG: database system is shut down 2024-03-22 13:50:06.871 UTC [51625][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:06.871 UTC [51625][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:06.871 UTC [51625][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:06.873 UTC [51628][startup] LOG: database system was shut down at 2024-03-22 13:50:06 UTC 2024-03-22 13:50:06.877 UTC [51625][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:06.961 UTC [51633][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58782 2024-03-22 13:50:06.966 UTC [51633][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-22 13:50:06.966 UTC [51633][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-22 13:50:06.972 UTC [51633][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-22 13:50:06.972 UTC [51633][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=58782 2024-03-22 13:50:06.982 UTC [51635][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58792 2024-03-22 13:50:06.987 UTC [51635][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-22 13:50:06.987 UTC [51635][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-22 13:50:06.988 UTC [51635][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-22 13:50:06.989 UTC [51635][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=58792 2024-03-22 13:50:06.999 UTC [51637][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58808 2024-03-22 13:50:07.004 UTC [51637][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-22 13:50:07.004 UTC [51637][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-22 13:50:07.005 UTC [51637][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-22 13:50:07.006 UTC [51637][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=58808 2024-03-22 13:50:07.011 UTC [51625][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:07.011 UTC [51625][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:07.013 UTC [51625][postmaster] LOG: background worker "logical replication launcher" (PID 51631) exited with exit code 1 2024-03-22 13:50:07.013 UTC [51626][checkpointer] LOG: shutting down 2024-03-22 13:50:07.013 UTC [51626][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:07.014 UTC [51626][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-22 13:50:07.017 UTC [51625][postmaster] LOG: database system is shut down 2024-03-22 13:50:07.132 UTC [51639][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:07.132 UTC [51639][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:07.132 UTC [51639][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:07.134 UTC [51642][startup] LOG: database system was shut down at 2024-03-22 13:50:07 UTC 2024-03-22 13:50:07.138 UTC [51639][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:07.221 UTC [51647][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58820 2024-03-22 13:50:07.226 UTC [51647][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-22 13:50:07.226 UTC [51647][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-22 13:50:07.232 UTC [51647][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-22 13:50:07.232 UTC [51647][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=58820 2024-03-22 13:50:07.244 UTC [51649][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58828 2024-03-22 13:50:07.250 UTC [51649][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-22 13:50:07.250 UTC [51649][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-22 13:50:07.251 UTC [51649][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-22 13:50:07.252 UTC [51649][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=58828 2024-03-22 13:50:07.262 UTC [51651][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58836 2024-03-22 13:50:07.271 UTC [51639][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:07.271 UTC [51639][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:07.273 UTC [51639][postmaster] LOG: background worker "logical replication launcher" (PID 51645) exited with exit code 1 2024-03-22 13:50:07.273 UTC [51640][checkpointer] LOG: shutting down 2024-03-22 13:50:07.273 UTC [51640][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:07.274 UTC [51640][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-22 13:50:07.277 UTC [51639][postmaster] LOG: database system is shut down 2024-03-22 13:50:07.392 UTC [51653][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:07.392 UTC [51653][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:07.392 UTC [51653][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:07.395 UTC [51656][startup] LOG: database system was shut down at 2024-03-22 13:50:07 UTC 2024-03-22 13:50:07.398 UTC [51653][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:07.482 UTC [51661][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58850 2024-03-22 13:50:07.487 UTC [51661][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-22 13:50:07.487 UTC [51661][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-22 13:50:07.492 UTC [51661][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-22 13:50:07.492 UTC [51661][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=58850 2024-03-22 13:50:07.503 UTC [51663][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58856 2024-03-22 13:50:07.508 UTC [51663][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-22 13:50:07.508 UTC [51663][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-22 13:50:07.509 UTC [51663][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-22 13:50:07.510 UTC [51663][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=58856 2024-03-22 13:50:07.520 UTC [51665][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58870 2024-03-22 13:50:07.525 UTC [51665][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-22 13:50:07.525 UTC [51665][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-22 13:50:07.526 UTC [51665][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-22 13:50:07.527 UTC [51665][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=58870 2024-03-22 13:50:07.532 UTC [51653][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:07.532 UTC [51653][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:07.533 UTC [51653][postmaster] LOG: background worker "logical replication launcher" (PID 51659) exited with exit code 1 2024-03-22 13:50:07.534 UTC [51654][checkpointer] LOG: shutting down 2024-03-22 13:50:07.534 UTC [51654][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:07.534 UTC [51654][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-22 13:50:07.538 UTC [51653][postmaster] LOG: database system is shut down 2024-03-22 13:50:07.653 UTC [51667][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:07.653 UTC [51667][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:07.653 UTC [51667][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:07.656 UTC [51670][startup] LOG: database system was shut down at 2024-03-22 13:50:07 UTC 2024-03-22 13:50:07.660 UTC [51667][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:07.742 UTC [51675][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58884 2024-03-22 13:50:07.747 UTC [51675][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-22 13:50:07.747 UTC [51675][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-22 13:50:07.753 UTC [51675][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-22 13:50:07.753 UTC [51675][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=58884 2024-03-22 13:50:07.764 UTC [51677][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58886 2024-03-22 13:50:07.774 UTC [51667][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:07.774 UTC [51667][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:07.776 UTC [51667][postmaster] LOG: background worker "logical replication launcher" (PID 51673) exited with exit code 1 2024-03-22 13:50:07.776 UTC [51668][checkpointer] LOG: shutting down 2024-03-22 13:50:07.776 UTC [51668][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:07.777 UTC [51668][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-22 13:50:07.781 UTC [51667][postmaster] LOG: database system is shut down 2024-03-22 13:50:07.896 UTC [51679][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:07.896 UTC [51679][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:07.896 UTC [51679][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:07.899 UTC [51682][startup] LOG: database system was shut down at 2024-03-22 13:50:07 UTC 2024-03-22 13:50:07.903 UTC [51679][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:07.984 UTC [51687][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58898 2024-03-22 13:50:07.988 UTC [51687][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:08.006 UTC [51690][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58912 2024-03-22 13:50:08.010 UTC [51690][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-22 13:50:08.010 UTC [51690][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-22 13:50:08.016 UTC [51690][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-22 13:50:08.016 UTC [51690][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=58912 2024-03-22 13:50:08.027 UTC [51692][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58916 2024-03-22 13:50:08.037 UTC [51679][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:08.037 UTC [51679][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:08.038 UTC [51679][postmaster] LOG: background worker "logical replication launcher" (PID 51685) exited with exit code 1 2024-03-22 13:50:08.038 UTC [51680][checkpointer] LOG: shutting down 2024-03-22 13:50:08.038 UTC [51680][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:08.039 UTC [51680][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-22 13:50:08.042 UTC [51679][postmaster] LOG: database system is shut down 2024-03-22 13:50:08.158 UTC [51696][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:08.158 UTC [51696][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:08.158 UTC [51696][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:08.161 UTC [51699][startup] LOG: database system was shut down at 2024-03-22 13:50:08 UTC 2024-03-22 13:50:08.165 UTC [51696][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:08.247 UTC [51704][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58922 2024-03-22 13:50:08.252 UTC [51704][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-22 13:50:08.252 UTC [51704][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-22 13:50:08.258 UTC [51704][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-22 13:50:08.258 UTC [51704][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=58922 2024-03-22 13:50:08.270 UTC [51706][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58924 2024-03-22 13:50:08.274 UTC [51706][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-22 13:50:08.286 UTC [51708][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58936 2024-03-22 13:50:08.289 UTC [51708][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-22 13:50:08.300 UTC [51710][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58946 2024-03-22 13:50:08.305 UTC [51710][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-22 13:50:08.305 UTC [51710][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-22 13:50:08.306 UTC [51710][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-22 13:50:08.307 UTC [51710][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=58946 2024-03-22 13:50:08.318 UTC [51712][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58962 2024-03-22 13:50:08.323 UTC [51712][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-22 13:50:08.323 UTC [51712][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-22 13:50:08.324 UTC [51712][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-22 13:50:08.325 UTC [51712][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=58962 2024-03-22 13:50:08.361 UTC [51717][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58972 2024-03-22 13:50:08.365 UTC [51717][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-22 13:50:08.375 UTC [51719][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58986 2024-03-22 13:50:08.382 UTC [51719][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-22 13:50:08.382 UTC [51719][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-22 13:50:08.387 UTC [51719][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_THuR/client.key$$ 2024-03-22 13:50:08.388 UTC [51719][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb host=localhost port=58986 2024-03-22 13:50:08.399 UTC [51721][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=58994 2024-03-22 13:50:08.406 UTC [51721][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-22 13:50:08.406 UTC [51721][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-22 13:50:08.407 UTC [51721][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_THuR/client-der.key$$ 2024-03-22 13:50:08.407 UTC [51721][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=58994 2024-03-22 13:50:08.418 UTC [51723][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59000 2024-03-22 13:50:08.426 UTC [51723][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-22 13:50:08.426 UTC [51723][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-22 13:50:08.427 UTC [51723][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_THuR/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-22 13:50:08.428 UTC [51723][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=59000 2024-03-22 13:50:08.438 UTC [51725][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59008 2024-03-22 13:50:08.446 UTC [51725][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-22 13:50:08.446 UTC [51725][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-22 13:50:08.447 UTC [51725][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_THuR/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-22 13:50:08.448 UTC [51725][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=59008 2024-03-22 13:50:08.460 UTC [51727][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59014 2024-03-22 13:50:08.468 UTC [51727][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-22 13:50:08.468 UTC [51727][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-22 13:50:08.469 UTC [51727][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_THuR/client.key$$ 2024-03-22 13:50:08.470 UTC [51727][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=59014 2024-03-22 13:50:08.481 UTC [51729][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59022 2024-03-22 13:50:08.488 UTC [51729][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-22 13:50:08.489 UTC [51729][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-22 13:50:08.490 UTC [51729][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_THuR/client.key$$ 2024-03-22 13:50:08.490 UTC [51729][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=59022 2024-03-22 13:50:08.501 UTC [51731][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59036 2024-03-22 13:50:08.506 UTC [51731][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-22 13:50:08.517 UTC [51733][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59046 2024-03-22 13:50:08.519 UTC [51733][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:08.529 UTC [51735][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59058 2024-03-22 13:50:08.535 UTC [51735][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-22 13:50:08.535 UTC [51735][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-22 13:50:08.541 UTC [51735][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_THuR/client-dn.key$$ 2024-03-22 13:50:08.541 UTC [51735][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=59058 2024-03-22 13:50:08.552 UTC [51737][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59074 2024-03-22 13:50:08.559 UTC [51737][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-22 13:50:08.559 UTC [51737][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-22 13:50:08.564 UTC [51737][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_THuR/client-dn.key$$ 2024-03-22 13:50:08.565 UTC [51737][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_dn_re host=localhost port=59074 2024-03-22 13:50:08.576 UTC [51739][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59076 2024-03-22 13:50:08.584 UTC [51739][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-22 13:50:08.584 UTC [51739][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-22 13:50:08.589 UTC [51739][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_THuR/client-dn.key$$ 2024-03-22 13:50:08.590 UTC [51739][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_cn host=localhost port=59076 2024-03-22 13:50:08.617 UTC [51742][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59086 2024-03-22 13:50:08.623 UTC [51742][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-22 13:50:08.623 UTC [51742][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-22 13:50:08.624 UTC [51742][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-22 13:50:08.626 UTC [51742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=59086 2024-03-22 13:50:08.637 UTC [51744][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59088 2024-03-22 13:50:08.639 UTC [51744][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:08.650 UTC [51746][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59100 2024-03-22 13:50:08.657 UTC [51746][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-22 13:50:08.657 UTC [51746][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-22 13:50:08.657 UTC [51746][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-22 13:50:08.657 UTC [51746][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-22 13:50:08.668 UTC [51748][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59114 2024-03-22 13:50:08.674 UTC [51748][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:08.674 UTC [51748][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-22 13:50:08.684 UTC [51750][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59122 2024-03-22 13:50:08.690 UTC [51750][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-22 13:50:08.690 UTC [51750][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-22 13:50:08.697 UTC [51750][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_THuR/client.key$$ 2024-03-22 13:50:08.697 UTC [51750][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=verifydb host=localhost port=59122 2024-03-22 13:50:08.710 UTC [51752][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59138 2024-03-22 13:50:08.717 UTC [51752][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-22 13:50:08.717 UTC [51752][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-22 13:50:08.717 UTC [51752][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-22 13:50:08.717 UTC [51752][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-22 13:50:08.727 UTC [51754][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59140 2024-03-22 13:50:08.732 UTC [51754][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-22 13:50:08.732 UTC [51754][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-22 13:50:08.733 UTC [51754][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_THuR/client.key$$ 2024-03-22 13:50:08.734 UTC [51754][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=59140 2024-03-22 13:50:08.738 UTC [51696][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:08.738 UTC [51696][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:08.740 UTC [51696][postmaster] LOG: background worker "logical replication launcher" (PID 51702) exited with exit code 1 2024-03-22 13:50:08.740 UTC [51697][checkpointer] LOG: shutting down 2024-03-22 13:50:08.740 UTC [51697][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:08.741 UTC [51697][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-22 13:50:08.744 UTC [51696][postmaster] LOG: database system is shut down 2024-03-22 13:50:08.857 UTC [51756][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:08.857 UTC [51756][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:08.858 UTC [51756][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:08.860 UTC [51759][startup] LOG: database system was shut down at 2024-03-22 13:50:08 UTC 2024-03-22 13:50:08.864 UTC [51756][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:08.949 UTC [51764][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59148 2024-03-22 13:50:08.956 UTC [51764][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-22 13:50:08.956 UTC [51764][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-22 13:50:08.961 UTC [51764][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_THuR/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-22 13:50:08.962 UTC [51764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb host=localhost port=59148 2024-03-22 13:50:08.973 UTC [51766][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59160 2024-03-22 13:50:08.979 UTC [51766][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:08.979 UTC [51766][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-22 13:50:08.990 UTC [51768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59174 2024-03-22 13:50:08.996 UTC [51768][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:08.996 UTC [51768][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-22 13:50:09.001 UTC [51756][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:09.001 UTC [51756][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:09.003 UTC [51756][postmaster] LOG: background worker "logical replication launcher" (PID 51762) exited with exit code 1 2024-03-22 13:50:09.003 UTC [51757][checkpointer] LOG: shutting down 2024-03-22 13:50:09.003 UTC [51757][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:09.004 UTC [51757][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-22 13:50:09.007 UTC [51756][postmaster] LOG: database system is shut down 2024-03-22 13:50:09.123 UTC [51770][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:09.123 UTC [51770][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:09.123 UTC [51770][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:09.125 UTC [51773][startup] LOG: database system was shut down at 2024-03-22 13:50:08 UTC 2024-03-22 13:50:09.130 UTC [51770][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:09.211 UTC [51778][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59188 2024-03-22 13:50:09.217 UTC [51778][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:09.217 UTC [51778][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-22 13:50:09.221 UTC [51770][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:09.221 UTC [51770][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:09.223 UTC [51770][postmaster] LOG: background worker "logical replication launcher" (PID 51776) exited with exit code 1 2024-03-22 13:50:09.223 UTC [51771][checkpointer] LOG: shutting down 2024-03-22 13:50:09.223 UTC [51771][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:09.224 UTC [51771][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-22 13:50:09.227 UTC [51770][postmaster] LOG: database system is shut down 2024-03-22 13:50:09.341 UTC [51780][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:09.342 UTC [51780][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:09.342 UTC [51780][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:09.344 UTC [51783][startup] LOG: database system was shut down at 2024-03-22 13:50:09 UTC 2024-03-22 13:50:09.349 UTC [51780][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:09.432 UTC [51788][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59198 2024-03-22 13:50:09.438 UTC [51788][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:09.438 UTC [51788][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-22 13:50:09.450 UTC [51790][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59214 2024-03-22 13:50:09.456 UTC [51790][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:09.456 UTC [51790][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-22 13:50:09.461 UTC [51780][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:09.461 UTC [51780][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:09.463 UTC [51780][postmaster] LOG: background worker "logical replication launcher" (PID 51786) exited with exit code 1 2024-03-22 13:50:09.463 UTC [51781][checkpointer] LOG: shutting down 2024-03-22 13:50:09.464 UTC [51781][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:09.464 UTC [51781][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-22 13:50:09.468 UTC [51780][postmaster] LOG: database system is shut down 2024-03-22 13:50:09.583 UTC [51792][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:09.583 UTC [51792][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:09.583 UTC [51792][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:09.585 UTC [51795][startup] LOG: database system was shut down at 2024-03-22 13:50:09 UTC 2024-03-22 13:50:09.589 UTC [51792][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:09.673 UTC [51800][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59228 2024-03-22 13:50:09.675 UTC [51800][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:09.675 UTC [51800][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:09.688 UTC [51802][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59244 2024-03-22 13:50:09.693 UTC [51802][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-22 13:50:09.693 UTC [51802][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-22 13:50:09.699 UTC [51802][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-22 13:50:09.699 UTC [51802][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=59244 2024-03-22 13:50:09.704 UTC [51792][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:09.705 UTC [51792][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:09.707 UTC [51792][postmaster] LOG: background worker "logical replication launcher" (PID 51798) exited with exit code 1 2024-03-22 13:50:09.707 UTC [51793][checkpointer] LOG: shutting down 2024-03-22 13:50:09.707 UTC [51793][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:09.707 UTC [51793][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-22 13:50:09.711 UTC [51792][postmaster] LOG: database system is shut down 2024-03-22 13:50:09.825 UTC [51804][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:09.825 UTC [51804][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:09.825 UTC [51804][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:09.827 UTC [51807][startup] LOG: database system was shut down at 2024-03-22 13:50:09 UTC 2024-03-22 13:50:09.831 UTC [51804][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:09.915 UTC [51812][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59246 2024-03-22 13:50:09.917 UTC [51812][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:09.917 UTC [51812][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:09.929 UTC [51814][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59258 2024-03-22 13:50:09.934 UTC [51814][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-22 13:50:09.934 UTC [51814][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-22 13:50:09.939 UTC [51814][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-22 13:50:09.940 UTC [51814][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59258 2024-03-22 13:50:09.945 UTC [51804][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:09.945 UTC [51804][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:09.947 UTC [51804][postmaster] LOG: background worker "logical replication launcher" (PID 51810) exited with exit code 1 2024-03-22 13:50:09.947 UTC [51805][checkpointer] LOG: shutting down 2024-03-22 13:50:09.947 UTC [51805][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:09.947 UTC [51805][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-22 13:50:09.951 UTC [51804][postmaster] LOG: database system is shut down 2024-03-22 13:50:10.067 UTC [51816][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:10.067 UTC [51816][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:10.067 UTC [51816][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:10.069 UTC [51819][startup] LOG: database system was shut down at 2024-03-22 13:50:09 UTC 2024-03-22 13:50:10.073 UTC [51816][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:10.155 UTC [51824][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59264 2024-03-22 13:50:10.157 UTC [51824][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:10.157 UTC [51824][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:10.169 UTC [51826][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59266 2024-03-22 13:50:10.174 UTC [51826][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-22 13:50:10.174 UTC [51826][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-22 13:50:10.180 UTC [51826][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-22 13:50:10.181 UTC [51826][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59266 2024-03-22 13:50:10.186 UTC [51816][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:10.186 UTC [51816][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:10.188 UTC [51816][postmaster] LOG: background worker "logical replication launcher" (PID 51822) exited with exit code 1 2024-03-22 13:50:10.188 UTC [51817][checkpointer] LOG: shutting down 2024-03-22 13:50:10.188 UTC [51817][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:10.189 UTC [51817][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-22 13:50:10.192 UTC [51816][postmaster] LOG: database system is shut down 2024-03-22 13:50:10.307 UTC [51828][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:10.307 UTC [51828][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:10.307 UTC [51828][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:10.309 UTC [51831][startup] LOG: database system was shut down at 2024-03-22 13:50:10 UTC 2024-03-22 13:50:10.313 UTC [51828][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:10.397 UTC [51836][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59274 2024-03-22 13:50:10.398 UTC [51836][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:10.399 UTC [51836][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:10.411 UTC [51838][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59276 2024-03-22 13:50:10.415 UTC [51838][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-22 13:50:10.415 UTC [51838][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-22 13:50:10.421 UTC [51838][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-22 13:50:10.421 UTC [51838][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=59276 2024-03-22 13:50:10.426 UTC [51828][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:10.426 UTC [51828][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:10.428 UTC [51828][postmaster] LOG: background worker "logical replication launcher" (PID 51834) exited with exit code 1 2024-03-22 13:50:10.428 UTC [51829][checkpointer] LOG: shutting down 2024-03-22 13:50:10.428 UTC [51829][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:10.429 UTC [51829][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-22 13:50:10.432 UTC [51828][postmaster] LOG: database system is shut down 2024-03-22 13:50:10.547 UTC [51840][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:10.547 UTC [51840][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:10.547 UTC [51840][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:10.549 UTC [51843][startup] LOG: database system was shut down at 2024-03-22 13:50:10 UTC 2024-03-22 13:50:10.553 UTC [51840][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:10.637 UTC [51848][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59284 2024-03-22 13:50:10.639 UTC [51848][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:10.639 UTC [51848][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:10.652 UTC [51850][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59288 2024-03-22 13:50:10.657 UTC [51850][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-22 13:50:10.657 UTC [51850][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-22 13:50:10.663 UTC [51850][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-22 13:50:10.664 UTC [51850][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=59288 2024-03-22 13:50:10.669 UTC [51840][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:10.669 UTC [51840][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:10.671 UTC [51840][postmaster] LOG: background worker "logical replication launcher" (PID 51846) exited with exit code 1 2024-03-22 13:50:10.671 UTC [51841][checkpointer] LOG: shutting down 2024-03-22 13:50:10.672 UTC [51841][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:10.672 UTC [51841][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-22 13:50:10.676 UTC [51840][postmaster] LOG: database system is shut down 2024-03-22 13:50:10.790 UTC [51852][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:10.790 UTC [51852][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:10.790 UTC [51852][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:10.793 UTC [51855][startup] LOG: database system was shut down at 2024-03-22 13:50:10 UTC 2024-03-22 13:50:10.797 UTC [51852][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:10.880 UTC [51860][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59304 2024-03-22 13:50:10.881 UTC [51860][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:10.881 UTC [51860][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:10.893 UTC [51862][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59306 2024-03-22 13:50:10.898 UTC [51862][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-22 13:50:10.898 UTC [51862][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-22 13:50:10.903 UTC [51862][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-22 13:50:10.904 UTC [51862][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59306 2024-03-22 13:50:10.909 UTC [51852][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:10.909 UTC [51852][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:10.911 UTC [51852][postmaster] LOG: background worker "logical replication launcher" (PID 51858) exited with exit code 1 2024-03-22 13:50:10.911 UTC [51853][checkpointer] LOG: shutting down 2024-03-22 13:50:10.911 UTC [51853][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:10.912 UTC [51853][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-22 13:50:10.915 UTC [51852][postmaster] LOG: database system is shut down 2024-03-22 13:50:11.030 UTC [51864][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:11.030 UTC [51864][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:11.030 UTC [51864][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:11.032 UTC [51867][startup] LOG: database system was shut down at 2024-03-22 13:50:10 UTC 2024-03-22 13:50:11.037 UTC [51864][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:11.119 UTC [51872][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59322 2024-03-22 13:50:11.121 UTC [51872][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:11.121 UTC [51872][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:11.134 UTC [51874][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59328 2024-03-22 13:50:11.139 UTC [51874][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-22 13:50:11.139 UTC [51874][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-22 13:50:11.144 UTC [51874][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-22 13:50:11.144 UTC [51874][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59328 2024-03-22 13:50:11.150 UTC [51864][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:11.150 UTC [51864][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:11.152 UTC [51864][postmaster] LOG: background worker "logical replication launcher" (PID 51870) exited with exit code 1 2024-03-22 13:50:11.152 UTC [51865][checkpointer] LOG: shutting down 2024-03-22 13:50:11.152 UTC [51865][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:11.153 UTC [51865][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-22 13:50:11.156 UTC [51864][postmaster] LOG: database system is shut down 2024-03-22 13:50:11.271 UTC [51876][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:11.271 UTC [51876][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:11.271 UTC [51876][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:11.274 UTC [51879][startup] LOG: database system was shut down at 2024-03-22 13:50:11 UTC 2024-03-22 13:50:11.278 UTC [51876][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:11.361 UTC [51884][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59334 2024-03-22 13:50:11.362 UTC [51884][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:11.362 UTC [51884][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:11.375 UTC [51886][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59350 2024-03-22 13:50:11.380 UTC [51886][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-22 13:50:11.380 UTC [51886][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-22 13:50:11.385 UTC [51886][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-22 13:50:11.386 UTC [51886][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59350 2024-03-22 13:50:11.391 UTC [51876][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:11.391 UTC [51876][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:11.393 UTC [51876][postmaster] LOG: background worker "logical replication launcher" (PID 51882) exited with exit code 1 2024-03-22 13:50:11.393 UTC [51877][checkpointer] LOG: shutting down 2024-03-22 13:50:11.393 UTC [51877][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:11.394 UTC [51877][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-22 13:50:11.398 UTC [51876][postmaster] LOG: database system is shut down 2024-03-22 13:50:11.511 UTC [51888][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-22 13:50:11.511 UTC [51888][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59853 2024-03-22 13:50:11.511 UTC [51888][postmaster] LOG: listening on Unix socket "/tmp/JpWUA3RNuQ/.s.PGSQL.59853" 2024-03-22 13:50:11.513 UTC [51891][startup] LOG: database system was shut down at 2024-03-22 13:50:11 UTC 2024-03-22 13:50:11.517 UTC [51888][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:11.601 UTC [51896][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59352 2024-03-22 13:50:11.603 UTC [51896][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:11.603 UTC [51896][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:11.614 UTC [51898][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=59368 2024-03-22 13:50:11.618 UTC [51898][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-22 13:50:11.618 UTC [51898][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-22 13:50:11.624 UTC [51898][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-22 13:50:11.624 UTC [51898][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59368 2024-03-22 13:50:11.629 UTC [51888][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:11.629 UTC [51888][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:11.631 UTC [51888][postmaster] LOG: background worker "logical replication launcher" (PID 51894) exited with exit code 1 2024-03-22 13:50:11.631 UTC [51889][checkpointer] LOG: shutting down 2024-03-22 13:50:11.631 UTC [51889][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:11.632 UTC [51889][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-22 13:50:11.635 UTC [51888][postmaster] LOG: database system is shut down 2024-03-22 13:50:11.745 UTC [51900][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-22 13:50:11.745 UTC [51900][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:50:11.831](0.205s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"