[10:21:43.487](0.022s) # setting up data directory # Checking port 60688 # Found port 60688 Name: primary Data directory: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup Archive directory: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives Connection string: port=60688 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [10:21:43.498](0.011s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 43401 [10:21:43.972](0.474s) ok 1 - ssl_library parameter ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 43860 [10:21:46.130](2.158s) # testing password-protected keys ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-01 10:21:43.873 UTC [43401][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:43.874 UTC [43401][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:43.905 UTC [43418][startup] LOG: database system was shut down at 2024-04-01 10:18:42 UTC 2024-04-01 10:21:43.913 UTC [43401][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:43.967 UTC [43445][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:43.967 UTC [43445][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:43.967 UTC [43445][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:43.970 UTC [43445][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-01 10:21:43.971 UTC [43445][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-04-01 10:21:43.985 UTC [43453][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:43.985 UTC [43453][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:43.985 UTC [43453][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:43.987 UTC [43453][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-01 10:21:43.988 UTC [43453][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-04-01 10:21:44.034 UTC [43487][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.034 UTC [43487][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.034 UTC [43487][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.035 UTC [43487][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-01 10:21:44.035 UTC [43487][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-04-01 10:21:44.091 UTC [43499][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.092 UTC [43499][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.092 UTC [43499][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.093 UTC [43499][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-01 10:21:44.093 UTC [43499][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-04-01 10:21:44.146 UTC [43527][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.146 UTC [43527][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.146 UTC [43527][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.147 UTC [43527][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-01 10:21:44.147 UTC [43527][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-04-01 10:21:44.191 UTC [43540][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.191 UTC [43540][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.191 UTC [43540][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.192 UTC [43540][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-01 10:21:44.328 UTC [43540][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.137 user=admin database=postgres host=[local] 2024-04-01 10:21:44.510 UTC [43602][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.510 UTC [43602][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.510 UTC [43602][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.511 UTC [43602][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-01 10:21:44.585 UTC [43602][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.075 user=admin database=postgres host=[local] 2024-04-01 10:21:44.647 UTC [43636][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.648 UTC [43636][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.648 UTC [43636][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.648 UTC [43636][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-01 10:21:44.699 UTC [43636][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.051 user=admin database=postgres host=[local] 2024-04-01 10:21:44.775 UTC [43650][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.775 UTC [43650][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.775 UTC [43650][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.776 UTC [43650][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-01 10:21:44.859 UTC [43650][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=admin database=postgres host=[local] 2024-04-01 10:21:44.915 UTC [43677][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.915 UTC [43677][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.915 UTC [43677][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.917 UTC [43677][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-01 10:21:44.971 UTC [43677][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=admin database=postgres host=[local] 2024-04-01 10:21:45.049 UTC [43698][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:45.049 UTC [43698][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:45.049 UTC [43698][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:45.050 UTC [43698][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-01 10:21:45.187 UTC [43698][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.138 user=admin database=postgres host=[local] 2024-04-01 10:21:45.384 UTC [43401][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:45.384 UTC [43401][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:45.385 UTC [43401][postmaster] LOG: background worker "logical replication launcher" (PID 43426) exited with exit code 1 2024-04-01 10:21:45.386 UTC [43414][checkpointer] LOG: shutting down 2024-04-01 10:21:45.386 UTC [43414][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:45.942 UTC [43414][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.555 s, sync=0.001 s, total=0.556 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D3A28, redo lsn=0/40D3A28 2024-04-01 10:21:45.952 UTC [43401][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.042 UTC [43860][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.043 UTC [43860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.043 UTC [43860][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.050 UTC [43865][startup] LOG: database system was shut down at 2024-04-01 10:21:45 UTC 2024-04-01 10:21:46.056 UTC [43860][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:46.135 UTC [43860][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:46.135 UTC [43860][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:46.136 UTC [43860][postmaster] LOG: background worker "logical replication launcher" (PID 43868) exited with exit code 1 2024-04-01 10:21:46.137 UTC [43862][checkpointer] LOG: shutting down 2024-04-01 10:21:46.137 UTC [43862][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:46.138 UTC [43862][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3AA0, redo lsn=0/40D3AA0 2024-04-01 10:21:46.139 UTC [43860][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.256 UTC [43879][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-01 10:21:46.296 UTC [43879][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:21:46.349](0.219s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/Users/admin/pgsql/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 43894 [10:21:46.457](0.108s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-01 10:21:43.873 UTC [43401][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:43.874 UTC [43401][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:43.905 UTC [43418][startup] LOG: database system was shut down at 2024-04-01 10:18:42 UTC 2024-04-01 10:21:43.913 UTC [43401][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:43.967 UTC [43445][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:43.967 UTC [43445][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:43.967 UTC [43445][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:43.970 UTC [43445][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-01 10:21:43.971 UTC [43445][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-04-01 10:21:43.985 UTC [43453][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:43.985 UTC [43453][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:43.985 UTC [43453][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:43.987 UTC [43453][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-01 10:21:43.988 UTC [43453][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-04-01 10:21:44.034 UTC [43487][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.034 UTC [43487][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.034 UTC [43487][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.035 UTC [43487][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-01 10:21:44.035 UTC [43487][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-04-01 10:21:44.091 UTC [43499][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.092 UTC [43499][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.092 UTC [43499][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.093 UTC [43499][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-01 10:21:44.093 UTC [43499][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-04-01 10:21:44.146 UTC [43527][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.146 UTC [43527][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.146 UTC [43527][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.147 UTC [43527][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-01 10:21:44.147 UTC [43527][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-04-01 10:21:44.191 UTC [43540][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.191 UTC [43540][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.191 UTC [43540][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.192 UTC [43540][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-01 10:21:44.328 UTC [43540][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.137 user=admin database=postgres host=[local] 2024-04-01 10:21:44.510 UTC [43602][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.510 UTC [43602][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.510 UTC [43602][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.511 UTC [43602][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-01 10:21:44.585 UTC [43602][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.075 user=admin database=postgres host=[local] 2024-04-01 10:21:44.647 UTC [43636][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.648 UTC [43636][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.648 UTC [43636][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.648 UTC [43636][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-01 10:21:44.699 UTC [43636][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.051 user=admin database=postgres host=[local] 2024-04-01 10:21:44.775 UTC [43650][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.775 UTC [43650][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.775 UTC [43650][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.776 UTC [43650][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-01 10:21:44.859 UTC [43650][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=admin database=postgres host=[local] 2024-04-01 10:21:44.915 UTC [43677][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.915 UTC [43677][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.915 UTC [43677][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.917 UTC [43677][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-01 10:21:44.971 UTC [43677][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=admin database=postgres host=[local] 2024-04-01 10:21:45.049 UTC [43698][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:45.049 UTC [43698][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:45.049 UTC [43698][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:45.050 UTC [43698][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-01 10:21:45.187 UTC [43698][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.138 user=admin database=postgres host=[local] 2024-04-01 10:21:45.384 UTC [43401][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:45.384 UTC [43401][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:45.385 UTC [43401][postmaster] LOG: background worker "logical replication launcher" (PID 43426) exited with exit code 1 2024-04-01 10:21:45.386 UTC [43414][checkpointer] LOG: shutting down 2024-04-01 10:21:45.386 UTC [43414][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:45.942 UTC [43414][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.555 s, sync=0.001 s, total=0.556 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D3A28, redo lsn=0/40D3A28 2024-04-01 10:21:45.952 UTC [43401][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.042 UTC [43860][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.043 UTC [43860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.043 UTC [43860][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.050 UTC [43865][startup] LOG: database system was shut down at 2024-04-01 10:21:45 UTC 2024-04-01 10:21:46.056 UTC [43860][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:46.135 UTC [43860][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:46.135 UTC [43860][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:46.136 UTC [43860][postmaster] LOG: background worker "logical replication launcher" (PID 43868) exited with exit code 1 2024-04-01 10:21:46.137 UTC [43862][checkpointer] LOG: shutting down 2024-04-01 10:21:46.137 UTC [43862][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:46.138 UTC [43862][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3AA0, redo lsn=0/40D3AA0 2024-04-01 10:21:46.139 UTC [43860][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.256 UTC [43879][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-01 10:21:46.296 UTC [43879][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.372 UTC [43894][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.372 UTC [43894][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.372 UTC [43894][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.376 UTC [43898][startup] LOG: database system was shut down at 2024-04-01 10:21:46 UTC 2024-04-01 10:21:46.377 UTC [43894][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:46.463 UTC [43894][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:46.463 UTC [43894][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:46.464 UTC [43894][postmaster] LOG: background worker "logical replication launcher" (PID 43901) exited with exit code 1 2024-04-01 10:21:46.464 UTC [43896][checkpointer] LOG: shutting down 2024-04-01 10:21:46.464 UTC [43896][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:46.538 UTC [43896][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.075 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3B18, redo lsn=0/40D3B18 2024-04-01 10:21:46.539 UTC [43894][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.582 UTC [43928][postmaster] FATAL: could not set SSL protocol version range 2024-04-01 10:21:46.582 UTC [43928][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-01 10:21:46.582 UTC [43928][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:21:46.676](0.219s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/Users/admin/pgsql/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 43948 [10:21:46.790](0.114s) ok 5 - restart succeeds with correct SSL protocol bounds [10:21:46.790](0.000s) # running client tests ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 43983 [10:21:47.030](0.240s) ok 6 - server doesn't accept non-SSL connections [10:21:47.031](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [10:21:47.054](0.024s) ok 8 - connect without server root cert sslmode=require [10:21:47.055](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [10:21:47.070](0.016s) ok 10 - connect without server root cert sslmode=verify-ca [10:21:47.070](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [10:21:47.085](0.015s) ok 12 - connect without server root cert sslmode=verify-full [10:21:47.085](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [10:21:47.102](0.017s) ok 14 - connect with wrong server root cert sslmode=require [10:21:47.102](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [10:21:47.119](0.017s) ok 16 - connect with wrong server root cert sslmode=verify-ca [10:21:47.119](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [10:21:47.136](0.017s) ok 18 - connect with wrong server root cert sslmode=verify-full [10:21:47.136](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [10:21:47.156](0.020s) ok 20 - connect with server CA cert, without root CA [10:21:47.156](0.000s) ok 21 - connect with server CA cert, without root CA: matches [10:21:47.180](0.024s) ok 22 - connect with correct server CA cert file sslmode=require [10:21:47.180](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [10:21:47.203](0.022s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [10:21:47.203](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [10:21:47.226](0.023s) ok 26 - connect with correct server CA cert file sslmode=verify-full [10:21:47.226](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [10:21:47.252](0.026s) ok 28 - cert root file that contains two certificates, order 1 [10:21:47.252](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [10:21:47.276](0.024s) ok 30 - cert root file that contains two certificates, order 2 [10:21:47.276](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [10:21:47.294](0.018s) ok 32 - connect with sslcertmode=disable [10:21:47.295](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [10:21:47.313](0.018s) ok 34 - connect with sslcertmode=allow [10:21:47.313](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [10:21:47.330](0.017s) ok 36 - connect with sslcertmode=require fails without a client certificate [10:21:47.330](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [10:21:47.348](0.018s) ok 38 - sslcrl option with invalid file name [10:21:47.348](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [10:21:47.364](0.016s) ok 40 - CRL belonging to a different CA [10:21:47.365](0.000s) ok 41 - CRL belonging to a different CA: matches [10:21:47.382](0.017s) ok 42 - directory CRL belonging to a different CA [10:21:47.944](0.562s) ok 43 - directory CRL belonging to a different CA: matches [10:21:47.968](0.025s) ok 44 - CRL with a non-revoked cert [10:21:47.969](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [10:21:47.991](0.023s) ok 46 - directory CRL with a non-revoked cert [10:21:47.992](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [10:21:48.012](0.020s) ok 48 - mismatch between host name and server certificate sslmode=require [10:21:48.012](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [10:21:48.031](0.019s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [10:21:48.031](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [10:21:48.050](0.019s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [10:21:48.050](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44109 [10:21:48.308](0.257s) ok 54 - IP address in the Common Name [10:21:48.308](0.000s) ok 55 - IP address in the Common Name: no stderr [10:21:48.325](0.017s) ok 56 - mismatch between host name and server certificate IP address [10:21:48.325](0.000s) ok 57 - mismatch between host name and server certificate IP address: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44131 [10:21:48.571](0.246s) ok 58 - IP address in a dNSName [10:21:48.572](0.000s) ok 59 - IP address in a dNSName: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44141 [10:21:48.823](0.252s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [10:21:48.823](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [10:21:48.842](0.019s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [10:21:48.843](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [10:21:48.860](0.017s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [10:21:48.860](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [10:21:48.876](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [10:21:48.876](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [10:21:48.892](0.016s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [10:21:48.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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44159 [10:21:49.145](0.254s) ok 70 - host name matching with a single X.509 Subject Alternative Name [10:21:49.146](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [10:21:49.165](0.020s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [10:21:49.165](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [10:21:49.182](0.017s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [10:21:49.182](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44174 [10:21:49.441](0.259s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [10:21:49.441](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [10:21:49.461](0.020s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [10:21:49.461](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [10:21:49.478](0.017s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [10:21:49.478](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [10:21:49.495](0.017s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [10:21:49.495](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [10:21:49.512](0.017s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [10:21:49.512](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [10:21:49.530](0.017s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [10:21:49.530](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [10:21:49.547](0.017s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [10:21:49.547](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [10:21:49.564](0.017s) ok 90 - IPv6 host with CIDR mask does not match [10:21:49.564](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44198 [10:21:49.833](0.269s) ok 92 - certificate with both a CN and SANs 1 [10:21:49.833](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [10:21:49.855](0.022s) ok 94 - certificate with both a CN and SANs 2 [10:21:49.855](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [10:21:49.872](0.017s) ok 96 - certificate with both a CN and SANs ignores CN [10:21:49.872](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44213 [10:21:50.155](0.283s) ok 98 - certificate with both a CN and IP SANs matches CN [10:21:50.155](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [10:21:50.176](0.021s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [10:21:50.177](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [10:21:50.194](0.018s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [10:21:50.194](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44227 [10:21:50.453](0.259s) ok 104 - certificate with both an IP CN and IP SANs 1 [10:21:50.453](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [10:21:50.474](0.021s) ok 106 - certificate with both an IP CN and IP SANs 2 [10:21:50.474](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [10:21:50.491](0.017s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [10:21:50.491](0.000s) ok 109 - certificate with both an IP CN and IP SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44241 [10:21:50.741](0.250s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [10:21:50.741](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [10:21:50.762](0.020s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [10:21:50.762](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [10:21:50.779](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [10:21:50.779](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44255 [10:21:51.049](0.270s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [10:21:51.049](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [10:21:51.068](0.019s) ok 118 - server certificate without CN or SANs sslmode=verify-full [10:21:51.069](0.000s) ok 119 - server certificate without CN or SANs sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44267 [10:21:51.343](0.275s) ok 120 - sslrootcert=system does not connect with private CA [10:21:51.344](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [10:21:51.354](0.011s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [10:21:51.355](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [10:21:51.376](0.022s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [10:21:51.376](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [10:21:51.392](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [10:21:51.393](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44282 [10:21:51.677](0.285s) ok 128 - connects without client-side CRL [10:21:51.677](0.000s) ok 129 - connects without client-side CRL: no stderr [10:21:51.699](0.022s) ok 130 - does not connect with client-side CRL file [10:21:51.699](0.000s) ok 131 - does not connect with client-side CRL file: matches [10:21:51.717](0.018s) ok 132 - does not connect with client-side CRL directory [10:21:51.717](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [10:21:51.734](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [10:21:51.734](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [10:21:51.734](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [10:21:51.751](0.017s) ok 137 - connection success with correct range of TLS protocol versions [10:21:51.751](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [10:21:51.759](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions [10:21:51.759](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [10:21:51.766](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [10:21:51.766](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [10:21:51.773](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [10:21:51.774](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [10:21:51.774](0.000s) # running server tests [10:21:51.790](0.016s) ok 145 - certificate authorization fails without client cert [10:21:51.790](0.000s) ok 146 - certificate authorization fails without client cert: matches [10:21:51.810](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [10:21:51.810](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [10:21:51.829](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [10:21:51.829](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [10:21:51.847](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [10:21:51.847](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [10:21:51.865](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [10:21:51.865](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [10:21:51.883](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [10:21:51.883](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [10:21:51.901](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [10:21:51.901](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [10:21:51.917](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [10:21:51.917](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [10:21:51.932](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [10:21:51.932](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [10:21:51.953](0.021s) ok 163 - certificate authorization succeeds with DN mapping [10:21:51.953](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [10:21:51.953](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [10:21:51.973](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [10:21:51.973](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [10:21:51.993](0.020s) ok 168 - certificate authorization succeeds with CN mapping [10:21:51.993](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [10:21:51.993](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [10:21:51.993](0.000s) not ok 171 # TODO & SKIP Need Pty support [10:21:51.993](0.000s) not ok 172 # TODO & SKIP Need Pty support [10:21:51.993](0.000s) not ok 173 # TODO & SKIP Need Pty support [10:21:51.993](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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [10:21:52.020](0.027s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [10:21:52.020](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [10:21:52.020](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [10:21:52.035](0.014s) ok 178 - certificate authorization fails because of file permissions [10:21:52.035](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [10:21:52.052](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [10:21:52.052](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [10:21:52.070](0.018s) ok 182 - certificate authorization fails with revoked client cert [10:21:52.070](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [10:21:52.070](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [10:21:52.090](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [10:21:52.090](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [10:21:52.090](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [10:21:52.108](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [10:21:52.108](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [10:21:52.108](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [10:21:52.126](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [10:21:52.126](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [10:21:52.126](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44342 [10:21:52.405](0.279s) ok 194 - intermediate client certificate is provided by client [10:21:52.405](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [10:21:52.426](0.021s) ok 196 - intermediate client certificate is missing [10:21:52.426](0.000s) ok 197 - intermediate client certificate is missing: matches [10:21:52.444](0.018s) ok 198 - logged client certificate Subjects are truncated if they're too long [10:21:52.444](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44356 [10:21:52.703](0.259s) ok 200 - intermediate client certificate is untrusted [10:21:52.703](0.000s) ok 201 - intermediate client certificate is untrusted: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44366 [10:21:52.974](0.271s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [10:21:52.974](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [10:21:52.998](0.024s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [10:21:52.998](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44378 [10:21:53.267](0.269s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [10:21:53.267](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [10:21:53.267](0.000s) # got: '2' # expected: '0' [10:21:53.267](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [10:21:53.267](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [10:21:53.267](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 60688 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [10:21:53.293](0.025s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [10:21:53.293](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44390 [10:21:53.559](0.266s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [10:21:53.585](0.026s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [10:21:53.585](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44402 [10:21:53.846](0.261s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [10:21:53.872](0.026s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [10:21:53.872](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44414 [10:21:54.142](0.270s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [10:21:54.167](0.025s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [10:21:54.167](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44426 [10:21:54.430](0.262s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [10:21:54.430](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [10:21:54.430](0.000s) # got: '2' # expected: '0' [10:21:54.430](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [10:21:54.430](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [10:21:54.430](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 60688 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [10:21:54.455](0.025s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [10:21:54.455](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44438 [10:21:54.724](0.269s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [10:21:54.750](0.026s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [10:21:54.750](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44450 [10:21:55.033](0.283s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [10:21:55.060](0.026s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [10:21:55.060](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44462 [10:21:55.322](0.262s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [10:21:55.347](0.026s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [10:21:55.348](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 44474 [10:21:55.629](0.282s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [10:21:55.656](0.026s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [10:21:55.656](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-04-01 10:21:43.873 UTC [43401][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:43.874 UTC [43401][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:43.905 UTC [43418][startup] LOG: database system was shut down at 2024-04-01 10:18:42 UTC 2024-04-01 10:21:43.913 UTC [43401][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:43.967 UTC [43445][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:43.967 UTC [43445][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:43.967 UTC [43445][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:43.970 UTC [43445][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-04-01 10:21:43.971 UTC [43445][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-04-01 10:21:43.985 UTC [43453][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:43.985 UTC [43453][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:43.985 UTC [43453][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:43.987 UTC [43453][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-04-01 10:21:43.988 UTC [43453][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-04-01 10:21:44.034 UTC [43487][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.034 UTC [43487][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.034 UTC [43487][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.035 UTC [43487][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-04-01 10:21:44.035 UTC [43487][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-04-01 10:21:44.091 UTC [43499][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.092 UTC [43499][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.092 UTC [43499][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.093 UTC [43499][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-04-01 10:21:44.093 UTC [43499][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-04-01 10:21:44.146 UTC [43527][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.146 UTC [43527][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.146 UTC [43527][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.147 UTC [43527][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-04-01 10:21:44.147 UTC [43527][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-04-01 10:21:44.191 UTC [43540][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.191 UTC [43540][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.191 UTC [43540][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.192 UTC [43540][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-04-01 10:21:44.328 UTC [43540][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.137 user=admin database=postgres host=[local] 2024-04-01 10:21:44.510 UTC [43602][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.510 UTC [43602][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.510 UTC [43602][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.511 UTC [43602][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-04-01 10:21:44.585 UTC [43602][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.075 user=admin database=postgres host=[local] 2024-04-01 10:21:44.647 UTC [43636][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.648 UTC [43636][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.648 UTC [43636][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.648 UTC [43636][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-04-01 10:21:44.699 UTC [43636][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.051 user=admin database=postgres host=[local] 2024-04-01 10:21:44.775 UTC [43650][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.775 UTC [43650][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.775 UTC [43650][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.776 UTC [43650][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-04-01 10:21:44.859 UTC [43650][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=admin database=postgres host=[local] 2024-04-01 10:21:44.915 UTC [43677][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:44.915 UTC [43677][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:44.915 UTC [43677][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:44.917 UTC [43677][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-04-01 10:21:44.971 UTC [43677][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=admin database=postgres host=[local] 2024-04-01 10:21:45.049 UTC [43698][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-04-01 10:21:45.049 UTC [43698][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-04-01 10:21:45.049 UTC [43698][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-04-01 10:21:45.050 UTC [43698][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-04-01 10:21:45.187 UTC [43698][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.138 user=admin database=postgres host=[local] 2024-04-01 10:21:45.384 UTC [43401][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:45.384 UTC [43401][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:45.385 UTC [43401][postmaster] LOG: background worker "logical replication launcher" (PID 43426) exited with exit code 1 2024-04-01 10:21:45.386 UTC [43414][checkpointer] LOG: shutting down 2024-04-01 10:21:45.386 UTC [43414][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:45.942 UTC [43414][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.555 s, sync=0.001 s, total=0.556 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D3A28, redo lsn=0/40D3A28 2024-04-01 10:21:45.952 UTC [43401][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.042 UTC [43860][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.043 UTC [43860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.043 UTC [43860][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.050 UTC [43865][startup] LOG: database system was shut down at 2024-04-01 10:21:45 UTC 2024-04-01 10:21:46.056 UTC [43860][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:46.135 UTC [43860][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:46.135 UTC [43860][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:46.136 UTC [43860][postmaster] LOG: background worker "logical replication launcher" (PID 43868) exited with exit code 1 2024-04-01 10:21:46.137 UTC [43862][checkpointer] LOG: shutting down 2024-04-01 10:21:46.137 UTC [43862][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:46.138 UTC [43862][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3AA0, redo lsn=0/40D3AA0 2024-04-01 10:21:46.139 UTC [43860][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.256 UTC [43879][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-04-01 10:21:46.296 UTC [43879][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.372 UTC [43894][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.372 UTC [43894][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.372 UTC [43894][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.376 UTC [43898][startup] LOG: database system was shut down at 2024-04-01 10:21:46 UTC 2024-04-01 10:21:46.377 UTC [43894][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:46.463 UTC [43894][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:46.463 UTC [43894][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:46.464 UTC [43894][postmaster] LOG: background worker "logical replication launcher" (PID 43901) exited with exit code 1 2024-04-01 10:21:46.464 UTC [43896][checkpointer] LOG: shutting down 2024-04-01 10:21:46.464 UTC [43896][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:46.538 UTC [43896][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.075 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3B18, redo lsn=0/40D3B18 2024-04-01 10:21:46.539 UTC [43894][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.582 UTC [43928][postmaster] FATAL: could not set SSL protocol version range 2024-04-01 10:21:46.582 UTC [43928][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-04-01 10:21:46.582 UTC [43928][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.697 UTC [43948][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.697 UTC [43948][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.697 UTC [43948][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.700 UTC [43954][startup] LOG: database system was shut down at 2024-04-01 10:21:46 UTC 2024-04-01 10:21:46.701 UTC [43948][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:46.794 UTC [43948][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:46.794 UTC [43948][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:46.795 UTC [43948][postmaster] LOG: background worker "logical replication launcher" (PID 43957) exited with exit code 1 2024-04-01 10:21:46.795 UTC [43952][checkpointer] LOG: shutting down 2024-04-01 10:21:46.795 UTC [43952][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:46.796 UTC [43952][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/40D3B90, redo lsn=0/40D3B90 2024-04-01 10:21:46.797 UTC [43948][postmaster] LOG: database system is shut down 2024-04-01 10:21:46.919 UTC [43983][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:46.919 UTC [43983][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:46.919 UTC [43983][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:46.923 UTC [43995][startup] LOG: database system was shut down at 2024-04-01 10:21:46 UTC 2024-04-01 10:21:46.924 UTC [43983][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:47.024 UTC [44019][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49264 2024-04-01 10:21:47.029 UTC [44019][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-04-01 10:21:47.029 UTC [44019][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-04-01 10:21:47.044 UTC [44024][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49266 2024-04-01 10:21:47.051 UTC [44024][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.051 UTC [44024][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.053 UTC [44024][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-04-01 10:21:47.053 UTC [44024][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49266 2024-04-01 10:21:47.064 UTC [44027][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49267 2024-04-01 10:21:47.069 UTC [44027][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:21:47.079 UTC [44031][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49269 2024-04-01 10:21:47.084 UTC [44031][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:21:47.094 UTC [44034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49271 2024-04-01 10:21:47.101 UTC [44034][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:47.111 UTC [44038][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49273 2024-04-01 10:21:47.118 UTC [44038][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:47.128 UTC [44042][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-04-01 10:21:47.135 UTC [44042][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:47.147 UTC [44046][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49276 2024-04-01 10:21:47.155 UTC [44046][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:47.171 UTC [44056][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-04-01 10:21:47.178 UTC [44056][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.178 UTC [44056][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.179 UTC [44056][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-04-01 10:21:47.179 UTC [44056][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49278 2024-04-01 10:21:47.194 UTC [44060][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-04-01 10:21:47.201 UTC [44060][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.201 UTC [44060][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.201 UTC [44060][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-04-01 10:21:47.202 UTC [44060][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49280 2024-04-01 10:21:47.216 UTC [44064][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-04-01 10:21:47.224 UTC [44064][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.224 UTC [44064][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.225 UTC [44064][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-04-01 10:21:47.225 UTC [44064][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49282 2024-04-01 10:21:47.241 UTC [44068][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-04-01 10:21:47.249 UTC [44068][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.249 UTC [44068][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.250 UTC [44068][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-04-01 10:21:47.251 UTC [44068][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49284 2024-04-01 10:21:47.266 UTC [44074][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-04-01 10:21:47.274 UTC [44074][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.274 UTC [44074][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.274 UTC [44074][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-04-01 10:21:47.275 UTC [44074][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49287 2024-04-01 10:21:47.285 UTC [44077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-04-01 10:21:47.293 UTC [44077][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.293 UTC [44077][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.293 UTC [44077][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-04-01 10:21:47.294 UTC [44077][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49288 2024-04-01 10:21:47.303 UTC [44079][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-04-01 10:21:47.311 UTC [44079][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.311 UTC [44079][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.312 UTC [44079][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-04-01 10:21:47.312 UTC [44079][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49289 2024-04-01 10:21:47.321 UTC [44081][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-04-01 10:21:47.329 UTC [44081][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.329 UTC [44081][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.329 UTC [44081][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49290 2024-04-01 10:21:47.339 UTC [44083][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-04-01 10:21:47.346 UTC [44083][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.346 UTC [44083][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.347 UTC [44083][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-04-01 10:21:47.347 UTC [44083][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-04-01 10:21:47.356 UTC [44085][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-04-01 10:21:47.364 UTC [44085][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:47.373 UTC [44087][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-04-01 10:21:47.380 UTC [44087][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:47.958 UTC [44090][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-04-01 10:21:47.966 UTC [44090][client backend] [[unknown]][11/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.966 UTC [44090][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.967 UTC [44090][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-04-01 10:21:47.967 UTC [44090][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49294 2024-04-01 10:21:47.981 UTC [44098][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-04-01 10:21:47.990 UTC [44098][client backend] [[unknown]][12/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:47.990 UTC [44098][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:47.990 UTC [44098][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-04-01 10:21:47.990 UTC [44098][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49295 2024-04-01 10:21:48.002 UTC [44100][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-04-01 10:21:48.010 UTC [44100][client backend] [[unknown]][13/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.010 UTC [44100][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.011 UTC [44100][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-04-01 10:21:48.011 UTC [44100][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49296 2024-04-01 10:21:48.021 UTC [44102][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-04-01 10:21:48.030 UTC [44102][client backend] [[unknown]][14/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.030 UTC [44102][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.030 UTC [44102][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-04-01 10:21:48.030 UTC [44102][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49297 2024-04-01 10:21:48.042 UTC [44104][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-04-01 10:21:48.054 UTC [43983][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:48.054 UTC [43983][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:48.056 UTC [43983][postmaster] LOG: background worker "logical replication launcher" (PID 43998) exited with exit code 1 2024-04-01 10:21:48.056 UTC [43993][checkpointer] LOG: shutting down 2024-04-01 10:21:48.056 UTC [43993][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:48.058 UTC [43993][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.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3C08, redo lsn=0/40D3C08 2024-04-01 10:21:48.059 UTC [43983][postmaster] LOG: database system is shut down 2024-04-01 10:21:48.188 UTC [44109][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:48.189 UTC [44109][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:48.189 UTC [44109][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:48.192 UTC [44113][startup] LOG: database system was shut down at 2024-04-01 10:21:48 UTC 2024-04-01 10:21:48.193 UTC [44109][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:48.293 UTC [44124][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-04-01 10:21:48.303 UTC [44124][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.303 UTC [44124][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.306 UTC [44124][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-01 10:21:48.306 UTC [44124][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49300 2024-04-01 10:21:48.317 UTC [44129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-04-01 10:21:48.328 UTC [44109][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:48.328 UTC [44109][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:48.329 UTC [44109][postmaster] LOG: background worker "logical replication launcher" (PID 44116) exited with exit code 1 2024-04-01 10:21:48.329 UTC [44111][checkpointer] LOG: shutting down 2024-04-01 10:21:48.329 UTC [44111][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:48.330 UTC [44111][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3C80, redo lsn=0/40D3C80 2024-04-01 10:21:48.331 UTC [44109][postmaster] LOG: database system is shut down 2024-04-01 10:21:48.458 UTC [44131][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:48.458 UTC [44131][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:48.459 UTC [44131][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:48.462 UTC [44134][startup] LOG: database system was shut down at 2024-04-01 10:21:48 UTC 2024-04-01 10:21:48.463 UTC [44131][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:48.558 UTC [44139][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-04-01 10:21:48.568 UTC [44139][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.568 UTC [44139][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.570 UTC [44139][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-01 10:21:48.570 UTC [44139][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49303 2024-04-01 10:21:48.575 UTC [44131][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:48.575 UTC [44131][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:48.575 UTC [44131][postmaster] LOG: background worker "logical replication launcher" (PID 44137) exited with exit code 1 2024-04-01 10:21:48.576 UTC [44132][checkpointer] LOG: shutting down 2024-04-01 10:21:48.576 UTC [44132][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:48.576 UTC [44132][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3CF8, redo lsn=0/40D3CF8 2024-04-01 10:21:48.577 UTC [44131][postmaster] LOG: database system is shut down 2024-04-01 10:21:48.706 UTC [44141][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:48.707 UTC [44141][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:48.707 UTC [44141][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:48.710 UTC [44144][startup] LOG: database system was shut down at 2024-04-01 10:21:48 UTC 2024-04-01 10:21:48.711 UTC [44141][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:48.808 UTC [44149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-04-01 10:21:48.819 UTC [44149][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.819 UTC [44149][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.822 UTC [44149][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:48.822 UTC [44149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49304 2024-04-01 10:21:48.833 UTC [44151][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-04-01 10:21:48.841 UTC [44151][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.841 UTC [44151][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.841 UTC [44151][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:48.842 UTC [44151][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-04-01 10:21:48.851 UTC [44153][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-04-01 10:21:48.859 UTC [44153][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:48.859 UTC [44153][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:48.859 UTC [44153][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-04-01 10:21:48.859 UTC [44153][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-04-01 10:21:48.868 UTC [44155][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-04-01 10:21:48.884 UTC [44157][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-04-01 10:21:48.895 UTC [44141][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:48.895 UTC [44141][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:48.896 UTC [44141][postmaster] LOG: background worker "logical replication launcher" (PID 44147) exited with exit code 1 2024-04-01 10:21:48.896 UTC [44142][checkpointer] LOG: shutting down 2024-04-01 10:21:48.896 UTC [44142][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:48.897 UTC [44142][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/40D3D70, redo lsn=0/40D3D70 2024-04-01 10:21:48.898 UTC [44141][postmaster] LOG: database system is shut down 2024-04-01 10:21:49.015 UTC [44159][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:49.016 UTC [44159][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:49.016 UTC [44159][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:49.019 UTC [44162][startup] LOG: database system was shut down at 2024-04-01 10:21:48 UTC 2024-04-01 10:21:49.020 UTC [44159][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:49.127 UTC [44167][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-04-01 10:21:49.140 UTC [44167][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.140 UTC [44167][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.144 UTC [44167][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:49.144 UTC [44167][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49309 2024-04-01 10:21:49.156 UTC [44169][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-04-01 10:21:49.174 UTC [44171][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-04-01 10:21:49.189 UTC [44159][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:49.189 UTC [44159][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:49.189 UTC [44159][postmaster] LOG: background worker "logical replication launcher" (PID 44165) exited with exit code 1 2024-04-01 10:21:49.189 UTC [44160][checkpointer] LOG: shutting down 2024-04-01 10:21:49.189 UTC [44160][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:49.190 UTC [44160][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/40D3DE8, redo lsn=0/40D3DE8 2024-04-01 10:21:49.191 UTC [44159][postmaster] LOG: database system is shut down 2024-04-01 10:21:49.324 UTC [44174][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:49.324 UTC [44174][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:49.324 UTC [44174][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:49.327 UTC [44177][startup] LOG: database system was shut down at 2024-04-01 10:21:49 UTC 2024-04-01 10:21:49.328 UTC [44174][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:49.426 UTC [44182][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-04-01 10:21:49.436 UTC [44182][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.436 UTC [44182][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.440 UTC [44182][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-01 10:21:49.440 UTC [44182][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49312 2024-04-01 10:21:49.451 UTC [44184][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-04-01 10:21:49.459 UTC [44184][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.459 UTC [44184][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.460 UTC [44184][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-04-01 10:21:49.460 UTC [44184][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-04-01 10:21:49.469 UTC [44186][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-04-01 10:21:49.486 UTC [44188][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-04-01 10:21:49.494 UTC [44188][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.494 UTC [44188][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.494 UTC [44188][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-04-01 10:21:49.494 UTC [44188][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-04-01 10:21:49.504 UTC [44190][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-04-01 10:21:49.511 UTC [44190][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.511 UTC [44190][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.511 UTC [44190][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-04-01 10:21:49.511 UTC [44190][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49316 2024-04-01 10:21:49.520 UTC [44192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-04-01 10:21:49.528 UTC [44192][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.528 UTC [44192][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.529 UTC [44192][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-04-01 10:21:49.529 UTC [44192][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49317 2024-04-01 10:21:49.538 UTC [44194][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-04-01 10:21:49.555 UTC [44196][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-04-01 10:21:49.567 UTC [44174][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:49.567 UTC [44174][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:49.567 UTC [44174][postmaster] LOG: background worker "logical replication launcher" (PID 44180) exited with exit code 1 2024-04-01 10:21:49.568 UTC [44175][checkpointer] LOG: shutting down 2024-04-01 10:21:49.568 UTC [44175][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:49.568 UTC [44175][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/40D3E60, redo lsn=0/40D3E60 2024-04-01 10:21:49.569 UTC [44174][postmaster] LOG: database system is shut down 2024-04-01 10:21:49.700 UTC [44198][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:49.700 UTC [44198][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:49.701 UTC [44198][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:49.703 UTC [44201][startup] LOG: database system was shut down at 2024-04-01 10:21:49 UTC 2024-04-01 10:21:49.705 UTC [44198][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:49.814 UTC [44206][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-04-01 10:21:49.827 UTC [44206][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.827 UTC [44206][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.831 UTC [44206][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:49.831 UTC [44206][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49320 2024-04-01 10:21:49.844 UTC [44208][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-04-01 10:21:49.853 UTC [44208][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:49.853 UTC [44208][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:49.854 UTC [44208][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:49.854 UTC [44208][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49321 2024-04-01 10:21:49.864 UTC [44210][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-04-01 10:21:49.878 UTC [44198][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:49.878 UTC [44198][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:49.879 UTC [44198][postmaster] LOG: background worker "logical replication launcher" (PID 44204) exited with exit code 1 2024-04-01 10:21:49.879 UTC [44199][checkpointer] LOG: shutting down 2024-04-01 10:21:49.879 UTC [44199][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:49.880 UTC [44199][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/40D3ED8, redo lsn=0/40D3ED8 2024-04-01 10:21:49.880 UTC [44198][postmaster] LOG: database system is shut down 2024-04-01 10:21:50.021 UTC [44213][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:50.022 UTC [44213][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:50.022 UTC [44213][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:50.025 UTC [44216][startup] LOG: database system was shut down at 2024-04-01 10:21:49 UTC 2024-04-01 10:21:50.027 UTC [44213][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:50.137 UTC [44221][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-04-01 10:21:50.150 UTC [44221][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.150 UTC [44221][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.154 UTC [44221][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-04-01 10:21:50.154 UTC [44221][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49323 2024-04-01 10:21:50.166 UTC [44223][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-04-01 10:21:50.175 UTC [44223][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.175 UTC [44223][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.175 UTC [44223][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-01 10:21:50.175 UTC [44223][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49324 2024-04-01 10:21:50.185 UTC [44225][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-04-01 10:21:50.193 UTC [44225][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.193 UTC [44225][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.193 UTC [44225][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-04-01 10:21:50.193 UTC [44225][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-04-01 10:21:50.197 UTC [44213][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:50.197 UTC [44213][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:50.198 UTC [44213][postmaster] LOG: background worker "logical replication launcher" (PID 44219) exited with exit code 1 2024-04-01 10:21:50.198 UTC [44214][checkpointer] LOG: shutting down 2024-04-01 10:21:50.198 UTC [44214][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:50.198 UTC [44214][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/40D3F50, redo lsn=0/40D3F50 2024-04-01 10:21:50.199 UTC [44213][postmaster] LOG: database system is shut down 2024-04-01 10:21:50.324 UTC [44227][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:50.325 UTC [44227][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:50.325 UTC [44227][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:50.327 UTC [44230][startup] LOG: database system was shut down at 2024-04-01 10:21:50 UTC 2024-04-01 10:21:50.329 UTC [44227][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:50.436 UTC [44235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-04-01 10:21:50.448 UTC [44235][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.448 UTC [44235][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.452 UTC [44235][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-04-01 10:21:50.452 UTC [44235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49326 2024-04-01 10:21:50.464 UTC [44237][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-04-01 10:21:50.472 UTC [44237][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.472 UTC [44237][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.473 UTC [44237][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-04-01 10:21:50.473 UTC [44237][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49327 2024-04-01 10:21:50.483 UTC [44239][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-04-01 10:21:50.494 UTC [44227][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:50.494 UTC [44227][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:50.495 UTC [44227][postmaster] LOG: background worker "logical replication launcher" (PID 44233) exited with exit code 1 2024-04-01 10:21:50.495 UTC [44228][checkpointer] LOG: shutting down 2024-04-01 10:21:50.495 UTC [44228][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:50.495 UTC [44228][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3FC8, redo lsn=0/40D3FC8 2024-04-01 10:21:50.496 UTC [44227][postmaster] LOG: database system is shut down 2024-04-01 10:21:50.628 UTC [44241][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:50.629 UTC [44241][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:50.629 UTC [44241][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:50.632 UTC [44244][startup] LOG: database system was shut down at 2024-04-01 10:21:50 UTC 2024-04-01 10:21:50.633 UTC [44241][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:50.725 UTC [44249][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-04-01 10:21:50.736 UTC [44249][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.736 UTC [44249][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.739 UTC [44249][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-04-01 10:21:50.740 UTC [44249][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49329 2024-04-01 10:21:50.751 UTC [44251][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-04-01 10:21:50.760 UTC [44251][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.760 UTC [44251][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.760 UTC [44251][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:50.761 UTC [44251][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49330 2024-04-01 10:21:50.770 UTC [44253][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-04-01 10:21:50.777 UTC [44253][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:50.778 UTC [44253][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:50.778 UTC [44253][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-04-01 10:21:50.778 UTC [44253][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-04-01 10:21:50.782 UTC [44241][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:50.782 UTC [44241][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:50.783 UTC [44241][postmaster] LOG: background worker "logical replication launcher" (PID 44247) exited with exit code 1 2024-04-01 10:21:50.783 UTC [44242][checkpointer] LOG: shutting down 2024-04-01 10:21:50.783 UTC [44242][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:50.783 UTC [44242][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D4058, redo lsn=0/40D4058 2024-04-01 10:21:50.784 UTC [44241][postmaster] LOG: database system is shut down 2024-04-01 10:21:50.913 UTC [44255][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:50.914 UTC [44255][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:50.914 UTC [44255][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:50.917 UTC [44258][startup] LOG: database system was shut down at 2024-04-01 10:21:50 UTC 2024-04-01 10:21:50.918 UTC [44255][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:51.031 UTC [44263][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-04-01 10:21:51.044 UTC [44263][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:51.044 UTC [44263][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.047 UTC [44263][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-04-01 10:21:51.048 UTC [44263][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49332 2024-04-01 10:21:51.059 UTC [44265][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-04-01 10:21:51.072 UTC [44255][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:51.072 UTC [44255][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:51.072 UTC [44255][postmaster] LOG: background worker "logical replication launcher" (PID 44261) exited with exit code 1 2024-04-01 10:21:51.072 UTC [44256][checkpointer] LOG: shutting down 2024-04-01 10:21:51.072 UTC [44256][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:51.073 UTC [44256][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/40D40D0, redo lsn=0/40D40D0 2024-04-01 10:21:51.074 UTC [44255][postmaster] LOG: database system is shut down 2024-04-01 10:21:51.212 UTC [44267][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:51.212 UTC [44267][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:51.212 UTC [44267][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:51.216 UTC [44270][startup] LOG: database system was shut down at 2024-04-01 10:21:51 UTC 2024-04-01 10:21:51.218 UTC [44267][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:51.329 UTC [44275][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-04-01 10:21:51.342 UTC [44275][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-04-01 10:21:51.364 UTC [44278][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-04-01 10:21:51.372 UTC [44278][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:51.372 UTC [44278][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.375 UTC [44278][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-04-01 10:21:51.375 UTC [44278][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49335 2024-04-01 10:21:51.384 UTC [44280][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-04-01 10:21:51.395 UTC [44267][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:51.396 UTC [44267][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:51.396 UTC [44267][postmaster] LOG: background worker "logical replication launcher" (PID 44273) exited with exit code 1 2024-04-01 10:21:51.396 UTC [44268][checkpointer] LOG: shutting down 2024-04-01 10:21:51.396 UTC [44268][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:51.397 UTC [44268][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/40D4148, redo lsn=0/40D4148 2024-04-01 10:21:51.398 UTC [44267][postmaster] LOG: database system is shut down 2024-04-01 10:21:51.538 UTC [44282][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:51.538 UTC [44282][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:51.538 UTC [44282][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:51.541 UTC [44285][startup] LOG: database system was shut down at 2024-04-01 10:21:51 UTC 2024-04-01 10:21:51.543 UTC [44282][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:51.658 UTC [44290][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-04-01 10:21:51.672 UTC [44290][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:51.672 UTC [44290][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.676 UTC [44290][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-04-01 10:21:51.676 UTC [44290][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49337 2024-04-01 10:21:51.689 UTC [44292][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-04-01 10:21:51.698 UTC [44292][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-04-01 10:21:51.708 UTC [44294][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-04-01 10:21:51.716 UTC [44294][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-04-01 10:21:51.725 UTC [44296][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-04-01 10:21:51.732 UTC [44296][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:51.732 UTC [44296][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.733 UTC [44296][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-01 10:21:51.733 UTC [44296][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-04-01 10:21:51.742 UTC [44298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-04-01 10:21:51.750 UTC [44298][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:51.750 UTC [44298][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-04-01 10:21:51.750 UTC [44298][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-04-01 10:21:51.750 UTC [44298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49341 2024-04-01 10:21:51.782 UTC [44303][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-04-01 10:21:51.789 UTC [44303][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-04-01 10:21:51.798 UTC [44305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-04-01 10:21:51.807 UTC [44305][client backend] [[unknown]][4/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:51.807 UTC [44305][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.809 UTC [44305][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key$$ 2024-04-01 10:21:51.809 UTC [44305][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-04-01 10:21:51.818 UTC [44307][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-04-01 10:21:51.827 UTC [44307][client backend] [[unknown]][5/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:51.827 UTC [44307][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.828 UTC [44307][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client-der.key$$ 2024-04-01 10:21:51.828 UTC [44307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-04-01 10:21:51.837 UTC [44309][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-04-01 10:21:51.846 UTC [44309][client backend] [[unknown]][6/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:51.846 UTC [44309][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.846 UTC [44309][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-04-01 10:21:51.846 UTC [44309][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-04-01 10:21:51.855 UTC [44311][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-04-01 10:21:51.864 UTC [44311][client backend] [[unknown]][7/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:51.864 UTC [44311][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.864 UTC [44311][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-04-01 10:21:51.864 UTC [44311][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-04-01 10:21:51.873 UTC [44313][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-04-01 10:21:51.882 UTC [44313][client backend] [[unknown]][8/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:51.882 UTC [44313][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.882 UTC [44313][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key$$ 2024-04-01 10:21:51.882 UTC [44313][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-04-01 10:21:51.891 UTC [44315][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-04-01 10:21:51.900 UTC [44315][client backend] [[unknown]][9/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:51.900 UTC [44315][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.900 UTC [44315][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key$$ 2024-04-01 10:21:51.900 UTC [44315][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-04-01 10:21:51.909 UTC [44317][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-04-01 10:21:51.916 UTC [44317][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-04-01 10:21:51.925 UTC [44319][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-04-01 10:21:51.931 UTC [44319][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:21:51.940 UTC [44321][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-04-01 10:21:51.949 UTC [44321][client backend] [[unknown]][11/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8) 2024-04-01 10:21:51.949 UTC [44321][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.952 UTC [44321][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client-dn.key$$ 2024-04-01 10:21:51.952 UTC [44321][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-04-01 10:21:51.961 UTC [44323][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-04-01 10:21:51.970 UTC [44323][client backend] [[unknown]][12/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9) 2024-04-01 10:21:51.970 UTC [44323][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.972 UTC [44323][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client-dn.key$$ 2024-04-01 10:21:51.972 UTC [44323][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=49352 2024-04-01 10:21:51.981 UTC [44325][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-04-01 10:21:51.990 UTC [44325][client backend] [[unknown]][13/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10) 2024-04-01 10:21:51.990 UTC [44325][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:51.992 UTC [44325][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client-dn.key$$ 2024-04-01 10:21:51.992 UTC [44325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-04-01 10:21:52.010 UTC [44328][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-04-01 10:21:52.018 UTC [44328][client backend] [[unknown]][14/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:52.018 UTC [44328][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:52.019 UTC [44328][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-04-01 10:21:52.020 UTC [44328][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49354 2024-04-01 10:21:52.029 UTC [44330][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-04-01 10:21:52.034 UTC [44330][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-04-01 10:21:52.043 UTC [44332][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-04-01 10:21:52.051 UTC [44332][client backend] [[unknown]][15/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:52.051 UTC [44332][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-01 10:21:52.051 UTC [44332][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-04-01 10:21:52.051 UTC [44332][client backend] [[unknown]][15/1:0] DETAIL: Connection matched file "/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb all 127.0.0.1/32 cert" 2024-04-01 10:21:52.060 UTC [44334][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-04-01 10:21:52.069 UTC [44334][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:21:52.069 UTC [44334][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-01 10:21:52.078 UTC [44336][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-04-01 10:21:52.087 UTC [44336][client backend] [[unknown]][16/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4) 2024-04-01 10:21:52.087 UTC [44336][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:52.089 UTC [44336][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key$$ 2024-04-01 10:21:52.089 UTC [44336][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-04-01 10:21:52.098 UTC [44338][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-04-01 10:21:52.107 UTC [44338][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-04-01 10:21:52.107 UTC [44338][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-04-01 10:21:52.107 UTC [44338][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-04-01 10:21:52.107 UTC [44338][client backend] [[unknown]][17/1:0] DETAIL: Connection matched file "/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb anotheruser 127.0.0.1/32 trust clientcert=verify-full" 2024-04-01 10:21:52.116 UTC [44340][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-04-01 10:21:52.124 UTC [44340][client backend] [[unknown]][18/1:0] LOG: connection authenticated: user="yetanotheruser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6) 2024-04-01 10:21:52.124 UTC [44340][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:52.125 UTC [44340][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key$$ 2024-04-01 10:21:52.125 UTC [44340][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-04-01 10:21:52.129 UTC [44282][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:52.129 UTC [44282][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:52.129 UTC [44282][postmaster] LOG: background worker "logical replication launcher" (PID 44288) exited with exit code 1 2024-04-01 10:21:52.129 UTC [44283][checkpointer] LOG: shutting down 2024-04-01 10:21:52.129 UTC [44283][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:52.130 UTC [44283][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D41C0, redo lsn=0/40D41C0 2024-04-01 10:21:52.131 UTC [44282][postmaster] LOG: database system is shut down 2024-04-01 10:21:52.272 UTC [44342][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:52.272 UTC [44342][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:52.272 UTC [44342][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:52.275 UTC [44345][startup] LOG: database system was shut down at 2024-04-01 10:21:52 UTC 2024-04-01 10:21:52.277 UTC [44342][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:52.384 UTC [44350][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-04-01 10:21:52.400 UTC [44350][client backend] [[unknown]][0/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-04-01 10:21:52.400 UTC [44350][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:52.404 UTC [44350][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_TFdT/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-04-01 10:21:52.404 UTC [44350][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=49361 2024-04-01 10:21:52.416 UTC [44352][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-04-01 10:21:52.425 UTC [44352][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:21:52.425 UTC [44352][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-01 10:21:52.435 UTC [44354][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-04-01 10:21:52.443 UTC [44354][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:21:52.443 UTC [44354][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-01 10:21:52.447 UTC [44342][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:52.447 UTC [44342][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:52.448 UTC [44342][postmaster] LOG: background worker "logical replication launcher" (PID 44348) exited with exit code 1 2024-04-01 10:21:52.448 UTC [44343][checkpointer] LOG: shutting down 2024-04-01 10:21:52.448 UTC [44343][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:52.448 UTC [44343][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/40D4238, redo lsn=0/40D4238 2024-04-01 10:21:52.449 UTC [44342][postmaster] LOG: database system is shut down 2024-04-01 10:21:52.580 UTC [44356][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:52.580 UTC [44356][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:52.581 UTC [44356][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:52.584 UTC [44359][startup] LOG: database system was shut down at 2024-04-01 10:21:52 UTC 2024-04-01 10:21:52.585 UTC [44356][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:52.689 UTC [44364][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-04-01 10:21:52.701 UTC [44364][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:21:52.701 UTC [44364][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-04-01 10:21:52.707 UTC [44356][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:52.707 UTC [44356][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:52.708 UTC [44356][postmaster] LOG: background worker "logical replication launcher" (PID 44362) exited with exit code 1 2024-04-01 10:21:52.708 UTC [44357][checkpointer] LOG: shutting down 2024-04-01 10:21:52.708 UTC [44357][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:52.708 UTC [44357][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/40D42B0, redo lsn=0/40D42B0 2024-04-01 10:21:52.710 UTC [44356][postmaster] LOG: database system is shut down 2024-04-01 10:21:52.850 UTC [44366][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:52.851 UTC [44366][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:52.851 UTC [44366][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:52.854 UTC [44369][startup] LOG: database system was shut down at 2024-04-01 10:21:52 UTC 2024-04-01 10:21:52.856 UTC [44366][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:52.957 UTC [44374][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-04-01 10:21:52.973 UTC [44374][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:21:52.973 UTC [44374][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-01 10:21:52.986 UTC [44376][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-04-01 10:21:52.997 UTC [44376][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-04-01 10:21:52.997 UTC [44376][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-04-01 10:21:53.001 UTC [44366][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:53.002 UTC [44366][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:53.002 UTC [44366][postmaster] LOG: background worker "logical replication launcher" (PID 44372) exited with exit code 1 2024-04-01 10:21:53.002 UTC [44367][checkpointer] LOG: shutting down 2024-04-01 10:21:53.002 UTC [44367][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:53.003 UTC [44367][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/40D4328, redo lsn=0/40D4328 2024-04-01 10:21:53.004 UTC [44366][postmaster] LOG: database system is shut down 2024-04-01 10:21:53.141 UTC [44378][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:53.141 UTC [44378][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:53.142 UTC [44378][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:53.145 UTC [44381][startup] LOG: database system was shut down at 2024-04-01 10:21:53 UTC 2024-04-01 10:21:53.147 UTC [44378][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:53.254 UTC [44386][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-04-01 10:21:53.264 UTC [44386][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:53.265 UTC [44386][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:53.279 UTC [44388][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-04-01 10:21:53.289 UTC [44388][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:53.289 UTC [44388][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:53.291 UTC [44388][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:53.292 UTC [44388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49368 2024-04-01 10:21:53.296 UTC [44378][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:53.296 UTC [44378][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:53.297 UTC [44378][postmaster] LOG: background worker "logical replication launcher" (PID 44384) exited with exit code 1 2024-04-01 10:21:53.297 UTC [44379][checkpointer] LOG: shutting down 2024-04-01 10:21:53.297 UTC [44379][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:53.298 UTC [44379][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D43A0, redo lsn=0/40D43A0 2024-04-01 10:21:53.299 UTC [44378][postmaster] LOG: database system is shut down 2024-04-01 10:21:53.432 UTC [44390][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:53.432 UTC [44390][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:53.432 UTC [44390][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:53.435 UTC [44393][startup] LOG: database system was shut down at 2024-04-01 10:21:53 UTC 2024-04-01 10:21:53.438 UTC [44390][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:53.545 UTC [44398][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-04-01 10:21:53.557 UTC [44398][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:53.557 UTC [44398][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:53.571 UTC [44400][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-04-01 10:21:53.581 UTC [44400][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:53.581 UTC [44400][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:53.584 UTC [44400][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:53.584 UTC [44400][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49370 2024-04-01 10:21:53.588 UTC [44390][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:53.588 UTC [44390][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:53.589 UTC [44390][postmaster] LOG: background worker "logical replication launcher" (PID 44396) exited with exit code 1 2024-04-01 10:21:53.589 UTC [44391][checkpointer] LOG: shutting down 2024-04-01 10:21:53.589 UTC [44391][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:53.590 UTC [44391][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/40D4418, redo lsn=0/40D4418 2024-04-01 10:21:53.591 UTC [44390][postmaster] LOG: database system is shut down 2024-04-01 10:21:53.726 UTC [44402][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:53.726 UTC [44402][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:53.727 UTC [44402][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:53.730 UTC [44405][startup] LOG: database system was shut down at 2024-04-01 10:21:53 UTC 2024-04-01 10:21:53.732 UTC [44402][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:53.832 UTC [44410][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-04-01 10:21:53.843 UTC [44410][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:53.844 UTC [44410][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:53.859 UTC [44412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-04-01 10:21:53.868 UTC [44412][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:53.868 UTC [44412][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:53.871 UTC [44412][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:53.871 UTC [44412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-04-01 10:21:53.876 UTC [44402][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:53.876 UTC [44402][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:53.877 UTC [44402][postmaster] LOG: background worker "logical replication launcher" (PID 44408) exited with exit code 1 2024-04-01 10:21:53.877 UTC [44403][checkpointer] LOG: shutting down 2024-04-01 10:21:53.877 UTC [44403][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:53.877 UTC [44403][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/40D4490, redo lsn=0/40D4490 2024-04-01 10:21:53.878 UTC [44402][postmaster] LOG: database system is shut down 2024-04-01 10:21:54.022 UTC [44414][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:54.022 UTC [44414][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:54.023 UTC [44414][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:54.026 UTC [44417][startup] LOG: database system was shut down at 2024-04-01 10:21:53 UTC 2024-04-01 10:21:54.028 UTC [44414][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:54.129 UTC [44422][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-04-01 10:21:54.140 UTC [44422][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:54.140 UTC [44422][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:54.154 UTC [44424][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-04-01 10:21:54.163 UTC [44424][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:54.163 UTC [44424][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:54.166 UTC [44424][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:54.166 UTC [44424][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49374 2024-04-01 10:21:54.171 UTC [44414][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:54.171 UTC [44414][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:54.171 UTC [44414][postmaster] LOG: background worker "logical replication launcher" (PID 44420) exited with exit code 1 2024-04-01 10:21:54.171 UTC [44415][checkpointer] LOG: shutting down 2024-04-01 10:21:54.171 UTC [44415][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:54.172 UTC [44415][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/40D4508, redo lsn=0/40D4508 2024-04-01 10:21:54.173 UTC [44414][postmaster] LOG: database system is shut down 2024-04-01 10:21:54.308 UTC [44426][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:54.309 UTC [44426][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:54.309 UTC [44426][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:54.312 UTC [44429][startup] LOG: database system was shut down at 2024-04-01 10:21:54 UTC 2024-04-01 10:21:54.315 UTC [44426][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:54.416 UTC [44434][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-04-01 10:21:54.427 UTC [44434][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:54.428 UTC [44434][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:54.442 UTC [44436][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-04-01 10:21:54.451 UTC [44436][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:54.451 UTC [44436][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:54.454 UTC [44436][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:54.454 UTC [44436][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-04-01 10:21:54.458 UTC [44426][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:54.458 UTC [44426][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:54.459 UTC [44426][postmaster] LOG: background worker "logical replication launcher" (PID 44432) exited with exit code 1 2024-04-01 10:21:54.459 UTC [44427][checkpointer] LOG: shutting down 2024-04-01 10:21:54.459 UTC [44427][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:54.460 UTC [44427][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/40D4580, redo lsn=0/40D4580 2024-04-01 10:21:54.461 UTC [44426][postmaster] LOG: database system is shut down 2024-04-01 10:21:54.592 UTC [44438][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:54.593 UTC [44438][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:54.593 UTC [44438][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:54.596 UTC [44441][startup] LOG: database system was shut down at 2024-04-01 10:21:54 UTC 2024-04-01 10:21:54.598 UTC [44438][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:54.711 UTC [44446][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-04-01 10:21:54.722 UTC [44446][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:54.722 UTC [44446][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:54.736 UTC [44448][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-04-01 10:21:54.746 UTC [44448][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:54.746 UTC [44448][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:54.749 UTC [44448][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:54.749 UTC [44448][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49378 2024-04-01 10:21:54.754 UTC [44438][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:54.754 UTC [44438][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:54.755 UTC [44438][postmaster] LOG: background worker "logical replication launcher" (PID 44444) exited with exit code 1 2024-04-01 10:21:54.755 UTC [44439][checkpointer] LOG: shutting down 2024-04-01 10:21:54.755 UTC [44439][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:54.755 UTC [44439][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D45F8, redo lsn=0/40D45F8 2024-04-01 10:21:54.756 UTC [44438][postmaster] LOG: database system is shut down 2024-04-01 10:21:54.900 UTC [44450][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:54.901 UTC [44450][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:54.901 UTC [44450][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:54.905 UTC [44453][startup] LOG: database system was shut down at 2024-04-01 10:21:54 UTC 2024-04-01 10:21:54.907 UTC [44450][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:55.019 UTC [44458][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-04-01 10:21:55.031 UTC [44458][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:55.031 UTC [44458][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:55.047 UTC [44460][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-04-01 10:21:55.056 UTC [44460][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:55.056 UTC [44460][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:55.058 UTC [44460][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:55.059 UTC [44460][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49380 2024-04-01 10:21:55.063 UTC [44450][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:55.063 UTC [44450][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:55.064 UTC [44450][postmaster] LOG: background worker "logical replication launcher" (PID 44456) exited with exit code 1 2024-04-01 10:21:55.064 UTC [44451][checkpointer] LOG: shutting down 2024-04-01 10:21:55.064 UTC [44451][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:55.065 UTC [44451][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D4670, redo lsn=0/40D4670 2024-04-01 10:21:55.066 UTC [44450][postmaster] LOG: database system is shut down 2024-04-01 10:21:55.196 UTC [44462][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:55.196 UTC [44462][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:55.196 UTC [44462][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:55.200 UTC [44465][startup] LOG: database system was shut down at 2024-04-01 10:21:55 UTC 2024-04-01 10:21:55.202 UTC [44462][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:55.308 UTC [44470][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-04-01 10:21:55.319 UTC [44470][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:55.319 UTC [44470][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:55.334 UTC [44472][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-04-01 10:21:55.343 UTC [44472][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:55.343 UTC [44472][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:55.346 UTC [44472][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:55.347 UTC [44472][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49382 2024-04-01 10:21:55.351 UTC [44462][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:55.351 UTC [44462][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:55.352 UTC [44462][postmaster] LOG: background worker "logical replication launcher" (PID 44468) exited with exit code 1 2024-04-01 10:21:55.352 UTC [44463][checkpointer] LOG: shutting down 2024-04-01 10:21:55.352 UTC [44463][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:55.352 UTC [44463][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/40D46E8, redo lsn=0/40D46E8 2024-04-01 10:21:55.353 UTC [44462][postmaster] LOG: database system is shut down 2024-04-01 10:21:55.498 UTC [44474][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-04-01 10:21:55.499 UTC [44474][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 60688 2024-04-01 10:21:55.499 UTC [44474][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/53u4offvxH/.s.PGSQL.60688" 2024-04-01 10:21:55.502 UTC [44477][startup] LOG: database system was shut down at 2024-04-01 10:21:55 UTC 2024-04-01 10:21:55.504 UTC [44474][postmaster] LOG: database system is ready to accept connections 2024-04-01 10:21:55.616 UTC [44482][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-04-01 10:21:55.627 UTC [44482][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-04-01 10:21:55.627 UTC [44482][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-04-01 10:21:55.643 UTC [44484][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-04-01 10:21:55.652 UTC [44484][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-04-01 10:21:55.652 UTC [44484][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-04-01 10:21:55.654 UTC [44484][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-04-01 10:21:55.655 UTC [44484][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49384 2024-04-01 10:21:55.659 UTC [44474][postmaster] LOG: received fast shutdown request 2024-04-01 10:21:55.659 UTC [44474][postmaster] LOG: aborting any active transactions 2024-04-01 10:21:55.660 UTC [44474][postmaster] LOG: background worker "logical replication launcher" (PID 44480) exited with exit code 1 2024-04-01 10:21:55.660 UTC [44475][checkpointer] LOG: shutting down 2024-04-01 10:21:55.660 UTC [44475][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-04-01 10:21:55.661 UTC [44475][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/40D4760, redo lsn=0/40D4760 2024-04-01 10:21:55.662 UTC [44474][postmaster] LOG: database system is shut down 2024-04-01 10:21:55.796 UTC [44486][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-04-01 10:21:55.796 UTC [44486][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [10:21:55.879](0.223s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"