[00:39:22.677](0.022s) # setting up data directory # Checking port 55694 # Found port 55694 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=55694 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_ Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [00:39:22.685](0.008s) # 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 38019 [00:39:23.444](0.760s) 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 38540 [00:39:28.252](4.807s) # 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-03-06 00:39:22.939 UTC [38019][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:22.939 UTC [38019][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:23.145 UTC [38063][startup] LOG: database system was shut down at 2024-03-06 00:34:52 UTC 2024-03-06 00:39:23.331 UTC [38019][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:23.440 UTC [38135][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.440 UTC [38135][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-03-06 00:39:23.440 UTC [38135][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.443 UTC [38135][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-06 00:39:23.444 UTC [38135][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-06 00:39:23.460 UTC [38138][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.552 UTC [38138][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-03-06 00:39:23.552 UTC [38138][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.552 UTC [38138][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-06 00:39:23.553 UTC [38138][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.092 user=admin database=postgres host=[local] 2024-03-06 00:39:23.588 UTC [38177][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.588 UTC [38177][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-03-06 00:39:23.588 UTC [38177][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.589 UTC [38177][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-06 00:39:23.646 UTC [38177][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=admin database=postgres host=[local] 2024-03-06 00:39:23.746 UTC [38200][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.746 UTC [38200][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-03-06 00:39:23.746 UTC [38200][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.747 UTC [38200][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-06 00:39:23.747 UTC [38200][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-06 00:39:23.803 UTC [38218][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.804 UTC [38218][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-03-06 00:39:23.804 UTC [38218][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.805 UTC [38218][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-06 00:39:23.806 UTC [38218][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-06 00:39:23.845 UTC [38231][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.845 UTC [38231][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-03-06 00:39:23.845 UTC [38231][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.846 UTC [38231][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-06 00:39:23.932 UTC [38231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.086 user=admin database=postgres host=[local] 2024-03-06 00:39:23.994 UTC [38250][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.994 UTC [38250][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-03-06 00:39:23.994 UTC [38250][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.995 UTC [38250][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-06 00:39:24.438 UTC [38250][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.443 user=admin database=postgres host=[local] 2024-03-06 00:39:24.650 UTC [38320][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:24.651 UTC [38320][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-03-06 00:39:24.651 UTC [38320][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:24.653 UTC [38320][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-06 00:39:24.710 UTC [38320][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.060 user=admin database=postgres host=[local] 2024-03-06 00:39:24.782 UTC [38339][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:24.782 UTC [38339][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-03-06 00:39:24.782 UTC [38339][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:24.783 UTC [38339][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-06 00:39:25.398 UTC [38339][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.616 user=admin database=postgres host=[local] 2024-03-06 00:39:25.925 UTC [38379][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:25.926 UTC [38379][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-03-06 00:39:25.926 UTC [38379][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:25.929 UTC [38379][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-06 00:39:25.966 UTC [38379][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.041 user=admin database=postgres host=[local] 2024-03-06 00:39:25.979 UTC [38400][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:25.979 UTC [38400][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-03-06 00:39:25.979 UTC [38400][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:25.980 UTC [38400][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-06 00:39:26.309 UTC [38400][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.330 user=admin database=postgres host=[local] 2024-03-06 00:39:26.691 UTC [38019][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:26.691 UTC [38019][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:26.692 UTC [38019][postmaster] LOG: background worker "logical replication launcher" (PID 38080) exited with exit code 1 2024-03-06 00:39:26.693 UTC [38061][checkpointer] LOG: shutting down 2024-03-06 00:39:26.693 UTC [38061][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:27.701 UTC [38061][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.008 s, sync=0.001 s, total=1.009 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104C80, redo lsn=0/4104C80 2024-03-06 00:39:27.709 UTC [38019][postmaster] LOG: database system is shut down 2024-03-06 00:39:27.943 UTC [38540][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:27.944 UTC [38540][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:27.944 UTC [38540][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:28.142 UTC [38544][startup] LOG: database system was shut down at 2024-03-06 00:39:27 UTC 2024-03-06 00:39:28.144 UTC [38540][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: background worker "logical replication launcher" (PID 38550) exited with exit code 1 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: shutting down 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104CF8, redo lsn=0/4104CF8 2024-03-06 00:39:28.258 UTC [38540][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.375 UTC [38565][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-06 00:39:28.375 UTC [38565][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [00:39:28.470](0.218s) 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 38582 [00:39:28.585](0.115s) 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-03-06 00:39:22.939 UTC [38019][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:22.939 UTC [38019][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:23.145 UTC [38063][startup] LOG: database system was shut down at 2024-03-06 00:34:52 UTC 2024-03-06 00:39:23.331 UTC [38019][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:23.440 UTC [38135][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.440 UTC [38135][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-03-06 00:39:23.440 UTC [38135][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.443 UTC [38135][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-06 00:39:23.444 UTC [38135][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-06 00:39:23.460 UTC [38138][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.552 UTC [38138][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-03-06 00:39:23.552 UTC [38138][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.552 UTC [38138][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-06 00:39:23.553 UTC [38138][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.092 user=admin database=postgres host=[local] 2024-03-06 00:39:23.588 UTC [38177][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.588 UTC [38177][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-03-06 00:39:23.588 UTC [38177][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.589 UTC [38177][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-06 00:39:23.646 UTC [38177][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=admin database=postgres host=[local] 2024-03-06 00:39:23.746 UTC [38200][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.746 UTC [38200][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-03-06 00:39:23.746 UTC [38200][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.747 UTC [38200][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-06 00:39:23.747 UTC [38200][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-06 00:39:23.803 UTC [38218][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.804 UTC [38218][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-03-06 00:39:23.804 UTC [38218][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.805 UTC [38218][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-06 00:39:23.806 UTC [38218][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-06 00:39:23.845 UTC [38231][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.845 UTC [38231][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-03-06 00:39:23.845 UTC [38231][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.846 UTC [38231][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-06 00:39:23.932 UTC [38231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.086 user=admin database=postgres host=[local] 2024-03-06 00:39:23.994 UTC [38250][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.994 UTC [38250][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-03-06 00:39:23.994 UTC [38250][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.995 UTC [38250][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-06 00:39:24.438 UTC [38250][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.443 user=admin database=postgres host=[local] 2024-03-06 00:39:24.650 UTC [38320][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:24.651 UTC [38320][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-03-06 00:39:24.651 UTC [38320][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:24.653 UTC [38320][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-06 00:39:24.710 UTC [38320][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.060 user=admin database=postgres host=[local] 2024-03-06 00:39:24.782 UTC [38339][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:24.782 UTC [38339][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-03-06 00:39:24.782 UTC [38339][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:24.783 UTC [38339][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-06 00:39:25.398 UTC [38339][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.616 user=admin database=postgres host=[local] 2024-03-06 00:39:25.925 UTC [38379][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:25.926 UTC [38379][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-03-06 00:39:25.926 UTC [38379][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:25.929 UTC [38379][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-06 00:39:25.966 UTC [38379][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.041 user=admin database=postgres host=[local] 2024-03-06 00:39:25.979 UTC [38400][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:25.979 UTC [38400][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-03-06 00:39:25.979 UTC [38400][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:25.980 UTC [38400][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-06 00:39:26.309 UTC [38400][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.330 user=admin database=postgres host=[local] 2024-03-06 00:39:26.691 UTC [38019][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:26.691 UTC [38019][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:26.692 UTC [38019][postmaster] LOG: background worker "logical replication launcher" (PID 38080) exited with exit code 1 2024-03-06 00:39:26.693 UTC [38061][checkpointer] LOG: shutting down 2024-03-06 00:39:26.693 UTC [38061][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:27.701 UTC [38061][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.008 s, sync=0.001 s, total=1.009 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104C80, redo lsn=0/4104C80 2024-03-06 00:39:27.709 UTC [38019][postmaster] LOG: database system is shut down 2024-03-06 00:39:27.943 UTC [38540][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:27.944 UTC [38540][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:27.944 UTC [38540][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:28.142 UTC [38544][startup] LOG: database system was shut down at 2024-03-06 00:39:27 UTC 2024-03-06 00:39:28.144 UTC [38540][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: background worker "logical replication launcher" (PID 38550) exited with exit code 1 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: shutting down 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104CF8, redo lsn=0/4104CF8 2024-03-06 00:39:28.258 UTC [38540][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.375 UTC [38565][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-06 00:39:28.375 UTC [38565][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.492 UTC [38582][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:28.492 UTC [38582][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:28.492 UTC [38582][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:28.495 UTC [38588][startup] LOG: database system was shut down at 2024-03-06 00:39:28 UTC 2024-03-06 00:39:28.497 UTC [38582][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:28.589 UTC [38582][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:28.589 UTC [38582][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:28.590 UTC [38582][postmaster] LOG: background worker "logical replication launcher" (PID 38591) exited with exit code 1 2024-03-06 00:39:28.590 UTC [38586][checkpointer] LOG: shutting down 2024-03-06 00:39:28.590 UTC [38586][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:28.747 UTC [38586][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.158 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104D70, redo lsn=0/4104D70 2024-03-06 00:39:28.749 UTC [38582][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.872 UTC [38623][postmaster] FATAL: could not set SSL protocol version range 2024-03-06 00:39:28.872 UTC [38623][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-06 00:39:28.872 UTC [38623][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [00:39:28.967](0.382s) 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 38639 [00:39:29.079](0.112s) ok 5 - restart succeeds with correct SSL protocol bounds [00:39:29.080](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 38684 [00:39:29.323](0.243s) ok 6 - server doesn't accept non-SSL connections [00:39:29.323](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [00:39:29.357](0.034s) ok 8 - connect without server root cert sslmode=require [00:39:29.357](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [00:39:29.381](0.024s) ok 10 - connect without server root cert sslmode=verify-ca [00:39:29.381](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [00:39:29.402](0.021s) ok 12 - connect without server root cert sslmode=verify-full [00:39:29.403](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [00:39:29.424](0.022s) ok 14 - connect with wrong server root cert sslmode=require [00:39:29.424](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [00:39:29.447](0.022s) ok 16 - connect with wrong server root cert sslmode=verify-ca [00:39:29.447](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [00:39:29.468](0.021s) ok 18 - connect with wrong server root cert sslmode=verify-full [00:39:29.468](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [00:39:29.487](0.019s) ok 20 - connect with server CA cert, without root CA [00:39:29.487](0.000s) ok 21 - connect with server CA cert, without root CA: matches [00:39:29.506](0.019s) ok 22 - connect with correct server CA cert file sslmode=require [00:39:29.506](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [00:39:29.524](0.018s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [00:39:29.524](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [00:39:29.542](0.018s) ok 26 - connect with correct server CA cert file sslmode=verify-full [00:39:29.542](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [00:39:29.560](0.018s) ok 28 - cert root file that contains two certificates, order 1 [00:39:29.560](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [00:39:29.580](0.019s) ok 30 - cert root file that contains two certificates, order 2 [00:39:29.580](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [00:39:29.598](0.018s) ok 32 - connect with sslcertmode=disable [00:39:29.598](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [00:39:29.618](0.020s) ok 34 - connect with sslcertmode=allow [00:39:29.618](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [00:39:29.644](0.026s) ok 36 - connect with sslcertmode=require fails without a client certificate [00:39:29.644](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [00:39:29.667](0.023s) ok 38 - sslcrl option with invalid file name [00:39:29.668](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [00:39:29.692](0.024s) ok 40 - CRL belonging to a different CA [00:39:29.692](0.000s) ok 41 - CRL belonging to a different CA: matches [00:39:30.114](0.422s) ok 42 - directory CRL belonging to a different CA [00:39:30.201](0.087s) ok 43 - directory CRL belonging to a different CA: matches [00:39:30.229](0.028s) ok 44 - CRL with a non-revoked cert [00:39:30.229](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [00:39:30.250](0.021s) ok 46 - directory CRL with a non-revoked cert [00:39:30.250](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [00:39:30.268](0.017s) ok 48 - mismatch between host name and server certificate sslmode=require [00:39:30.268](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [00:39:30.285](0.017s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [00:39:30.285](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [00:39:30.302](0.016s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [00:39:30.302](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 38784 [00:39:30.566](0.264s) ok 54 - IP address in the Common Name [00:39:30.566](0.000s) ok 55 - IP address in the Common Name: no stderr [00:39:30.584](0.018s) ok 56 - mismatch between host name and server certificate IP address [00:39:30.584](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 38796 [00:39:30.864](0.280s) ok 58 - IP address in a dNSName [00:39:30.864](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 38806 [00:39:31.141](0.277s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [00:39:31.141](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [00:39:31.163](0.022s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [00:39:31.163](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [00:39:31.180](0.017s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [00:39:31.180](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [00:39:31.196](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [00:39:31.196](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [00:39:31.211](0.015s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [00:39:31.211](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 38824 [00:39:31.490](0.278s) ok 70 - host name matching with a single X.509 Subject Alternative Name [00:39:31.490](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [00:39:31.509](0.019s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [00:39:31.509](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [00:39:31.525](0.016s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [00:39:31.526](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 38839 [00:39:31.793](0.268s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [00:39:31.793](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [00:39:31.814](0.020s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [00:39:31.814](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [00:39:31.830](0.016s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [00:39:31.830](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [00:39:31.847](0.016s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [00:39:31.847](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [00:39:31.863](0.016s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [00:39:31.863](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [00:39:31.879](0.016s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [00:39:31.879](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [00:39:31.895](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [00:39:31.895](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [00:39:31.911](0.015s) ok 90 - IPv6 host with CIDR mask does not match [00:39:31.911](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 38863 [00:39:32.163](0.253s) ok 92 - certificate with both a CN and SANs 1 [00:39:32.163](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [00:39:32.184](0.021s) ok 94 - certificate with both a CN and SANs 2 [00:39:32.184](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [00:39:32.201](0.016s) ok 96 - certificate with both a CN and SANs ignores CN [00:39:32.201](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 38878 [00:39:32.479](0.278s) ok 98 - certificate with both a CN and IP SANs matches CN [00:39:32.479](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [00:39:32.500](0.020s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [00:39:32.500](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [00:39:32.517](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [00:39:32.517](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 38892 [00:39:32.784](0.268s) ok 104 - certificate with both an IP CN and IP SANs 1 [00:39:32.784](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [00:39:32.805](0.021s) ok 106 - certificate with both an IP CN and IP SANs 2 [00:39:32.805](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [00:39:32.822](0.016s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [00:39:32.822](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 38906 [00:39:33.103](0.281s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [00:39:33.103](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [00:39:33.124](0.021s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [00:39:33.124](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [00:39:33.141](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [00:39:33.142](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 38920 [00:39:33.402](0.260s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [00:39:33.402](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [00:39:33.422](0.020s) ok 118 - server certificate without CN or SANs sslmode=verify-full [00:39:33.422](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 38932 [00:39:33.677](0.255s) ok 120 - sslrootcert=system does not connect with private CA [00:39:33.677](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [00:39:33.689](0.011s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [00:39:33.689](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [00:39:33.710](0.021s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [00:39:33.710](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [00:39:33.726](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [00:39:33.726](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 38947 [00:39:33.979](0.253s) ok 128 - connects without client-side CRL [00:39:33.979](0.000s) ok 129 - connects without client-side CRL: no stderr [00:39:33.995](0.016s) ok 130 - does not connect with client-side CRL file [00:39:33.995](0.000s) ok 131 - does not connect with client-side CRL file: matches [00:39:34.011](0.016s) ok 132 - does not connect with client-side CRL directory [00:39:34.011](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() [00:39:34.028](0.016s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [00:39:34.028](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [00:39:34.028](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [00:39:34.044](0.016s) ok 137 - connection success with correct range of TLS protocol versions [00:39:34.044](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [00:39:34.052](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [00:39:34.052](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [00:39:34.059](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [00:39:34.059](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [00:39:34.066](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [00:39:34.066](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [00:39:34.066](0.000s) # running server tests [00:39:34.083](0.016s) ok 145 - certificate authorization fails without client cert [00:39:34.083](0.000s) ok 146 - certificate authorization fails without client cert: matches [00:39:34.102](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [00:39:34.102](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [00:39:34.120](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [00:39:34.120](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [00:39:34.138](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [00:39:34.138](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [00:39:34.156](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [00:39:34.156](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [00:39:34.174](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [00:39:34.174](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [00:39:34.191](0.017s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [00:39:34.191](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [00:39:34.207](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [00:39:34.207](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [00:39:34.222](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [00:39:34.222](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [00:39:34.242](0.021s) ok 163 - certificate authorization succeeds with DN mapping [00:39:34.242](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [00:39:34.243](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [00:39:34.262](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [00:39:34.262](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [00:39:34.282](0.020s) ok 168 - certificate authorization succeeds with CN mapping [00:39:34.282](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [00:39:34.282](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [00:39:34.282](0.000s) not ok 171 # TODO & SKIP Need Pty support [00:39:34.282](0.000s) not ok 172 # TODO & SKIP Need Pty support [00:39:34.282](0.000s) not ok 173 # TODO & SKIP Need Pty support [00:39:34.282](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_bvwX/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [00:39:34.310](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [00:39:34.310](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [00:39:34.310](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [00:39:34.324](0.014s) ok 178 - certificate authorization fails because of file permissions [00:39:34.324](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [00:39:34.341](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [00:39:34.342](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [00:39:34.359](0.017s) ok 182 - certificate authorization fails with revoked client cert [00:39:34.359](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [00:39:34.359](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [00:39:34.379](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [00:39:34.379](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [00:39:34.379](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [00:39:34.396](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [00:39:34.396](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [00:39:34.396](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [00:39:34.414](0.017s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [00:39:34.414](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [00:39:34.414](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 39007 [00:39:34.699](0.285s) ok 194 - intermediate client certificate is provided by client [00:39:34.699](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [00:39:34.720](0.020s) ok 196 - intermediate client certificate is missing [00:39:34.720](0.000s) ok 197 - intermediate client certificate is missing: matches [00:39:34.737](0.018s) ok 198 - logged client certificate Subjects are truncated if they're too long [00:39:34.737](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches ### Restarting node "primary" # Running: pg_ctl -w -D /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 39021 [00:39:35.004](0.267s) ok 200 - intermediate client certificate is untrusted [00:39:35.004](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 39031 [00:39:35.285](0.281s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [00:39:35.285](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [00:39:35.309](0.024s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [00:39:35.309](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 39043 [00:39:35.582](0.273s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [00:39:35.582](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. [00:39:35.582](0.000s) # got: '2' # expected: '0' [00:39:35.582](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [00:39:35.582](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. [00:39:35.582](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 55694 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: '' [00:39:35.607](0.024s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [00:39:35.607](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 39055 [00:39:35.885](0.278s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [00:39:35.910](0.026s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [00:39:35.910](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 39067 [00:39:36.176](0.266s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [00:39:36.202](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [00:39:36.202](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 39079 [00:39:36.450](0.248s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [00:39:36.474](0.024s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [00:39:36.474](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 39091 [00:39:36.741](0.267s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [00:39:36.741](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. [00:39:36.741](0.000s) # got: '2' # expected: '0' [00:39:36.742](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [00:39:36.742](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. [00:39:36.742](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 55694 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: '' [00:39:36.766](0.025s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [00:39:36.766](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 39103 [00:39:37.012](0.246s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [00:39:37.036](0.024s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [00:39:37.036](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 39115 [00:39:37.293](0.257s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [00:39:37.317](0.024s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [00:39:37.317](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 39127 [00:39:37.591](0.274s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [00:39:37.615](0.025s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [00:39:37.615](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 39139 [00:39:37.874](0.259s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [00:39:37.899](0.024s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [00:39:37.899](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-03-06 00:39:22.939 UTC [38019][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:22.939 UTC [38019][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:23.145 UTC [38063][startup] LOG: database system was shut down at 2024-03-06 00:34:52 UTC 2024-03-06 00:39:23.331 UTC [38019][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:23.440 UTC [38135][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.440 UTC [38135][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-03-06 00:39:23.440 UTC [38135][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.443 UTC [38135][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-06 00:39:23.444 UTC [38135][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-06 00:39:23.460 UTC [38138][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.552 UTC [38138][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-03-06 00:39:23.552 UTC [38138][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.552 UTC [38138][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-06 00:39:23.553 UTC [38138][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.092 user=admin database=postgres host=[local] 2024-03-06 00:39:23.588 UTC [38177][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.588 UTC [38177][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-03-06 00:39:23.588 UTC [38177][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.589 UTC [38177][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-06 00:39:23.646 UTC [38177][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=admin database=postgres host=[local] 2024-03-06 00:39:23.746 UTC [38200][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.746 UTC [38200][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-03-06 00:39:23.746 UTC [38200][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.747 UTC [38200][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-06 00:39:23.747 UTC [38200][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-06 00:39:23.803 UTC [38218][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.804 UTC [38218][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-03-06 00:39:23.804 UTC [38218][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.805 UTC [38218][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-06 00:39:23.806 UTC [38218][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-06 00:39:23.845 UTC [38231][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.845 UTC [38231][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-03-06 00:39:23.845 UTC [38231][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.846 UTC [38231][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-06 00:39:23.932 UTC [38231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.086 user=admin database=postgres host=[local] 2024-03-06 00:39:23.994 UTC [38250][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:23.994 UTC [38250][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-03-06 00:39:23.994 UTC [38250][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:23.995 UTC [38250][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-06 00:39:24.438 UTC [38250][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.443 user=admin database=postgres host=[local] 2024-03-06 00:39:24.650 UTC [38320][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:24.651 UTC [38320][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-03-06 00:39:24.651 UTC [38320][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:24.653 UTC [38320][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-06 00:39:24.710 UTC [38320][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.060 user=admin database=postgres host=[local] 2024-03-06 00:39:24.782 UTC [38339][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:24.782 UTC [38339][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-03-06 00:39:24.782 UTC [38339][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:24.783 UTC [38339][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-06 00:39:25.398 UTC [38339][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.616 user=admin database=postgres host=[local] 2024-03-06 00:39:25.925 UTC [38379][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:25.926 UTC [38379][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-03-06 00:39:25.926 UTC [38379][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:25.929 UTC [38379][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-06 00:39:25.966 UTC [38379][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.041 user=admin database=postgres host=[local] 2024-03-06 00:39:25.979 UTC [38400][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:39:25.979 UTC [38400][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-03-06 00:39:25.979 UTC [38400][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-06 00:39:25.980 UTC [38400][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-06 00:39:26.309 UTC [38400][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.330 user=admin database=postgres host=[local] 2024-03-06 00:39:26.691 UTC [38019][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:26.691 UTC [38019][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:26.692 UTC [38019][postmaster] LOG: background worker "logical replication launcher" (PID 38080) exited with exit code 1 2024-03-06 00:39:26.693 UTC [38061][checkpointer] LOG: shutting down 2024-03-06 00:39:26.693 UTC [38061][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:27.701 UTC [38061][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.008 s, sync=0.001 s, total=1.009 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104C80, redo lsn=0/4104C80 2024-03-06 00:39:27.709 UTC [38019][postmaster] LOG: database system is shut down 2024-03-06 00:39:27.943 UTC [38540][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:27.944 UTC [38540][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:27.944 UTC [38540][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:28.142 UTC [38544][startup] LOG: database system was shut down at 2024-03-06 00:39:27 UTC 2024-03-06 00:39:28.144 UTC [38540][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:28.256 UTC [38540][postmaster] LOG: background worker "logical replication launcher" (PID 38550) exited with exit code 1 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: shutting down 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:28.257 UTC [38542][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104CF8, redo lsn=0/4104CF8 2024-03-06 00:39:28.258 UTC [38540][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.375 UTC [38565][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-06 00:39:28.375 UTC [38565][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.492 UTC [38582][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:28.492 UTC [38582][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:28.492 UTC [38582][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:28.495 UTC [38588][startup] LOG: database system was shut down at 2024-03-06 00:39:28 UTC 2024-03-06 00:39:28.497 UTC [38582][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:28.589 UTC [38582][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:28.589 UTC [38582][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:28.590 UTC [38582][postmaster] LOG: background worker "logical replication launcher" (PID 38591) exited with exit code 1 2024-03-06 00:39:28.590 UTC [38586][checkpointer] LOG: shutting down 2024-03-06 00:39:28.590 UTC [38586][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:28.747 UTC [38586][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.158 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104D70, redo lsn=0/4104D70 2024-03-06 00:39:28.749 UTC [38582][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.872 UTC [38623][postmaster] FATAL: could not set SSL protocol version range 2024-03-06 00:39:28.872 UTC [38623][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-06 00:39:28.872 UTC [38623][postmaster] LOG: database system is shut down 2024-03-06 00:39:28.991 UTC [38639][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:28.991 UTC [38639][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:28.991 UTC [38639][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:28.994 UTC [38655][startup] LOG: database system was shut down at 2024-03-06 00:39:28 UTC 2024-03-06 00:39:28.995 UTC [38639][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:29.084 UTC [38639][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:29.084 UTC [38639][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:29.085 UTC [38639][postmaster] LOG: background worker "logical replication launcher" (PID 38658) exited with exit code 1 2024-03-06 00:39:29.086 UTC [38653][checkpointer] LOG: shutting down 2024-03-06 00:39:29.086 UTC [38653][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:29.086 UTC [38653][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/4104DE8, redo lsn=0/4104DE8 2024-03-06 00:39:29.088 UTC [38639][postmaster] LOG: database system is shut down 2024-03-06 00:39:29.211 UTC [38684][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:29.212 UTC [38684][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:29.212 UTC [38684][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:29.217 UTC [38695][startup] LOG: database system was shut down at 2024-03-06 00:39:29 UTC 2024-03-06 00:39:29.219 UTC [38684][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:29.316 UTC [38711][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49268 2024-03-06 00:39:29.321 UTC [38711][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-03-06 00:39:29.321 UTC [38711][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-06 00:39:29.342 UTC [38716][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49271 2024-03-06 00:39:29.351 UTC [38716][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-03-06 00:39:29.351 UTC [38716][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.355 UTC [38716][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-03-06 00:39:29.356 UTC [38716][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49271 2024-03-06 00:39:29.373 UTC [38720][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49273 2024-03-06 00:39:29.379 UTC [38720][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:39:29.395 UTC [38723][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-06 00:39:29.402 UTC [38723][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:39:29.416 UTC [38727][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-06 00:39:29.423 UTC [38727][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:29.438 UTC [38730][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-03-06 00:39:29.446 UTC [38730][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:29.458 UTC [38735][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-06 00:39:29.467 UTC [38735][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:29.478 UTC [38745][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-06 00:39:29.486 UTC [38745][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:29.496 UTC [38749][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-06 00:39:29.504 UTC [38749][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-03-06 00:39:29.504 UTC [38749][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.505 UTC [38749][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-03-06 00:39:29.505 UTC [38749][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-06 00:39:29.515 UTC [38751][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49285 2024-03-06 00:39:29.522 UTC [38751][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-03-06 00:39:29.522 UTC [38751][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.523 UTC [38751][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-06 00:39:29.523 UTC [38751][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49285 2024-03-06 00:39:29.533 UTC [38753][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-06 00:39:29.541 UTC [38753][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-03-06 00:39:29.541 UTC [38753][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.541 UTC [38753][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-03-06 00:39:29.541 UTC [38753][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-06 00:39:29.551 UTC [38755][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-06 00:39:29.559 UTC [38755][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-03-06 00:39:29.559 UTC [38755][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.559 UTC [38755][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-03-06 00:39:29.559 UTC [38755][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-06 00:39:29.570 UTC [38759][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-06 00:39:29.578 UTC [38759][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-03-06 00:39:29.578 UTC [38759][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.579 UTC [38759][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-03-06 00:39:29.579 UTC [38759][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49289 2024-03-06 00:39:29.589 UTC [38762][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-06 00:39:29.596 UTC [38762][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-03-06 00:39:29.596 UTC [38762][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.597 UTC [38762][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-03-06 00:39:29.597 UTC [38762][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-06 00:39:29.609 UTC [38764][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-06 00:39:29.616 UTC [38764][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-03-06 00:39:29.616 UTC [38764][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.616 UTC [38764][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-03-06 00:39:29.617 UTC [38764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-06 00:39:29.634 UTC [38766][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-06 00:39:29.642 UTC [38766][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-03-06 00:39:29.642 UTC [38766][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.643 UTC [38766][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49292 2024-03-06 00:39:29.658 UTC [38768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-06 00:39:29.666 UTC [38768][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-03-06 00:39:29.666 UTC [38768][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:29.666 UTC [38768][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-03-06 00:39:29.666 UTC [38768][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49293 2024-03-06 00:39:29.680 UTC [38770][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-06 00:39:29.691 UTC [38770][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:29.705 UTC [38772][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-06 00:39:30.113 UTC [38772][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:30.218 UTC [38774][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-06 00:39:30.227 UTC [38774][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-03-06 00:39:30.227 UTC [38774][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:30.227 UTC [38774][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-03-06 00:39:30.228 UTC [38774][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-06 00:39:30.241 UTC [38776][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-06 00:39:30.249 UTC [38776][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-03-06 00:39:30.249 UTC [38776][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:30.249 UTC [38776][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-03-06 00:39:30.249 UTC [38776][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-06 00:39:30.259 UTC [38778][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-06 00:39:30.266 UTC [38778][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-03-06 00:39:30.266 UTC [38778][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:30.267 UTC [38778][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-03-06 00:39:30.267 UTC [38778][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-06 00:39:30.276 UTC [38780][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-06 00:39:30.284 UTC [38780][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-03-06 00:39:30.284 UTC [38780][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:30.284 UTC [38780][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-03-06 00:39:30.284 UTC [38780][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49299 2024-03-06 00:39:30.294 UTC [38782][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-06 00:39:30.305 UTC [38684][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:30.305 UTC [38684][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:30.305 UTC [38684][postmaster] LOG: background worker "logical replication launcher" (PID 38701) exited with exit code 1 2024-03-06 00:39:30.306 UTC [38691][checkpointer] LOG: shutting down 2024-03-06 00:39:30.306 UTC [38691][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:30.306 UTC [38691][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104E60, redo lsn=0/4104E60 2024-03-06 00:39:30.307 UTC [38684][postmaster] LOG: database system is shut down 2024-03-06 00:39:30.435 UTC [38784][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:30.435 UTC [38784][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:30.435 UTC [38784][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:30.437 UTC [38787][startup] LOG: database system was shut down at 2024-03-06 00:39:30 UTC 2024-03-06 00:39:30.439 UTC [38784][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:30.549 UTC [38792][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-06 00:39:30.561 UTC [38792][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-03-06 00:39:30.561 UTC [38792][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:30.564 UTC [38792][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:39:30.565 UTC [38792][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-06 00:39:30.576 UTC [38794][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-06 00:39:30.587 UTC [38784][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:30.587 UTC [38784][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:30.588 UTC [38784][postmaster] LOG: background worker "logical replication launcher" (PID 38790) exited with exit code 1 2024-03-06 00:39:30.588 UTC [38785][checkpointer] LOG: shutting down 2024-03-06 00:39:30.588 UTC [38785][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:30.589 UTC [38785][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/4104ED8, redo lsn=0/4104ED8 2024-03-06 00:39:30.590 UTC [38784][postmaster] LOG: database system is shut down 2024-03-06 00:39:30.729 UTC [38796][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:30.730 UTC [38796][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:30.730 UTC [38796][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:30.733 UTC [38799][startup] LOG: database system was shut down at 2024-03-06 00:39:30 UTC 2024-03-06 00:39:30.734 UTC [38796][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:30.845 UTC [38804][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-06 00:39:30.859 UTC [38804][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-03-06 00:39:30.859 UTC [38804][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:30.863 UTC [38804][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:39:30.863 UTC [38804][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-06 00:39:30.868 UTC [38796][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:30.868 UTC [38796][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:30.869 UTC [38796][postmaster] LOG: background worker "logical replication launcher" (PID 38802) exited with exit code 1 2024-03-06 00:39:30.869 UTC [38797][checkpointer] LOG: shutting down 2024-03-06 00:39:30.869 UTC [38797][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:30.870 UTC [38797][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/4104F50, redo lsn=0/4104F50 2024-03-06 00:39:30.871 UTC [38796][postmaster] LOG: database system is shut down 2024-03-06 00:39:31.007 UTC [38806][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:31.008 UTC [38806][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:31.008 UTC [38806][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:31.012 UTC [38809][startup] LOG: database system was shut down at 2024-03-06 00:39:30 UTC 2024-03-06 00:39:31.014 UTC [38806][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:31.120 UTC [38814][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-06 00:39:31.135 UTC [38814][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-03-06 00:39:31.135 UTC [38814][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.139 UTC [38814][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:31.139 UTC [38814][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-06 00:39:31.152 UTC [38816][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-06 00:39:31.161 UTC [38816][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-03-06 00:39:31.161 UTC [38816][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.162 UTC [38816][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:31.162 UTC [38816][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-06 00:39:31.171 UTC [38818][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-06 00:39:31.179 UTC [38818][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-03-06 00:39:31.179 UTC [38818][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.179 UTC [38818][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-06 00:39:31.179 UTC [38818][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-06 00:39:31.188 UTC [38820][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-06 00:39:31.204 UTC [38822][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-06 00:39:31.214 UTC [38806][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:31.214 UTC [38806][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:31.215 UTC [38806][postmaster] LOG: background worker "logical replication launcher" (PID 38812) exited with exit code 1 2024-03-06 00:39:31.215 UTC [38807][checkpointer] LOG: shutting down 2024-03-06 00:39:31.215 UTC [38807][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:31.216 UTC [38807][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/4104FC8, redo lsn=0/4104FC8 2024-03-06 00:39:31.217 UTC [38806][postmaster] LOG: database system is shut down 2024-03-06 00:39:31.357 UTC [38824][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:31.358 UTC [38824][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:31.358 UTC [38824][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:31.361 UTC [38827][startup] LOG: database system was shut down at 2024-03-06 00:39:31 UTC 2024-03-06 00:39:31.362 UTC [38824][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:31.471 UTC [38832][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-06 00:39:31.485 UTC [38832][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-03-06 00:39:31.485 UTC [38832][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.488 UTC [38832][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:31.489 UTC [38832][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-06 00:39:31.500 UTC [38834][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-06 00:39:31.518 UTC [38836][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-06 00:39:31.532 UTC [38824][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:31.532 UTC [38824][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:31.532 UTC [38824][postmaster] LOG: background worker "logical replication launcher" (PID 38830) exited with exit code 1 2024-03-06 00:39:31.532 UTC [38825][checkpointer] LOG: shutting down 2024-03-06 00:39:31.532 UTC [38825][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:31.533 UTC [38825][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/4105040, redo lsn=0/4105040 2024-03-06 00:39:31.534 UTC [38824][postmaster] LOG: database system is shut down 2024-03-06 00:39:31.676 UTC [38839][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:31.677 UTC [38839][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:31.677 UTC [38839][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:31.680 UTC [38842][startup] LOG: database system was shut down at 2024-03-06 00:39:31 UTC 2024-03-06 00:39:31.682 UTC [38839][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:31.776 UTC [38847][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-06 00:39:31.788 UTC [38847][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-03-06 00:39:31.788 UTC [38847][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.792 UTC [38847][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:39:31.792 UTC [38847][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-06 00:39:31.804 UTC [38849][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-06 00:39:31.812 UTC [38849][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-03-06 00:39:31.812 UTC [38849][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.813 UTC [38849][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-03-06 00:39:31.813 UTC [38849][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-06 00:39:31.822 UTC [38851][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-06 00:39:31.838 UTC [38853][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-06 00:39:31.845 UTC [38853][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-03-06 00:39:31.845 UTC [38853][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.846 UTC [38853][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-03-06 00:39:31.846 UTC [38853][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-06 00:39:31.855 UTC [38855][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-06 00:39:31.862 UTC [38855][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-03-06 00:39:31.862 UTC [38855][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.862 UTC [38855][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-03-06 00:39:31.862 UTC [38855][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-06 00:39:31.871 UTC [38857][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-06 00:39:31.878 UTC [38857][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-03-06 00:39:31.878 UTC [38857][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:31.878 UTC [38857][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-03-06 00:39:31.879 UTC [38857][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-06 00:39:31.887 UTC [38859][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-06 00:39:31.903 UTC [38861][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-06 00:39:31.914 UTC [38839][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:31.914 UTC [38839][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:31.914 UTC [38839][postmaster] LOG: background worker "logical replication launcher" (PID 38845) exited with exit code 1 2024-03-06 00:39:31.914 UTC [38840][checkpointer] LOG: shutting down 2024-03-06 00:39:31.914 UTC [38840][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:31.915 UTC [38840][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/41050B8, redo lsn=0/41050B8 2024-03-06 00:39:31.916 UTC [38839][postmaster] LOG: database system is shut down 2024-03-06 00:39:32.046 UTC [38863][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:32.046 UTC [38863][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:32.047 UTC [38863][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:32.049 UTC [38866][startup] LOG: database system was shut down at 2024-03-06 00:39:31 UTC 2024-03-06 00:39:32.051 UTC [38863][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:32.146 UTC [38871][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-06 00:39:32.159 UTC [38871][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-03-06 00:39:32.159 UTC [38871][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.162 UTC [38871][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:32.162 UTC [38871][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-06 00:39:32.175 UTC [38873][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-06 00:39:32.183 UTC [38873][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-03-06 00:39:32.183 UTC [38873][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.183 UTC [38873][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:32.184 UTC [38873][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-06 00:39:32.193 UTC [38875][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-06 00:39:32.207 UTC [38863][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:32.207 UTC [38863][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:32.208 UTC [38863][postmaster] LOG: background worker "logical replication launcher" (PID 38869) exited with exit code 1 2024-03-06 00:39:32.208 UTC [38864][checkpointer] LOG: shutting down 2024-03-06 00:39:32.208 UTC [38864][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:32.208 UTC [38864][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/4105130, redo lsn=0/4105130 2024-03-06 00:39:32.209 UTC [38863][postmaster] LOG: database system is shut down 2024-03-06 00:39:32.349 UTC [38878][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:32.350 UTC [38878][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:32.350 UTC [38878][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:32.353 UTC [38881][startup] LOG: database system was shut down at 2024-03-06 00:39:32 UTC 2024-03-06 00:39:32.354 UTC [38878][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:32.460 UTC [38886][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-06 00:39:32.474 UTC [38886][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-03-06 00:39:32.474 UTC [38886][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.478 UTC [38886][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-06 00:39:32.478 UTC [38886][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-06 00:39:32.490 UTC [38888][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-06 00:39:32.498 UTC [38888][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-03-06 00:39:32.498 UTC [38888][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.499 UTC [38888][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:39:32.499 UTC [38888][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-06 00:39:32.508 UTC [38890][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-06 00:39:32.515 UTC [38890][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-03-06 00:39:32.515 UTC [38890][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.516 UTC [38890][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-06 00:39:32.516 UTC [38890][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-06 00:39:32.520 UTC [38878][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:32.520 UTC [38878][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:32.520 UTC [38878][postmaster] LOG: background worker "logical replication launcher" (PID 38884) exited with exit code 1 2024-03-06 00:39:32.520 UTC [38879][checkpointer] LOG: shutting down 2024-03-06 00:39:32.520 UTC [38879][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:32.521 UTC [38879][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/41051A8, redo lsn=0/41051A8 2024-03-06 00:39:32.522 UTC [38878][postmaster] LOG: database system is shut down 2024-03-06 00:39:32.660 UTC [38892][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:32.661 UTC [38892][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:32.661 UTC [38892][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:32.664 UTC [38895][startup] LOG: database system was shut down at 2024-03-06 00:39:32 UTC 2024-03-06 00:39:32.665 UTC [38892][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:32.766 UTC [38900][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-06 00:39:32.779 UTC [38900][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-03-06 00:39:32.779 UTC [38900][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.783 UTC [38900][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-03-06 00:39:32.783 UTC [38900][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-06 00:39:32.795 UTC [38902][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-06 00:39:32.804 UTC [38902][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-03-06 00:39:32.804 UTC [38902][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:32.804 UTC [38902][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-06 00:39:32.804 UTC [38902][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-06 00:39:32.814 UTC [38904][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-06 00:39:32.825 UTC [38892][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:32.825 UTC [38892][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:32.825 UTC [38892][postmaster] LOG: background worker "logical replication launcher" (PID 38898) exited with exit code 1 2024-03-06 00:39:32.825 UTC [38893][checkpointer] LOG: shutting down 2024-03-06 00:39:32.825 UTC [38893][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:32.826 UTC [38893][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/4105220, redo lsn=0/4105220 2024-03-06 00:39:32.827 UTC [38892][postmaster] LOG: database system is shut down 2024-03-06 00:39:32.968 UTC [38906][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:32.969 UTC [38906][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:32.969 UTC [38906][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:32.972 UTC [38909][startup] LOG: database system was shut down at 2024-03-06 00:39:32 UTC 2024-03-06 00:39:32.973 UTC [38906][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:33.084 UTC [38914][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-06 00:39:33.098 UTC [38914][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-03-06 00:39:33.098 UTC [38914][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:33.101 UTC [38914][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:39:33.102 UTC [38914][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-06 00:39:33.114 UTC [38916][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-06 00:39:33.123 UTC [38916][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-03-06 00:39:33.123 UTC [38916][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:33.123 UTC [38916][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:33.123 UTC [38916][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-06 00:39:33.133 UTC [38918][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-06 00:39:33.140 UTC [38918][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-03-06 00:39:33.140 UTC [38918][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:33.141 UTC [38918][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-06 00:39:33.141 UTC [38918][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-06 00:39:33.144 UTC [38906][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:33.145 UTC [38906][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:33.145 UTC [38906][postmaster] LOG: background worker "logical replication launcher" (PID 38912) exited with exit code 1 2024-03-06 00:39:33.145 UTC [38907][checkpointer] LOG: shutting down 2024-03-06 00:39:33.145 UTC [38907][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:33.146 UTC [38907][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/4105298, redo lsn=0/4105298 2024-03-06 00:39:33.147 UTC [38906][postmaster] LOG: database system is shut down 2024-03-06 00:39:33.276 UTC [38920][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:33.277 UTC [38920][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:33.277 UTC [38920][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:33.280 UTC [38923][startup] LOG: database system was shut down at 2024-03-06 00:39:33 UTC 2024-03-06 00:39:33.281 UTC [38920][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:33.385 UTC [38928][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-06 00:39:33.397 UTC [38928][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-03-06 00:39:33.397 UTC [38928][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:33.401 UTC [38928][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-06 00:39:33.401 UTC [38928][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-06 00:39:33.413 UTC [38930][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-06 00:39:33.425 UTC [38920][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:33.425 UTC [38920][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:33.426 UTC [38920][postmaster] LOG: background worker "logical replication launcher" (PID 38926) exited with exit code 1 2024-03-06 00:39:33.426 UTC [38921][checkpointer] LOG: shutting down 2024-03-06 00:39:33.426 UTC [38921][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:33.426 UTC [38921][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/4105310, redo lsn=0/4105310 2024-03-06 00:39:33.427 UTC [38920][postmaster] LOG: database system is shut down 2024-03-06 00:39:33.558 UTC [38932][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:33.558 UTC [38932][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:33.559 UTC [38932][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:33.561 UTC [38935][startup] LOG: database system was shut down at 2024-03-06 00:39:33 UTC 2024-03-06 00:39:33.563 UTC [38932][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:33.663 UTC [38940][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-06 00:39:33.676 UTC [38940][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:39:33.699 UTC [38943][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-06 00:39:33.707 UTC [38943][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-03-06 00:39:33.707 UTC [38943][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:33.709 UTC [38943][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-06 00:39:33.709 UTC [38943][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-06 00:39:33.718 UTC [38945][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-06 00:39:33.729 UTC [38932][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:33.729 UTC [38932][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:33.729 UTC [38932][postmaster] LOG: background worker "logical replication launcher" (PID 38938) exited with exit code 1 2024-03-06 00:39:33.730 UTC [38933][checkpointer] LOG: shutting down 2024-03-06 00:39:33.730 UTC [38933][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:33.730 UTC [38933][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/4105388, redo lsn=0/4105388 2024-03-06 00:39:33.731 UTC [38932][postmaster] LOG: database system is shut down 2024-03-06 00:39:33.862 UTC [38947][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:33.862 UTC [38947][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:33.863 UTC [38947][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:33.869 UTC [38950][startup] LOG: database system was shut down at 2024-03-06 00:39:33 UTC 2024-03-06 00:39:33.871 UTC [38947][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:33.968 UTC [38955][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-06 00:39:33.976 UTC [38955][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-03-06 00:39:33.976 UTC [38955][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:33.978 UTC [38955][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-06 00:39:33.978 UTC [38955][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-06 00:39:33.987 UTC [38957][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-06 00:39:33.994 UTC [38957][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-06 00:39:34.003 UTC [38959][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-06 00:39:34.010 UTC [38959][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-06 00:39:34.019 UTC [38961][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-06 00:39:34.026 UTC [38961][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-03-06 00:39:34.026 UTC [38961][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.026 UTC [38961][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-06 00:39:34.027 UTC [38961][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-06 00:39:34.036 UTC [38963][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-06 00:39:34.043 UTC [38963][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-03-06 00:39:34.043 UTC [38963][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-03-06 00:39:34.043 UTC [38963][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-03-06 00:39:34.043 UTC [38963][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-06 00:39:34.074 UTC [38968][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-06 00:39:34.082 UTC [38968][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-06 00:39:34.090 UTC [38970][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-06 00:39:34.099 UTC [38970][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-03-06 00:39:34.099 UTC [38970][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.101 UTC [38970][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_bvwX/client.key$$ 2024-03-06 00:39:34.101 UTC [38970][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-06 00:39:34.110 UTC [38972][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-06 00:39:34.119 UTC [38972][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-03-06 00:39:34.119 UTC [38972][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.119 UTC [38972][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_bvwX/client-der.key$$ 2024-03-06 00:39:34.119 UTC [38972][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-06 00:39:34.128 UTC [38974][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-06 00:39:34.137 UTC [38974][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-03-06 00:39:34.137 UTC [38974][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.137 UTC [38974][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_bvwX/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-06 00:39:34.138 UTC [38974][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-06 00:39:34.146 UTC [38976][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-06 00:39:34.155 UTC [38976][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-03-06 00:39:34.155 UTC [38976][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.155 UTC [38976][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_bvwX/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-06 00:39:34.155 UTC [38976][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-06 00:39:34.164 UTC [38978][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-06 00:39:34.172 UTC [38978][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-03-06 00:39:34.172 UTC [38978][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.173 UTC [38978][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_bvwX/client.key$$ 2024-03-06 00:39:34.173 UTC [38978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-06 00:39:34.182 UTC [38980][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-06 00:39:34.190 UTC [38980][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-03-06 00:39:34.190 UTC [38980][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.190 UTC [38980][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_bvwX/client.key$$ 2024-03-06 00:39:34.190 UTC [38980][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-06 00:39:34.199 UTC [38982][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-06 00:39:34.206 UTC [38982][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-06 00:39:34.215 UTC [38984][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-06 00:39:34.221 UTC [38984][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:39:34.230 UTC [38986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-06 00:39:34.239 UTC [38986][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-03-06 00:39:34.239 UTC [38986][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.241 UTC [38986][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_bvwX/client-dn.key$$ 2024-03-06 00:39:34.242 UTC [38986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-06 00:39:34.251 UTC [38988][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-06 00:39:34.259 UTC [38988][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-03-06 00:39:34.259 UTC [38988][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.261 UTC [38988][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_bvwX/client-dn.key$$ 2024-03-06 00:39:34.261 UTC [38988][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-03-06 00:39:34.270 UTC [38990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-06 00:39:34.279 UTC [38990][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-03-06 00:39:34.279 UTC [38990][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.281 UTC [38990][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_bvwX/client-dn.key$$ 2024-03-06 00:39:34.281 UTC [38990][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-06 00:39:34.300 UTC [38993][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-06 00:39:34.309 UTC [38993][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-03-06 00:39:34.309 UTC [38993][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.309 UTC [38993][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-06 00:39:34.310 UTC [38993][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49354 2024-03-06 00:39:34.318 UTC [38995][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-06 00:39:34.324 UTC [38995][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:39:34.332 UTC [38997][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-06 00:39:34.341 UTC [38997][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-03-06 00:39:34.341 UTC [38997][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-06 00:39:34.341 UTC [38997][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-06 00:39:34.341 UTC [38997][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-03-06 00:39:34.349 UTC [38999][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-06 00:39:34.358 UTC [38999][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:39:34.358 UTC [38999][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:39:34.367 UTC [39001][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-06 00:39:34.376 UTC [39001][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-03-06 00:39:34.376 UTC [39001][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.378 UTC [39001][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_bvwX/client.key$$ 2024-03-06 00:39:34.378 UTC [39001][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-06 00:39:34.387 UTC [39003][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-06 00:39:34.395 UTC [39003][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-06 00:39:34.395 UTC [39003][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-06 00:39:34.395 UTC [39003][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-06 00:39:34.395 UTC [39003][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-03-06 00:39:34.404 UTC [39005][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-06 00:39:34.412 UTC [39005][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-03-06 00:39:34.412 UTC [39005][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.413 UTC [39005][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_bvwX/client.key$$ 2024-03-06 00:39:34.413 UTC [39005][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-06 00:39:34.417 UTC [38947][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:34.417 UTC [38947][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:34.418 UTC [38947][postmaster] LOG: background worker "logical replication launcher" (PID 38953) exited with exit code 1 2024-03-06 00:39:34.418 UTC [38948][checkpointer] LOG: shutting down 2024-03-06 00:39:34.418 UTC [38948][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:34.418 UTC [38948][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/4105400, redo lsn=0/4105400 2024-03-06 00:39:34.419 UTC [38947][postmaster] LOG: database system is shut down 2024-03-06 00:39:34.561 UTC [39007][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:34.562 UTC [39007][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:34.562 UTC [39007][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:34.565 UTC [39010][startup] LOG: database system was shut down at 2024-03-06 00:39:34 UTC 2024-03-06 00:39:34.567 UTC [39007][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:34.678 UTC [39015][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-06 00:39:34.695 UTC [39015][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-03-06 00:39:34.695 UTC [39015][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:34.698 UTC [39015][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_bvwX/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-06 00:39:34.698 UTC [39015][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=ssltestuser database=certdb host=localhost port=49361 2024-03-06 00:39:34.710 UTC [39017][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-06 00:39:34.719 UTC [39017][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:39:34.719 UTC [39017][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:39:34.728 UTC [39019][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-06 00:39:34.736 UTC [39019][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:39:34.736 UTC [39019][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:39:34.740 UTC [39007][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:34.740 UTC [39007][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:34.741 UTC [39007][postmaster] LOG: background worker "logical replication launcher" (PID 39013) exited with exit code 1 2024-03-06 00:39:34.741 UTC [39008][checkpointer] LOG: shutting down 2024-03-06 00:39:34.741 UTC [39008][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:34.742 UTC [39008][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/4105478, redo lsn=0/4105478 2024-03-06 00:39:34.743 UTC [39007][postmaster] LOG: database system is shut down 2024-03-06 00:39:34.879 UTC [39021][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:34.879 UTC [39021][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:34.879 UTC [39021][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:34.882 UTC [39024][startup] LOG: database system was shut down at 2024-03-06 00:39:34 UTC 2024-03-06 00:39:34.883 UTC [39021][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:34.988 UTC [39029][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-06 00:39:35.003 UTC [39029][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:39:35.003 UTC [39029][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-03-06 00:39:35.008 UTC [39021][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:35.009 UTC [39021][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:35.009 UTC [39021][postmaster] LOG: background worker "logical replication launcher" (PID 39027) exited with exit code 1 2024-03-06 00:39:35.009 UTC [39022][checkpointer] LOG: shutting down 2024-03-06 00:39:35.009 UTC [39022][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:35.010 UTC [39022][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/41054F0, redo lsn=0/41054F0 2024-03-06 00:39:35.011 UTC [39021][postmaster] LOG: database system is shut down 2024-03-06 00:39:35.168 UTC [39031][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:35.169 UTC [39031][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:35.169 UTC [39031][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:35.174 UTC [39034][startup] LOG: database system was shut down at 2024-03-06 00:39:35 UTC 2024-03-06 00:39:35.176 UTC [39031][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:35.267 UTC [39039][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-06 00:39:35.283 UTC [39039][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:39:35.283 UTC [39039][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:39:35.297 UTC [39041][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-06 00:39:35.308 UTC [39041][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:39:35.308 UTC [39041][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:39:35.312 UTC [39031][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:35.312 UTC [39031][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:35.313 UTC [39031][postmaster] LOG: background worker "logical replication launcher" (PID 39037) exited with exit code 1 2024-03-06 00:39:35.313 UTC [39032][checkpointer] LOG: shutting down 2024-03-06 00:39:35.313 UTC [39032][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:35.313 UTC [39032][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/4105568, redo lsn=0/4105568 2024-03-06 00:39:35.314 UTC [39031][postmaster] LOG: database system is shut down 2024-03-06 00:39:35.451 UTC [39043][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:35.452 UTC [39043][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:35.452 UTC [39043][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:35.454 UTC [39046][startup] LOG: database system was shut down at 2024-03-06 00:39:35 UTC 2024-03-06 00:39:35.456 UTC [39043][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:35.569 UTC [39051][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-06 00:39:35.579 UTC [39051][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:35.580 UTC [39051][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:35.594 UTC [39053][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-06 00:39:35.603 UTC [39053][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-03-06 00:39:35.603 UTC [39053][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:35.605 UTC [39053][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:35.606 UTC [39053][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-06 00:39:35.610 UTC [39043][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:35.610 UTC [39043][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:35.611 UTC [39043][postmaster] LOG: background worker "logical replication launcher" (PID 39049) exited with exit code 1 2024-03-06 00:39:35.611 UTC [39044][checkpointer] LOG: shutting down 2024-03-06 00:39:35.611 UTC [39044][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:35.611 UTC [39044][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/41055E0, redo lsn=0/41055E0 2024-03-06 00:39:35.612 UTC [39043][postmaster] LOG: database system is shut down 2024-03-06 00:39:35.754 UTC [39055][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:35.755 UTC [39055][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:35.755 UTC [39055][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:35.758 UTC [39058][startup] LOG: database system was shut down at 2024-03-06 00:39:35 UTC 2024-03-06 00:39:35.760 UTC [39055][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:35.871 UTC [39063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-06 00:39:35.882 UTC [39063][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:35.882 UTC [39063][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:35.897 UTC [39065][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-06 00:39:35.906 UTC [39065][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-03-06 00:39:35.906 UTC [39065][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:35.909 UTC [39065][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:35.909 UTC [39065][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-06 00:39:35.913 UTC [39055][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:35.914 UTC [39055][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:35.914 UTC [39055][postmaster] LOG: background worker "logical replication launcher" (PID 39061) exited with exit code 1 2024-03-06 00:39:35.914 UTC [39056][checkpointer] LOG: shutting down 2024-03-06 00:39:35.914 UTC [39056][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:35.915 UTC [39056][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/4105658, redo lsn=0/4105658 2024-03-06 00:39:35.916 UTC [39055][postmaster] LOG: database system is shut down 2024-03-06 00:39:36.059 UTC [39067][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:36.060 UTC [39067][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:36.060 UTC [39067][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:36.063 UTC [39070][startup] LOG: database system was shut down at 2024-03-06 00:39:35 UTC 2024-03-06 00:39:36.065 UTC [39067][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:36.163 UTC [39075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-06 00:39:36.174 UTC [39075][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:36.174 UTC [39075][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:36.189 UTC [39077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-06 00:39:36.198 UTC [39077][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-03-06 00:39:36.198 UTC [39077][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:36.200 UTC [39077][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:36.201 UTC [39077][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-06 00:39:36.205 UTC [39067][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:36.205 UTC [39067][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:36.206 UTC [39067][postmaster] LOG: background worker "logical replication launcher" (PID 39073) exited with exit code 1 2024-03-06 00:39:36.206 UTC [39068][checkpointer] LOG: shutting down 2024-03-06 00:39:36.206 UTC [39068][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:36.206 UTC [39068][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/41056D0, redo lsn=0/41056D0 2024-03-06 00:39:36.207 UTC [39067][postmaster] LOG: database system is shut down 2024-03-06 00:39:36.341 UTC [39079][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:36.342 UTC [39079][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:36.342 UTC [39079][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:36.345 UTC [39082][startup] LOG: database system was shut down at 2024-03-06 00:39:36 UTC 2024-03-06 00:39:36.346 UTC [39079][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:36.439 UTC [39087][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-06 00:39:36.448 UTC [39087][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:36.448 UTC [39087][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:36.462 UTC [39089][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-06 00:39:36.471 UTC [39089][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-03-06 00:39:36.471 UTC [39089][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:36.473 UTC [39089][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:36.473 UTC [39089][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-06 00:39:36.478 UTC [39079][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:36.478 UTC [39079][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:36.478 UTC [39079][postmaster] LOG: background worker "logical replication launcher" (PID 39085) exited with exit code 1 2024-03-06 00:39:36.478 UTC [39080][checkpointer] LOG: shutting down 2024-03-06 00:39:36.479 UTC [39080][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:36.479 UTC [39080][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/4105748, redo lsn=0/4105748 2024-03-06 00:39:36.480 UTC [39079][postmaster] LOG: database system is shut down 2024-03-06 00:39:36.627 UTC [39091][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:36.628 UTC [39091][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:36.628 UTC [39091][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:36.631 UTC [39094][startup] LOG: database system was shut down at 2024-03-06 00:39:36 UTC 2024-03-06 00:39:36.633 UTC [39091][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:36.729 UTC [39099][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-06 00:39:36.739 UTC [39099][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:36.739 UTC [39099][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:36.754 UTC [39101][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-06 00:39:36.762 UTC [39101][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-03-06 00:39:36.762 UTC [39101][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:36.765 UTC [39101][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:36.765 UTC [39101][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-06 00:39:36.770 UTC [39091][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:36.770 UTC [39091][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:36.770 UTC [39091][postmaster] LOG: background worker "logical replication launcher" (PID 39097) exited with exit code 1 2024-03-06 00:39:36.770 UTC [39092][checkpointer] LOG: shutting down 2024-03-06 00:39:36.770 UTC [39092][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:36.771 UTC [39092][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/41057C0, redo lsn=0/41057C0 2024-03-06 00:39:36.772 UTC [39091][postmaster] LOG: database system is shut down 2024-03-06 00:39:36.896 UTC [39103][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:36.897 UTC [39103][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:36.897 UTC [39103][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:36.900 UTC [39106][startup] LOG: database system was shut down at 2024-03-06 00:39:36 UTC 2024-03-06 00:39:36.901 UTC [39103][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:37.001 UTC [39111][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-06 00:39:37.010 UTC [39111][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:37.010 UTC [39111][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:37.024 UTC [39113][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-06 00:39:37.033 UTC [39113][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-03-06 00:39:37.033 UTC [39113][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:37.035 UTC [39113][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:37.035 UTC [39113][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-06 00:39:37.040 UTC [39103][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:37.040 UTC [39103][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:37.040 UTC [39103][postmaster] LOG: background worker "logical replication launcher" (PID 39109) exited with exit code 1 2024-03-06 00:39:37.040 UTC [39104][checkpointer] LOG: shutting down 2024-03-06 00:39:37.041 UTC [39104][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:37.041 UTC [39104][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/4105838, redo lsn=0/4105838 2024-03-06 00:39:37.042 UTC [39103][postmaster] LOG: database system is shut down 2024-03-06 00:39:37.183 UTC [39115][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:37.183 UTC [39115][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:37.183 UTC [39115][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:37.186 UTC [39118][startup] LOG: database system was shut down at 2024-03-06 00:39:37 UTC 2024-03-06 00:39:37.188 UTC [39115][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:37.281 UTC [39123][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-06 00:39:37.291 UTC [39123][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:37.291 UTC [39123][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:37.305 UTC [39125][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-06 00:39:37.313 UTC [39125][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-03-06 00:39:37.313 UTC [39125][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:37.316 UTC [39125][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:37.316 UTC [39125][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-06 00:39:37.320 UTC [39115][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:37.320 UTC [39115][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:37.321 UTC [39115][postmaster] LOG: background worker "logical replication launcher" (PID 39121) exited with exit code 1 2024-03-06 00:39:37.321 UTC [39116][checkpointer] LOG: shutting down 2024-03-06 00:39:37.321 UTC [39116][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:37.322 UTC [39116][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/41058B0, redo lsn=0/41058B0 2024-03-06 00:39:37.323 UTC [39115][postmaster] LOG: database system is shut down 2024-03-06 00:39:37.469 UTC [39127][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:37.470 UTC [39127][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:37.470 UTC [39127][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:37.474 UTC [39130][startup] LOG: database system was shut down at 2024-03-06 00:39:37 UTC 2024-03-06 00:39:37.476 UTC [39127][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:37.577 UTC [39135][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-06 00:39:37.588 UTC [39135][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:37.588 UTC [39135][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:37.603 UTC [39137][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-06 00:39:37.611 UTC [39137][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-03-06 00:39:37.612 UTC [39137][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:37.614 UTC [39137][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:37.614 UTC [39137][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-06 00:39:37.619 UTC [39127][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:37.619 UTC [39127][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:37.619 UTC [39127][postmaster] LOG: background worker "logical replication launcher" (PID 39133) exited with exit code 1 2024-03-06 00:39:37.619 UTC [39128][checkpointer] LOG: shutting down 2024-03-06 00:39:37.619 UTC [39128][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:37.620 UTC [39128][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/4105928, redo lsn=0/4105928 2024-03-06 00:39:37.621 UTC [39127][postmaster] LOG: database system is shut down 2024-03-06 00:39:37.761 UTC [39139][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-06 00:39:37.762 UTC [39139][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55694 2024-03-06 00:39:37.762 UTC [39139][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/iVIxpyYnk_/.s.PGSQL.55694" 2024-03-06 00:39:37.765 UTC [39142][startup] LOG: database system was shut down at 2024-03-06 00:39:37 UTC 2024-03-06 00:39:37.767 UTC [39139][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:39:37.863 UTC [39147][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-06 00:39:37.873 UTC [39147][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:39:37.873 UTC [39147][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:39:37.886 UTC [39149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-06 00:39:37.895 UTC [39149][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-03-06 00:39:37.895 UTC [39149][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:39:37.898 UTC [39149][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:39:37.898 UTC [39149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-06 00:39:37.902 UTC [39139][postmaster] LOG: received fast shutdown request 2024-03-06 00:39:37.902 UTC [39139][postmaster] LOG: aborting any active transactions 2024-03-06 00:39:37.903 UTC [39139][postmaster] LOG: background worker "logical replication launcher" (PID 39145) exited with exit code 1 2024-03-06 00:39:37.903 UTC [39140][checkpointer] LOG: shutting down 2024-03-06 00:39:37.903 UTC [39140][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:39:37.903 UTC [39140][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/41059A0, redo lsn=0/41059A0 2024-03-06 00:39:37.904 UTC [39139][postmaster] LOG: database system is shut down 2024-03-06 00:39:38.046 UTC [39151][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": SSL error code 2147483650 2024-03-06 00:39:38.046 UTC [39151][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [00:39:38.144](0.245s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"