[14:13:40.604](0.019s) # setting up data directory # Checking port 52553 # Found port 52553 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=52553 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [14:13:40.610](0.006s) # 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 38630 [14:13:41.330](0.719s) 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 39084 [14:13:45.755](4.425s) # 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-18 14:13:41.194 UTC [38630][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:41.194 UTC [38630][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:41.213 UTC [38659][startup] LOG: database system was shut down at 2024-03-18 14:08:56 UTC 2024-03-18 14:13:41.221 UTC [38630][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:41.323 UTC [38691][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.323 UTC [38691][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-18 14:13:41.323 UTC [38691][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.327 UTC [38691][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-18 14:13:41.328 UTC [38691][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=admin database=postgres host=[local] 2024-03-18 14:13:41.345 UTC [38695][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.435 UTC [38695][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-18 14:13:41.435 UTC [38695][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.436 UTC [38695][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-18 14:13:41.436 UTC [38695][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.090 user=admin database=postgres host=[local] 2024-03-18 14:13:41.476 UTC [38705][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.476 UTC [38705][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-18 14:13:41.476 UTC [38705][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.477 UTC [38705][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-18 14:13:41.615 UTC [38705][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.138 user=admin database=postgres host=[local] 2024-03-18 14:13:41.783 UTC [38776][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.032 UTC [38776][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-18 14:13:42.032 UTC [38776][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.035 UTC [38776][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-18 14:13:42.035 UTC [38776][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.253 user=admin database=postgres host=[local] 2024-03-18 14:13:42.097 UTC [38798][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.097 UTC [38798][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-18 14:13:42.098 UTC [38798][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.098 UTC [38798][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-18 14:13:42.099 UTC [38798][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-18 14:13:42.149 UTC [38815][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.150 UTC [38815][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-18 14:13:42.150 UTC [38815][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.150 UTC [38815][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-18 14:13:42.196 UTC [38815][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=admin database=postgres host=[local] 2024-03-18 14:13:42.288 UTC [38836][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.289 UTC [38836][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-18 14:13:42.289 UTC [38836][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.289 UTC [38836][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-18 14:13:42.518 UTC [38836][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.229 user=admin database=postgres host=[local] 2024-03-18 14:13:42.593 UTC [38875][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.593 UTC [38875][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-18 14:13:42.593 UTC [38875][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.594 UTC [38875][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-18 14:13:42.694 UTC [38875][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.100 user=admin database=postgres host=[local] 2024-03-18 14:13:42.724 UTC [38893][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.724 UTC [38893][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-18 14:13:42.724 UTC [38893][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.725 UTC [38893][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-18 14:13:43.179 UTC [38893][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.454 user=admin database=postgres host=[local] 2024-03-18 14:13:43.369 UTC [38920][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:43.764 UTC [38920][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-18 14:13:43.764 UTC [38920][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:43.765 UTC [38920][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-18 14:13:43.937 UTC [38920][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.567 user=admin database=postgres host=[local] 2024-03-18 14:13:44.000 UTC [38939][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:44.001 UTC [38939][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-18 14:13:44.001 UTC [38939][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:44.001 UTC [38939][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-18 14:13:44.151 UTC [38939][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.150 user=admin database=postgres host=[local] 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: background worker "logical replication launcher" (PID 38671) exited with exit code 1 2024-03-18 14:13:44.522 UTC [38654][checkpointer] LOG: shutting down 2024-03-18 14:13:44.522 UTC [38654][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:45.456 UTC [38654][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.934 s, sync=0.001 s, total=0.935 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102DB0, redo lsn=0/4102DB0 2024-03-18 14:13:45.464 UTC [38630][postmaster] LOG: database system is shut down 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:45.649 UTC [39094][startup] LOG: database system was shut down at 2024-03-18 14:13:45 UTC 2024-03-18 14:13:45.650 UTC [39084][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:45.759 UTC [39084][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:45.759 UTC [39084][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:45.760 UTC [39084][postmaster] LOG: background worker "logical replication launcher" (PID 39097) exited with exit code 1 2024-03-18 14:13:45.760 UTC [39092][checkpointer] LOG: shutting down 2024-03-18 14:13:45.760 UTC [39092][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:45.761 UTC [39092][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102E28, redo lsn=0/4102E28 2024-03-18 14:13:45.762 UTC [39084][postmaster] LOG: database system is shut down 2024-03-18 14:13:45.888 UTC [39104][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-18 14:13:46.003 UTC [39104][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:13:46.115](0.360s) 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 39123 [14:13:46.357](0.242s) 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-18 14:13:41.194 UTC [38630][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:41.194 UTC [38630][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:41.213 UTC [38659][startup] LOG: database system was shut down at 2024-03-18 14:08:56 UTC 2024-03-18 14:13:41.221 UTC [38630][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:41.323 UTC [38691][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.323 UTC [38691][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-18 14:13:41.323 UTC [38691][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.327 UTC [38691][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-18 14:13:41.328 UTC [38691][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=admin database=postgres host=[local] 2024-03-18 14:13:41.345 UTC [38695][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.435 UTC [38695][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-18 14:13:41.435 UTC [38695][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.436 UTC [38695][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-18 14:13:41.436 UTC [38695][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.090 user=admin database=postgres host=[local] 2024-03-18 14:13:41.476 UTC [38705][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.476 UTC [38705][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-18 14:13:41.476 UTC [38705][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.477 UTC [38705][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-18 14:13:41.615 UTC [38705][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.138 user=admin database=postgres host=[local] 2024-03-18 14:13:41.783 UTC [38776][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.032 UTC [38776][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-18 14:13:42.032 UTC [38776][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.035 UTC [38776][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-18 14:13:42.035 UTC [38776][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.253 user=admin database=postgres host=[local] 2024-03-18 14:13:42.097 UTC [38798][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.097 UTC [38798][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-18 14:13:42.098 UTC [38798][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.098 UTC [38798][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-18 14:13:42.099 UTC [38798][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-18 14:13:42.149 UTC [38815][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.150 UTC [38815][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-18 14:13:42.150 UTC [38815][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.150 UTC [38815][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-18 14:13:42.196 UTC [38815][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=admin database=postgres host=[local] 2024-03-18 14:13:42.288 UTC [38836][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.289 UTC [38836][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-18 14:13:42.289 UTC [38836][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.289 UTC [38836][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-18 14:13:42.518 UTC [38836][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.229 user=admin database=postgres host=[local] 2024-03-18 14:13:42.593 UTC [38875][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.593 UTC [38875][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-18 14:13:42.593 UTC [38875][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.594 UTC [38875][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-18 14:13:42.694 UTC [38875][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.100 user=admin database=postgres host=[local] 2024-03-18 14:13:42.724 UTC [38893][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.724 UTC [38893][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-18 14:13:42.724 UTC [38893][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.725 UTC [38893][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-18 14:13:43.179 UTC [38893][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.454 user=admin database=postgres host=[local] 2024-03-18 14:13:43.369 UTC [38920][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:43.764 UTC [38920][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-18 14:13:43.764 UTC [38920][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:43.765 UTC [38920][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-18 14:13:43.937 UTC [38920][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.567 user=admin database=postgres host=[local] 2024-03-18 14:13:44.000 UTC [38939][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:44.001 UTC [38939][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-18 14:13:44.001 UTC [38939][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:44.001 UTC [38939][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-18 14:13:44.151 UTC [38939][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.150 user=admin database=postgres host=[local] 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: background worker "logical replication launcher" (PID 38671) exited with exit code 1 2024-03-18 14:13:44.522 UTC [38654][checkpointer] LOG: shutting down 2024-03-18 14:13:44.522 UTC [38654][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:45.456 UTC [38654][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.934 s, sync=0.001 s, total=0.935 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102DB0, redo lsn=0/4102DB0 2024-03-18 14:13:45.464 UTC [38630][postmaster] LOG: database system is shut down 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:45.649 UTC [39094][startup] LOG: database system was shut down at 2024-03-18 14:13:45 UTC 2024-03-18 14:13:45.650 UTC [39084][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:45.759 UTC [39084][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:45.759 UTC [39084][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:45.760 UTC [39084][postmaster] LOG: background worker "logical replication launcher" (PID 39097) exited with exit code 1 2024-03-18 14:13:45.760 UTC [39092][checkpointer] LOG: shutting down 2024-03-18 14:13:45.760 UTC [39092][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:45.761 UTC [39092][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102E28, redo lsn=0/4102E28 2024-03-18 14:13:45.762 UTC [39084][postmaster] LOG: database system is shut down 2024-03-18 14:13:45.888 UTC [39104][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-18 14:13:46.003 UTC [39104][postmaster] LOG: database system is shut down 2024-03-18 14:13:46.286 UTC [39123][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:46.286 UTC [39123][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:46.286 UTC [39123][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:46.289 UTC [39143][startup] LOG: database system was shut down at 2024-03-18 14:13:45 UTC 2024-03-18 14:13:46.290 UTC [39123][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:46.362 UTC [39123][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:46.362 UTC [39123][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:46.363 UTC [39123][postmaster] LOG: background worker "logical replication launcher" (PID 39146) exited with exit code 1 2024-03-18 14:13:46.363 UTC [39141][checkpointer] LOG: shutting down 2024-03-18 14:13:46.363 UTC [39141][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:46.363 UTC [39141][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/4102EA0, redo lsn=0/4102EA0 2024-03-18 14:13:46.364 UTC [39123][postmaster] LOG: database system is shut down 2024-03-18 14:13:46.488 UTC [39162][postmaster] FATAL: could not set SSL protocol version range 2024-03-18 14:13:46.488 UTC [39162][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-18 14:13:46.488 UTC [39162][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:13:46.584](0.227s) 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 39174 [14:13:46.697](0.113s) ok 5 - restart succeeds with correct SSL protocol bounds [14:13:46.697](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 39207 [14:13:47.071](0.374s) ok 6 - server doesn't accept non-SSL connections [14:13:47.071](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [14:13:47.091](0.020s) ok 8 - connect without server root cert sslmode=require [14:13:47.091](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [14:13:47.105](0.014s) ok 10 - connect without server root cert sslmode=verify-ca [14:13:47.106](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [14:13:47.121](0.015s) ok 12 - connect without server root cert sslmode=verify-full [14:13:47.121](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [14:13:47.138](0.017s) ok 14 - connect with wrong server root cert sslmode=require [14:13:47.138](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [14:13:47.154](0.017s) ok 16 - connect with wrong server root cert sslmode=verify-ca [14:13:47.155](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [14:13:47.173](0.018s) ok 18 - connect with wrong server root cert sslmode=verify-full [14:13:47.173](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [14:13:47.190](0.017s) ok 20 - connect with server CA cert, without root CA [14:13:47.190](0.000s) ok 21 - connect with server CA cert, without root CA: matches [14:13:47.213](0.023s) ok 22 - connect with correct server CA cert file sslmode=require [14:13:47.213](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [14:13:47.240](0.027s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [14:13:47.240](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [14:13:47.273](0.033s) ok 26 - connect with correct server CA cert file sslmode=verify-full [14:13:47.273](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [14:13:47.297](0.025s) ok 28 - cert root file that contains two certificates, order 1 [14:13:47.298](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [14:13:47.316](0.019s) ok 30 - cert root file that contains two certificates, order 2 [14:13:47.316](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [14:13:47.334](0.018s) ok 32 - connect with sslcertmode=disable [14:13:47.334](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [14:13:47.352](0.017s) ok 34 - connect with sslcertmode=allow [14:13:47.352](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [14:13:47.371](0.019s) ok 36 - connect with sslcertmode=require fails without a client certificate [14:13:47.371](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [14:13:47.390](0.019s) ok 38 - sslcrl option with invalid file name [14:13:47.390](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [14:13:47.408](0.018s) ok 40 - CRL belonging to a different CA [14:13:47.408](0.000s) ok 41 - CRL belonging to a different CA: matches [14:13:47.425](0.017s) ok 42 - directory CRL belonging to a different CA [14:13:47.425](0.000s) ok 43 - directory CRL belonging to a different CA: matches [14:13:47.444](0.020s) ok 44 - CRL with a non-revoked cert [14:13:47.444](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [14:13:47.461](0.017s) ok 46 - directory CRL with a non-revoked cert [14:13:47.461](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [14:13:47.477](0.016s) ok 48 - mismatch between host name and server certificate sslmode=require [14:13:47.478](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [14:13:47.498](0.020s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [14:13:47.498](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [14:13:47.521](0.023s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [14:13:47.522](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 39332 [14:13:47.782](0.261s) ok 54 - IP address in the Common Name [14:13:47.782](0.000s) ok 55 - IP address in the Common Name: no stderr [14:13:47.800](0.017s) ok 56 - mismatch between host name and server certificate IP address [14:13:47.800](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 39353 [14:13:48.043](0.244s) ok 58 - IP address in a dNSName [14:13:48.044](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 39363 [14:13:48.318](0.275s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [14:13:48.318](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [14:13:48.340](0.021s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [14:13:48.340](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [14:13:48.357](0.017s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [14:13:48.357](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [14:13:48.373](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [14:13:48.373](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [14:13:48.388](0.015s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [14:13:48.389](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 39381 [14:13:48.664](0.275s) ok 70 - host name matching with a single X.509 Subject Alternative Name [14:13:48.664](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [14:13:48.684](0.021s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [14:13:48.684](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [14:13:48.701](0.016s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [14:13:48.701](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 39396 [14:13:48.985](0.284s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [14:13:48.985](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [14:13:49.006](0.021s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [14:13:49.006](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [14:13:49.022](0.016s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [14:13:49.022](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [14:13:49.038](0.016s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [14:13:49.038](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [14:13:49.055](0.016s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [14:13:49.055](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [14:13:49.071](0.016s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [14:13:49.071](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [14:13:49.087](0.015s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [14:13:49.087](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [14:13:49.102](0.016s) ok 90 - IPv6 host with CIDR mask does not match [14:13:49.102](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 39420 [14:13:49.368](0.266s) ok 92 - certificate with both a CN and SANs 1 [14:13:49.369](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [14:13:49.390](0.021s) ok 94 - certificate with both a CN and SANs 2 [14:13:49.390](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [14:13:49.406](0.016s) ok 96 - certificate with both a CN and SANs ignores CN [14:13:49.406](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 39435 [14:13:49.659](0.253s) ok 98 - certificate with both a CN and IP SANs matches CN [14:13:49.659](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [14:13:49.678](0.020s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [14:13:49.678](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [14:13:49.696](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [14:13:49.696](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 39449 [14:13:49.978](0.282s) ok 104 - certificate with both an IP CN and IP SANs 1 [14:13:49.978](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [14:13:49.999](0.021s) ok 106 - certificate with both an IP CN and IP SANs 2 [14:13:49.999](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [14:13:50.016](0.016s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [14:13:50.016](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 39463 [14:13:50.285](0.270s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [14:13:50.285](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [14:13:50.306](0.021s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [14:13:50.307](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [14:13:50.324](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [14:13:50.324](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 39477 [14:13:50.589](0.266s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [14:13:50.589](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [14:13:50.609](0.019s) ok 118 - server certificate without CN or SANs sslmode=verify-full [14:13:50.609](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 39489 [14:13:50.871](0.263s) ok 120 - sslrootcert=system does not connect with private CA [14:13:50.872](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [14:13:50.882](0.010s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [14:13:50.882](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [14:13:50.902](0.020s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [14:13:50.902](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [14:13:50.918](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [14:13:50.918](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 39504 [14:13:51.191](0.273s) ok 128 - connects without client-side CRL [14:13:51.191](0.000s) ok 129 - connects without client-side CRL: no stderr [14:13:51.211](0.020s) ok 130 - does not connect with client-side CRL file [14:13:51.211](0.000s) ok 131 - does not connect with client-side CRL file: matches [14:13:51.228](0.017s) ok 132 - does not connect with client-side CRL directory [14:13:51.228](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() [14:13:51.244](0.016s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [14:13:51.244](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [14:13:51.244](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [14:13:51.260](0.016s) ok 137 - connection success with correct range of TLS protocol versions [14:13:51.260](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [14:13:51.268](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [14:13:51.268](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [14:13:51.275](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [14:13:51.275](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [14:13:51.282](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [14:13:51.282](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [14:13:51.282](0.000s) # running server tests [14:13:51.298](0.016s) ok 145 - certificate authorization fails without client cert [14:13:51.299](0.000s) ok 146 - certificate authorization fails without client cert: matches [14:13:51.318](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [14:13:51.318](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [14:13:51.336](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [14:13:51.336](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [14:13:51.354](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [14:13:51.354](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [14:13:51.372](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [14:13:51.372](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [14:13:51.390](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [14:13:51.390](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [14:13:51.407](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [14:13:51.407](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [14:13:51.423](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [14:13:51.423](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [14:13:51.438](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [14:13:51.438](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [14:13:51.460](0.022s) ok 163 - certificate authorization succeeds with DN mapping [14:13:51.460](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [14:13:51.460](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [14:13:51.479](0.019s) ok 166 - certificate authorization succeeds with DN regex mapping [14:13:51.479](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [14:13:51.499](0.020s) ok 168 - certificate authorization succeeds with CN mapping [14:13:51.499](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [14:13:51.499](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [14:13:51.499](0.000s) not ok 171 # TODO & SKIP Need Pty support [14:13:51.500](0.000s) not ok 172 # TODO & SKIP Need Pty support [14:13:51.500](0.000s) not ok 173 # TODO & SKIP Need Pty support [14:13:51.500](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_RWz7/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [14:13:51.528](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [14:13:51.528](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [14:13:51.528](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [14:13:51.542](0.014s) ok 178 - certificate authorization fails because of file permissions [14:13:51.542](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [14:13:51.559](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [14:13:51.559](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [14:13:51.577](0.018s) ok 182 - certificate authorization fails with revoked client cert [14:13:51.577](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [14:13:51.577](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [14:13:51.597](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [14:13:51.597](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [14:13:51.597](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [14:13:51.614](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [14:13:51.614](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [14:13:51.614](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [14:13:51.632](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [14:13:51.632](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [14:13:51.632](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 39564 [14:13:51.913](0.281s) ok 194 - intermediate client certificate is provided by client [14:13:51.913](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [14:13:51.934](0.020s) ok 196 - intermediate client certificate is missing [14:13:51.934](0.000s) ok 197 - intermediate client certificate is missing: matches [14:13:51.951](0.017s) ok 198 - logged client certificate Subjects are truncated if they're too long [14:13:51.951](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 39578 [14:13:52.221](0.270s) ok 200 - intermediate client certificate is untrusted [14:13:52.221](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 39588 [14:13:52.497](0.275s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [14:13:52.497](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [14:13:52.525](0.028s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [14:13:52.525](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 39601 [14:13:52.785](0.260s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [14:13:52.785](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. [14:13:52.786](0.000s) # got: '2' # expected: '0' [14:13:52.786](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:13:52.786](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. [14:13:52.786](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 52553 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: '' [14:13:52.811](0.025s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [14:13:52.811](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 39613 [14:13:53.082](0.272s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [14:13:53.109](0.026s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [14:13:53.109](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 39625 [14:13:53.378](0.269s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [14:13:53.404](0.026s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [14:13:53.404](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 39637 [14:13:53.677](0.274s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [14:13:53.703](0.025s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [14:13:53.703](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 39649 [14:13:53.978](0.275s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [14:13:53.978](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. [14:13:53.978](0.000s) # got: '2' # expected: '0' [14:13:53.978](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:13:53.978](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. [14:13:53.978](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 52553 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: '' [14:13:54.003](0.025s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [14:13:54.004](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 39661 [14:13:54.271](0.267s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [14:13:54.294](0.024s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [14:13:54.295](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 39673 [14:13:54.551](0.257s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [14:13:54.576](0.024s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [14:13:54.576](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 39685 [14:13:54.860](0.285s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [14:13:54.886](0.026s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [14:13:54.886](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 39697 [14:13:55.149](0.263s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [14:13:55.173](0.024s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [14:13:55.173](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-18 14:13:41.194 UTC [38630][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:41.194 UTC [38630][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:41.213 UTC [38659][startup] LOG: database system was shut down at 2024-03-18 14:08:56 UTC 2024-03-18 14:13:41.221 UTC [38630][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:41.323 UTC [38691][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.323 UTC [38691][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-18 14:13:41.323 UTC [38691][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.327 UTC [38691][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-18 14:13:41.328 UTC [38691][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=admin database=postgres host=[local] 2024-03-18 14:13:41.345 UTC [38695][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.435 UTC [38695][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-18 14:13:41.435 UTC [38695][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.436 UTC [38695][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-18 14:13:41.436 UTC [38695][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.090 user=admin database=postgres host=[local] 2024-03-18 14:13:41.476 UTC [38705][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:41.476 UTC [38705][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-18 14:13:41.476 UTC [38705][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:41.477 UTC [38705][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-18 14:13:41.615 UTC [38705][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.138 user=admin database=postgres host=[local] 2024-03-18 14:13:41.783 UTC [38776][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.032 UTC [38776][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-18 14:13:42.032 UTC [38776][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.035 UTC [38776][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-18 14:13:42.035 UTC [38776][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.253 user=admin database=postgres host=[local] 2024-03-18 14:13:42.097 UTC [38798][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.097 UTC [38798][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-18 14:13:42.098 UTC [38798][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.098 UTC [38798][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-18 14:13:42.099 UTC [38798][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-18 14:13:42.149 UTC [38815][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.150 UTC [38815][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-18 14:13:42.150 UTC [38815][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.150 UTC [38815][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-18 14:13:42.196 UTC [38815][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=admin database=postgres host=[local] 2024-03-18 14:13:42.288 UTC [38836][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.289 UTC [38836][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-18 14:13:42.289 UTC [38836][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.289 UTC [38836][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-18 14:13:42.518 UTC [38836][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.229 user=admin database=postgres host=[local] 2024-03-18 14:13:42.593 UTC [38875][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.593 UTC [38875][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-18 14:13:42.593 UTC [38875][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.594 UTC [38875][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-18 14:13:42.694 UTC [38875][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.100 user=admin database=postgres host=[local] 2024-03-18 14:13:42.724 UTC [38893][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:42.724 UTC [38893][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-18 14:13:42.724 UTC [38893][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:42.725 UTC [38893][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-18 14:13:43.179 UTC [38893][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.454 user=admin database=postgres host=[local] 2024-03-18 14:13:43.369 UTC [38920][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:43.764 UTC [38920][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-18 14:13:43.764 UTC [38920][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:43.765 UTC [38920][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-18 14:13:43.937 UTC [38920][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.567 user=admin database=postgres host=[local] 2024-03-18 14:13:44.000 UTC [38939][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-18 14:13:44.001 UTC [38939][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-18 14:13:44.001 UTC [38939][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-18 14:13:44.001 UTC [38939][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-18 14:13:44.151 UTC [38939][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.150 user=admin database=postgres host=[local] 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:44.521 UTC [38630][postmaster] LOG: background worker "logical replication launcher" (PID 38671) exited with exit code 1 2024-03-18 14:13:44.522 UTC [38654][checkpointer] LOG: shutting down 2024-03-18 14:13:44.522 UTC [38654][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:45.456 UTC [38654][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.934 s, sync=0.001 s, total=0.935 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102DB0, redo lsn=0/4102DB0 2024-03-18 14:13:45.464 UTC [38630][postmaster] LOG: database system is shut down 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:45.645 UTC [39084][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:45.649 UTC [39094][startup] LOG: database system was shut down at 2024-03-18 14:13:45 UTC 2024-03-18 14:13:45.650 UTC [39084][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:45.759 UTC [39084][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:45.759 UTC [39084][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:45.760 UTC [39084][postmaster] LOG: background worker "logical replication launcher" (PID 39097) exited with exit code 1 2024-03-18 14:13:45.760 UTC [39092][checkpointer] LOG: shutting down 2024-03-18 14:13:45.760 UTC [39092][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:45.761 UTC [39092][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102E28, redo lsn=0/4102E28 2024-03-18 14:13:45.762 UTC [39084][postmaster] LOG: database system is shut down 2024-03-18 14:13:45.888 UTC [39104][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-18 14:13:46.003 UTC [39104][postmaster] LOG: database system is shut down 2024-03-18 14:13:46.286 UTC [39123][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:46.286 UTC [39123][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:46.286 UTC [39123][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:46.289 UTC [39143][startup] LOG: database system was shut down at 2024-03-18 14:13:45 UTC 2024-03-18 14:13:46.290 UTC [39123][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:46.362 UTC [39123][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:46.362 UTC [39123][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:46.363 UTC [39123][postmaster] LOG: background worker "logical replication launcher" (PID 39146) exited with exit code 1 2024-03-18 14:13:46.363 UTC [39141][checkpointer] LOG: shutting down 2024-03-18 14:13:46.363 UTC [39141][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:46.363 UTC [39141][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/4102EA0, redo lsn=0/4102EA0 2024-03-18 14:13:46.364 UTC [39123][postmaster] LOG: database system is shut down 2024-03-18 14:13:46.488 UTC [39162][postmaster] FATAL: could not set SSL protocol version range 2024-03-18 14:13:46.488 UTC [39162][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-18 14:13:46.488 UTC [39162][postmaster] LOG: database system is shut down 2024-03-18 14:13:46.616 UTC [39174][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:46.617 UTC [39174][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:46.620 UTC [39174][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:46.635 UTC [39188][startup] LOG: database system was shut down at 2024-03-18 14:13:46 UTC 2024-03-18 14:13:46.637 UTC [39174][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:46.727 UTC [39174][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:46.727 UTC [39174][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:46.729 UTC [39174][postmaster] LOG: background worker "logical replication launcher" (PID 39191) exited with exit code 1 2024-03-18 14:13:46.729 UTC [39186][checkpointer] LOG: shutting down 2024-03-18 14:13:46.729 UTC [39186][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:46.896 UTC [39186][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.168 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102F18, redo lsn=0/4102F18 2024-03-18 14:13:46.897 UTC [39174][postmaster] LOG: database system is shut down 2024-03-18 14:13:46.993 UTC [39207][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:46.994 UTC [39207][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:46.994 UTC [39207][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:46.998 UTC [39217][startup] LOG: database system was shut down at 2024-03-18 14:13:46 UTC 2024-03-18 14:13:46.999 UTC [39207][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:47.066 UTC [39238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49262 2024-03-18 14:13:47.070 UTC [39238][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-18 14:13:47.070 UTC [39238][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-18 14:13:47.080 UTC [39242][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49264 2024-03-18 14:13:47.088 UTC [39242][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-18 14:13:47.088 UTC [39242][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-18 14:13:47.090 UTC [39242][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-18 14:13:47.090 UTC [39242][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49264 2024-03-18 14:13:47.101 UTC [39246][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49266 2024-03-18 14:13:47.105 UTC [39246][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-18 14:13:47.115 UTC [39249][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49267 2024-03-18 14:13:47.120 UTC [39249][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-18 14:13:47.129 UTC [39252][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49269 2024-03-18 14:13:47.137 UTC [39252][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:47.146 UTC [39255][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49270 2024-03-18 14:13:47.153 UTC [39255][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:47.164 UTC [39264][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49271 2024-03-18 14:13:47.172 UTC [39264][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:47.182 UTC [39268][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49273 2024-03-18 14:13:47.189 UTC [39268][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:47.202 UTC [39272][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-18 14:13:47.211 UTC [39272][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-18 14:13:47.211 UTC [39272][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-18 14:13:47.211 UTC [39272][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-18 14:13:47.212 UTC [39272][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49275 2024-03-18 14:13:47.224 UTC [39276][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-18 14:13:47.238 UTC [39276][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-18 14:13:47.238 UTC [39276][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-18 14:13:47.238 UTC [39276][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-18 14:13:47.239 UTC [39276][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49277 2024-03-18 14:13:47.256 UTC [39281][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49279 2024-03-18 14:13:47.270 UTC [39281][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-18 14:13:47.270 UTC [39281][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-18 14:13:47.271 UTC [39281][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-18 14:13:47.271 UTC [39281][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49279 2024-03-18 14:13:47.287 UTC [39286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-18 14:13:47.295 UTC [39286][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-18 14:13:47.295 UTC [39286][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-18 14:13:47.296 UTC [39286][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-18 14:13:47.296 UTC [39286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49282 2024-03-18 14:13:47.307 UTC [39290][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-18 14:13:47.315 UTC [39290][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-18 14:13:47.315 UTC [39290][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-18 14:13:47.315 UTC [39290][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-18 14:13:47.315 UTC [39290][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-18 14:13:47.325 UTC [39294][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-18 14:13:47.332 UTC [39294][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-18 14:13:47.332 UTC [39294][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-18 14:13:47.333 UTC [39294][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-18 14:13:47.333 UTC [39294][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-18 14:13:47.343 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-18 14:13:47.350 UTC [39298][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-18 14:13:47.350 UTC [39298][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-18 14:13:47.350 UTC [39298][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-18 14:13:47.351 UTC [39298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-18 14:13:47.361 UTC [39301][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-18 14:13:47.369 UTC [39301][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-18 14:13:47.369 UTC [39301][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-18 14:13:47.370 UTC [39301][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49289 2024-03-18 14:13:47.380 UTC [39305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-18 14:13:47.388 UTC [39305][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-18 14:13:47.388 UTC [39305][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-18 14:13:47.389 UTC [39305][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-18 14:13:47.389 UTC [39305][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-18 14:13:47.399 UTC [39314][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-18 14:13:47.407 UTC [39314][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:47.416 UTC [39316][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-18 14:13:47.423 UTC [39316][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:47.433 UTC [39318][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-18 14:13:47.443 UTC [39318][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-18 14:13:47.443 UTC [39318][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-18 14:13:47.443 UTC [39318][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-18 14:13:47.443 UTC [39318][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49294 2024-03-18 14:13:47.452 UTC [39320][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-18 14:13:47.460 UTC [39320][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-18 14:13:47.460 UTC [39320][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-18 14:13:47.460 UTC [39320][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-18 14:13:47.460 UTC [39320][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49295 2024-03-18 14:13:47.469 UTC [39322][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-18 14:13:47.476 UTC [39322][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-18 14:13:47.476 UTC [39322][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-18 14:13:47.477 UTC [39322][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-18 14:13:47.477 UTC [39322][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-18 14:13:47.487 UTC [39324][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-18 14:13:47.496 UTC [39324][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-18 14:13:47.496 UTC [39324][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-18 14:13:47.497 UTC [39324][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-18 14:13:47.497 UTC [39324][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-18 14:13:47.509 UTC [39328][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-18 14:13:47.526 UTC [39207][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:47.526 UTC [39207][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:47.528 UTC [39207][postmaster] LOG: background worker "logical replication launcher" (PID 39223) exited with exit code 1 2024-03-18 14:13:47.528 UTC [39213][checkpointer] LOG: shutting down 2024-03-18 14:13:47.528 UTC [39213][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:47.529 UTC [39213][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/4102F90, redo lsn=0/4102F90 2024-03-18 14:13:47.530 UTC [39207][postmaster] LOG: database system is shut down 2024-03-18 14:13:47.654 UTC [39332][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:47.654 UTC [39332][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:47.654 UTC [39332][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:47.656 UTC [39341][startup] LOG: database system was shut down at 2024-03-18 14:13:47 UTC 2024-03-18 14:13:47.657 UTC [39332][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:47.768 UTC [39348][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-18 14:13:47.778 UTC [39348][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-18 14:13:47.778 UTC [39348][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-18 14:13:47.781 UTC [39348][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-18 14:13:47.781 UTC [39348][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49300 2024-03-18 14:13:47.792 UTC [39351][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-18 14:13:47.803 UTC [39332][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:47.803 UTC [39332][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:47.803 UTC [39332][postmaster] LOG: background worker "logical replication launcher" (PID 39344) exited with exit code 1 2024-03-18 14:13:47.804 UTC [39339][checkpointer] LOG: shutting down 2024-03-18 14:13:47.804 UTC [39339][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:47.804 UTC [39339][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/4103008, redo lsn=0/4103008 2024-03-18 14:13:47.805 UTC [39332][postmaster] LOG: database system is shut down 2024-03-18 14:13:47.923 UTC [39353][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:47.923 UTC [39353][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:47.923 UTC [39353][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:47.926 UTC [39356][startup] LOG: database system was shut down at 2024-03-18 14:13:47 UTC 2024-03-18 14:13:47.928 UTC [39353][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:48.030 UTC [39361][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-18 14:13:48.040 UTC [39361][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-18 14:13:48.040 UTC [39361][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-18 14:13:48.042 UTC [39361][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-18 14:13:48.043 UTC [39361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-18 14:13:48.047 UTC [39353][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:48.047 UTC [39353][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:48.048 UTC [39353][postmaster] LOG: background worker "logical replication launcher" (PID 39359) exited with exit code 1 2024-03-18 14:13:48.048 UTC [39354][checkpointer] LOG: shutting down 2024-03-18 14:13:48.048 UTC [39354][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:48.048 UTC [39354][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/4103080, redo lsn=0/4103080 2024-03-18 14:13:48.049 UTC [39353][postmaster] LOG: database system is shut down 2024-03-18 14:13:48.188 UTC [39363][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:48.188 UTC [39363][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:48.188 UTC [39363][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:48.192 UTC [39366][startup] LOG: database system was shut down at 2024-03-18 14:13:48 UTC 2024-03-18 14:13:48.193 UTC [39363][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:48.298 UTC [39371][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-18 14:13:48.312 UTC [39371][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-18 14:13:48.312 UTC [39371][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-18 14:13:48.316 UTC [39371][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-18 14:13:48.317 UTC [39371][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-18 14:13:48.330 UTC [39373][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-18 14:13:48.338 UTC [39373][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-18 14:13:48.338 UTC [39373][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-18 14:13:48.339 UTC [39373][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-18 14:13:48.339 UTC [39373][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-18 14:13:48.349 UTC [39375][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-18 14:13:48.356 UTC [39375][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-18 14:13:48.356 UTC [39375][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-18 14:13:48.356 UTC [39375][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-18 14:13:48.357 UTC [39375][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-18 14:13:48.365 UTC [39377][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-18 14:13:48.381 UTC [39379][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-18 14:13:48.391 UTC [39363][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:48.391 UTC [39363][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:48.392 UTC [39363][postmaster] LOG: background worker "logical replication launcher" (PID 39369) exited with exit code 1 2024-03-18 14:13:48.392 UTC [39364][checkpointer] LOG: shutting down 2024-03-18 14:13:48.392 UTC [39364][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:48.393 UTC [39364][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/41030F8, redo lsn=0/41030F8 2024-03-18 14:13:48.394 UTC [39363][postmaster] LOG: database system is shut down 2024-03-18 14:13:48.524 UTC [39381][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:48.524 UTC [39381][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:48.525 UTC [39381][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:48.527 UTC [39384][startup] LOG: database system was shut down at 2024-03-18 14:13:48 UTC 2024-03-18 14:13:48.529 UTC [39381][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:48.644 UTC [39389][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-18 14:13:48.658 UTC [39389][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-18 14:13:48.658 UTC [39389][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-18 14:13:48.662 UTC [39389][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-18 14:13:48.662 UTC [39389][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-18 14:13:48.675 UTC [39391][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-18 14:13:48.693 UTC [39393][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-18 14:13:48.707 UTC [39381][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:48.707 UTC [39381][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:48.708 UTC [39381][postmaster] LOG: background worker "logical replication launcher" (PID 39387) exited with exit code 1 2024-03-18 14:13:48.708 UTC [39382][checkpointer] LOG: shutting down 2024-03-18 14:13:48.708 UTC [39382][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:48.708 UTC [39382][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/4103170, redo lsn=0/4103170 2024-03-18 14:13:48.709 UTC [39381][postmaster] LOG: database system is shut down 2024-03-18 14:13:48.851 UTC [39396][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:48.851 UTC [39396][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:48.851 UTC [39396][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:48.854 UTC [39399][startup] LOG: database system was shut down at 2024-03-18 14:13:48 UTC 2024-03-18 14:13:48.856 UTC [39396][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:48.965 UTC [39404][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-18 14:13:48.979 UTC [39404][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-18 14:13:48.979 UTC [39404][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-18 14:13:48.983 UTC [39404][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-18 14:13:48.983 UTC [39404][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-18 14:13:48.996 UTC [39406][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-18 14:13:49.004 UTC [39406][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-18 14:13:49.004 UTC [39406][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-18 14:13:49.005 UTC [39406][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-18 14:13:49.005 UTC [39406][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-18 14:13:49.014 UTC [39408][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-18 14:13:49.030 UTC [39410][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-18 14:13:49.037 UTC [39410][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-18 14:13:49.037 UTC [39410][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-18 14:13:49.037 UTC [39410][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-18 14:13:49.038 UTC [39410][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-18 14:13:49.046 UTC [39412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-18 14:13:49.053 UTC [39412][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-18 14:13:49.053 UTC [39412][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-18 14:13:49.054 UTC [39412][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-18 14:13:49.054 UTC [39412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-18 14:13:49.063 UTC [39414][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-18 14:13:49.070 UTC [39414][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-18 14:13:49.070 UTC [39414][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-18 14:13:49.070 UTC [39414][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-18 14:13:49.070 UTC [39414][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-18 14:13:49.079 UTC [39416][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-18 14:13:49.095 UTC [39418][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-18 14:13:49.105 UTC [39396][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:49.105 UTC [39396][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:49.106 UTC [39396][postmaster] LOG: background worker "logical replication launcher" (PID 39402) exited with exit code 1 2024-03-18 14:13:49.106 UTC [39397][checkpointer] LOG: shutting down 2024-03-18 14:13:49.106 UTC [39397][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:49.107 UTC [39397][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/41031E8, redo lsn=0/41031E8 2024-03-18 14:13:49.108 UTC [39396][postmaster] LOG: database system is shut down 2024-03-18 14:13:49.243 UTC [39420][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:49.243 UTC [39420][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:49.243 UTC [39420][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:49.246 UTC [39423][startup] LOG: database system was shut down at 2024-03-18 14:13:49 UTC 2024-03-18 14:13:49.247 UTC [39420][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:49.350 UTC [39428][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-18 14:13:49.363 UTC [39428][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-18 14:13:49.363 UTC [39428][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-18 14:13:49.367 UTC [39428][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-18 14:13:49.367 UTC [39428][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-18 14:13:49.380 UTC [39430][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-18 14:13:49.388 UTC [39430][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-18 14:13:49.388 UTC [39430][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-18 14:13:49.389 UTC [39430][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-18 14:13:49.389 UTC [39430][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-18 14:13:49.398 UTC [39432][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-18 14:13:49.412 UTC [39420][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:49.412 UTC [39420][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:49.413 UTC [39420][postmaster] LOG: background worker "logical replication launcher" (PID 39426) exited with exit code 1 2024-03-18 14:13:49.413 UTC [39421][checkpointer] LOG: shutting down 2024-03-18 14:13:49.413 UTC [39421][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:49.413 UTC [39421][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/4103260, redo lsn=0/4103260 2024-03-18 14:13:49.414 UTC [39420][postmaster] LOG: database system is shut down 2024-03-18 14:13:49.545 UTC [39435][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:49.546 UTC [39435][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:49.546 UTC [39435][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:49.549 UTC [39438][startup] LOG: database system was shut down at 2024-03-18 14:13:49 UTC 2024-03-18 14:13:49.550 UTC [39435][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:49.642 UTC [39443][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-18 14:13:49.654 UTC [39443][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-18 14:13:49.654 UTC [39443][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-18 14:13:49.657 UTC [39443][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-18 14:13:49.658 UTC [39443][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-18 14:13:49.669 UTC [39445][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-18 14:13:49.677 UTC [39445][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-18 14:13:49.677 UTC [39445][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-18 14:13:49.677 UTC [39445][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-18 14:13:49.678 UTC [39445][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-18 14:13:49.687 UTC [39447][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-18 14:13:49.694 UTC [39447][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-18 14:13:49.694 UTC [39447][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-18 14:13:49.695 UTC [39447][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-18 14:13:49.695 UTC [39447][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-18 14:13:49.699 UTC [39435][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:49.699 UTC [39435][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:49.699 UTC [39435][postmaster] LOG: background worker "logical replication launcher" (PID 39441) exited with exit code 1 2024-03-18 14:13:49.699 UTC [39436][checkpointer] LOG: shutting down 2024-03-18 14:13:49.700 UTC [39436][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:49.700 UTC [39436][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/41032D8, redo lsn=0/41032D8 2024-03-18 14:13:49.701 UTC [39435][postmaster] LOG: database system is shut down 2024-03-18 14:13:49.843 UTC [39449][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:49.843 UTC [39449][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:49.844 UTC [39449][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:49.846 UTC [39452][startup] LOG: database system was shut down at 2024-03-18 14:13:49 UTC 2024-03-18 14:13:49.848 UTC [39449][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:49.959 UTC [39457][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-18 14:13:49.973 UTC [39457][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-18 14:13:49.973 UTC [39457][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-18 14:13:49.976 UTC [39457][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-18 14:13:49.977 UTC [39457][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-18 14:13:49.989 UTC [39459][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-18 14:13:49.998 UTC [39459][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-18 14:13:49.998 UTC [39459][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-18 14:13:49.998 UTC [39459][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-18 14:13:49.998 UTC [39459][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-18 14:13:50.008 UTC [39461][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-18 14:13:50.019 UTC [39449][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:50.019 UTC [39449][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:50.019 UTC [39449][postmaster] LOG: background worker "logical replication launcher" (PID 39455) exited with exit code 1 2024-03-18 14:13:50.019 UTC [39450][checkpointer] LOG: shutting down 2024-03-18 14:13:50.019 UTC [39450][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:50.020 UTC [39450][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/4103350, redo lsn=0/4103350 2024-03-18 14:13:50.021 UTC [39449][postmaster] LOG: database system is shut down 2024-03-18 14:13:50.162 UTC [39463][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:50.163 UTC [39463][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:50.163 UTC [39463][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:50.166 UTC [39466][startup] LOG: database system was shut down at 2024-03-18 14:13:50 UTC 2024-03-18 14:13:50.168 UTC [39463][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:50.268 UTC [39471][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-18 14:13:50.281 UTC [39471][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-18 14:13:50.281 UTC [39471][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-18 14:13:50.284 UTC [39471][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-18 14:13:50.284 UTC [39471][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-18 14:13:50.296 UTC [39473][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-18 14:13:50.305 UTC [39473][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-18 14:13:50.305 UTC [39473][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-18 14:13:50.305 UTC [39473][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-18 14:13:50.306 UTC [39473][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-18 14:13:50.315 UTC [39475][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-18 14:13:50.322 UTC [39475][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-18 14:13:50.322 UTC [39475][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-18 14:13:50.323 UTC [39475][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-18 14:13:50.323 UTC [39475][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-18 14:13:50.327 UTC [39463][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:50.327 UTC [39463][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:50.327 UTC [39463][postmaster] LOG: background worker "logical replication launcher" (PID 39469) exited with exit code 1 2024-03-18 14:13:50.327 UTC [39464][checkpointer] LOG: shutting down 2024-03-18 14:13:50.327 UTC [39464][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:50.328 UTC [39464][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/41033C8, redo lsn=0/41033C8 2024-03-18 14:13:50.329 UTC [39463][postmaster] LOG: database system is shut down 2024-03-18 14:13:50.452 UTC [39477][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:50.453 UTC [39477][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:50.453 UTC [39477][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:50.455 UTC [39480][startup] LOG: database system was shut down at 2024-03-18 14:13:50 UTC 2024-03-18 14:13:50.457 UTC [39477][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:50.571 UTC [39485][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-18 14:13:50.584 UTC [39485][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-18 14:13:50.584 UTC [39485][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-18 14:13:50.588 UTC [39485][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-18 14:13:50.588 UTC [39485][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-18 14:13:50.600 UTC [39487][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-18 14:13:50.612 UTC [39477][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:50.612 UTC [39477][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:50.613 UTC [39477][postmaster] LOG: background worker "logical replication launcher" (PID 39483) exited with exit code 1 2024-03-18 14:13:50.613 UTC [39478][checkpointer] LOG: shutting down 2024-03-18 14:13:50.613 UTC [39478][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:50.613 UTC [39478][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/4103440, redo lsn=0/4103440 2024-03-18 14:13:50.614 UTC [39477][postmaster] LOG: database system is shut down 2024-03-18 14:13:50.754 UTC [39489][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:50.754 UTC [39489][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:50.755 UTC [39489][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:50.758 UTC [39492][startup] LOG: database system was shut down at 2024-03-18 14:13:50 UTC 2024-03-18 14:13:50.759 UTC [39489][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:50.859 UTC [39497][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-18 14:13:50.870 UTC [39497][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-18 14:13:50.891 UTC [39500][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-18 14:13:50.899 UTC [39500][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-18 14:13:50.899 UTC [39500][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-18 14:13:50.901 UTC [39500][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-18 14:13:50.902 UTC [39500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-18 14:13:50.910 UTC [39502][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-18 14:13:50.921 UTC [39489][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:50.921 UTC [39489][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:50.922 UTC [39489][postmaster] LOG: background worker "logical replication launcher" (PID 39495) exited with exit code 1 2024-03-18 14:13:50.922 UTC [39490][checkpointer] LOG: shutting down 2024-03-18 14:13:50.922 UTC [39490][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:50.922 UTC [39490][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/41034B8, redo lsn=0/41034B8 2024-03-18 14:13:50.923 UTC [39489][postmaster] LOG: database system is shut down 2024-03-18 14:13:51.059 UTC [39504][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:51.060 UTC [39504][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:51.060 UTC [39504][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:51.063 UTC [39507][startup] LOG: database system was shut down at 2024-03-18 14:13:50 UTC 2024-03-18 14:13:51.064 UTC [39504][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:51.172 UTC [39512][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-18 14:13:51.185 UTC [39512][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-18 14:13:51.185 UTC [39512][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-18 14:13:51.189 UTC [39512][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-18 14:13:51.189 UTC [39512][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-18 14:13:51.202 UTC [39514][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-18 14:13:51.210 UTC [39514][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-18 14:13:51.219 UTC [39516][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-18 14:13:51.227 UTC [39516][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-18 14:13:51.235 UTC [39518][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-18 14:13:51.242 UTC [39518][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-18 14:13:51.242 UTC [39518][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-18 14:13:51.243 UTC [39518][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-18 14:13:51.243 UTC [39518][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-18 14:13:51.252 UTC [39520][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-18 14:13:51.259 UTC [39520][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-18 14:13:51.259 UTC [39520][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-18 14:13:51.259 UTC [39520][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-18 14:13:51.260 UTC [39520][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-18 14:13:51.290 UTC [39525][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-18 14:13:51.298 UTC [39525][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-18 14:13:51.306 UTC [39527][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-18 14:13:51.315 UTC [39527][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-18 14:13:51.315 UTC [39527][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-18 14:13:51.317 UTC [39527][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_RWz7/client.key$$ 2024-03-18 14:13:51.317 UTC [39527][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-18 14:13:51.326 UTC [39529][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-18 14:13:51.335 UTC [39529][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-18 14:13:51.335 UTC [39529][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-18 14:13:51.335 UTC [39529][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_RWz7/client-der.key$$ 2024-03-18 14:13:51.335 UTC [39529][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-18 14:13:51.344 UTC [39531][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-18 14:13:51.353 UTC [39531][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-18 14:13:51.353 UTC [39531][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-18 14:13:51.353 UTC [39531][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_RWz7/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-18 14:13:51.353 UTC [39531][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-18 14:13:51.362 UTC [39533][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-18 14:13:51.370 UTC [39533][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-18 14:13:51.370 UTC [39533][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-18 14:13:51.371 UTC [39533][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_RWz7/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-18 14:13:51.371 UTC [39533][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-18 14:13:51.380 UTC [39535][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-18 14:13:51.388 UTC [39535][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-18 14:13:51.388 UTC [39535][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-18 14:13:51.389 UTC [39535][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_RWz7/client.key$$ 2024-03-18 14:13:51.389 UTC [39535][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-18 14:13:51.398 UTC [39537][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-18 14:13:51.406 UTC [39537][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-18 14:13:51.406 UTC [39537][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-18 14:13:51.406 UTC [39537][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_RWz7/client.key$$ 2024-03-18 14:13:51.407 UTC [39537][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-18 14:13:51.415 UTC [39539][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-18 14:13:51.422 UTC [39539][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-18 14:13:51.431 UTC [39541][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-18 14:13:51.437 UTC [39541][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-18 14:13:51.446 UTC [39543][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-18 14:13:51.456 UTC [39543][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-18 14:13:51.456 UTC [39543][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-18 14:13:51.459 UTC [39543][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_RWz7/client-dn.key$$ 2024-03-18 14:13:51.459 UTC [39543][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-18 14:13:51.468 UTC [39545][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-18 14:13:51.476 UTC [39545][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-18 14:13:51.476 UTC [39545][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-18 14:13:51.478 UTC [39545][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_RWz7/client-dn.key$$ 2024-03-18 14:13:51.479 UTC [39545][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-18 14:13:51.487 UTC [39547][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-18 14:13:51.496 UTC [39547][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-18 14:13:51.496 UTC [39547][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-18 14:13:51.498 UTC [39547][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_RWz7/client-dn.key$$ 2024-03-18 14:13:51.498 UTC [39547][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-18 14:13:51.517 UTC [39550][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-18 14:13:51.526 UTC [39550][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-18 14:13:51.526 UTC [39550][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-18 14:13:51.526 UTC [39550][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-18 14:13:51.527 UTC [39550][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49354 2024-03-18 14:13:51.536 UTC [39552][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-18 14:13:51.541 UTC [39552][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-18 14:13:51.549 UTC [39554][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-18 14:13:51.558 UTC [39554][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-18 14:13:51.558 UTC [39554][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-18 14:13:51.558 UTC [39554][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-18 14:13:51.558 UTC [39554][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-18 14:13:51.566 UTC [39556][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-18 14:13:51.576 UTC [39556][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-18 14:13:51.576 UTC [39556][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-18 14:13:51.585 UTC [39558][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-18 14:13:51.593 UTC [39558][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-18 14:13:51.593 UTC [39558][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-18 14:13:51.596 UTC [39558][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_RWz7/client.key$$ 2024-03-18 14:13:51.596 UTC [39558][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-18 14:13:51.605 UTC [39560][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-18 14:13:51.613 UTC [39560][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-18 14:13:51.613 UTC [39560][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-18 14:13:51.613 UTC [39560][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-18 14:13:51.613 UTC [39560][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-18 14:13:51.622 UTC [39562][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-18 14:13:51.630 UTC [39562][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-18 14:13:51.630 UTC [39562][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-18 14:13:51.631 UTC [39562][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_RWz7/client.key$$ 2024-03-18 14:13:51.631 UTC [39562][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-18 14:13:51.635 UTC [39504][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:51.635 UTC [39504][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:51.636 UTC [39504][postmaster] LOG: background worker "logical replication launcher" (PID 39510) exited with exit code 1 2024-03-18 14:13:51.636 UTC [39505][checkpointer] LOG: shutting down 2024-03-18 14:13:51.636 UTC [39505][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:51.636 UTC [39505][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/4103530, redo lsn=0/4103530 2024-03-18 14:13:51.637 UTC [39504][postmaster] LOG: database system is shut down 2024-03-18 14:13:51.778 UTC [39564][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:51.779 UTC [39564][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:51.779 UTC [39564][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:51.782 UTC [39567][startup] LOG: database system was shut down at 2024-03-18 14:13:51 UTC 2024-03-18 14:13:51.784 UTC [39564][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:51.891 UTC [39572][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-18 14:13:51.908 UTC [39572][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-18 14:13:51.908 UTC [39572][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-18 14:13:51.912 UTC [39572][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_RWz7/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-18 14:13:51.912 UTC [39572][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=ssltestuser database=certdb host=localhost port=49361 2024-03-18 14:13:51.924 UTC [39574][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-18 14:13:51.933 UTC [39574][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-18 14:13:51.933 UTC [39574][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-18 14:13:51.942 UTC [39576][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-18 14:13:51.950 UTC [39576][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-18 14:13:51.950 UTC [39576][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-18 14:13:51.954 UTC [39564][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:51.954 UTC [39564][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:51.955 UTC [39564][postmaster] LOG: background worker "logical replication launcher" (PID 39570) exited with exit code 1 2024-03-18 14:13:51.955 UTC [39565][checkpointer] LOG: shutting down 2024-03-18 14:13:51.955 UTC [39565][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:51.955 UTC [39565][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/41035A8, redo lsn=0/41035A8 2024-03-18 14:13:51.956 UTC [39564][postmaster] LOG: database system is shut down 2024-03-18 14:13:52.084 UTC [39578][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:52.084 UTC [39578][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:52.084 UTC [39578][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:52.087 UTC [39581][startup] LOG: database system was shut down at 2024-03-18 14:13:51 UTC 2024-03-18 14:13:52.088 UTC [39578][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:52.204 UTC [39586][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-18 14:13:52.220 UTC [39586][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-18 14:13:52.220 UTC [39586][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-18 14:13:52.226 UTC [39578][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:52.226 UTC [39578][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:52.226 UTC [39578][postmaster] LOG: background worker "logical replication launcher" (PID 39584) exited with exit code 1 2024-03-18 14:13:52.227 UTC [39579][checkpointer] LOG: shutting down 2024-03-18 14:13:52.227 UTC [39579][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:52.227 UTC [39579][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/4103620, redo lsn=0/4103620 2024-03-18 14:13:52.229 UTC [39578][postmaster] LOG: database system is shut down 2024-03-18 14:13:52.377 UTC [39588][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:52.378 UTC [39588][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:52.378 UTC [39588][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:52.382 UTC [39591][startup] LOG: database system was shut down at 2024-03-18 14:13:52 UTC 2024-03-18 14:13:52.384 UTC [39588][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:52.479 UTC [39596][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-18 14:13:52.495 UTC [39596][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-18 14:13:52.495 UTC [39596][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-18 14:13:52.509 UTC [39598][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-18 14:13:52.524 UTC [39598][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-18 14:13:52.524 UTC [39598][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-18 14:13:52.528 UTC [39588][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:52.528 UTC [39588][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:52.529 UTC [39588][postmaster] LOG: background worker "logical replication launcher" (PID 39594) exited with exit code 1 2024-03-18 14:13:52.529 UTC [39589][checkpointer] LOG: shutting down 2024-03-18 14:13:52.529 UTC [39589][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:52.530 UTC [39589][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/4103698, redo lsn=0/4103698 2024-03-18 14:13:52.531 UTC [39588][postmaster] LOG: database system is shut down 2024-03-18 14:13:52.668 UTC [39601][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:52.668 UTC [39601][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:52.669 UTC [39601][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:52.672 UTC [39604][startup] LOG: database system was shut down at 2024-03-18 14:13:52 UTC 2024-03-18 14:13:52.673 UTC [39601][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:52.772 UTC [39609][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-18 14:13:52.783 UTC [39609][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:52.783 UTC [39609][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:52.798 UTC [39611][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-18 14:13:52.807 UTC [39611][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-18 14:13:52.807 UTC [39611][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-18 14:13:52.809 UTC [39611][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-18 14:13:52.810 UTC [39611][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-18 14:13:52.814 UTC [39601][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:52.814 UTC [39601][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:52.815 UTC [39601][postmaster] LOG: background worker "logical replication launcher" (PID 39607) exited with exit code 1 2024-03-18 14:13:52.815 UTC [39602][checkpointer] LOG: shutting down 2024-03-18 14:13:52.815 UTC [39602][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:52.815 UTC [39602][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/4103710, redo lsn=0/4103710 2024-03-18 14:13:52.816 UTC [39601][postmaster] LOG: database system is shut down 2024-03-18 14:13:52.956 UTC [39613][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:52.957 UTC [39613][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:52.957 UTC [39613][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:52.960 UTC [39616][startup] LOG: database system was shut down at 2024-03-18 14:13:52 UTC 2024-03-18 14:13:52.962 UTC [39613][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:53.069 UTC [39621][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-18 14:13:53.080 UTC [39621][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:53.080 UTC [39621][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:53.095 UTC [39623][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-18 14:13:53.104 UTC [39623][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-18 14:13:53.105 UTC [39623][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-18 14:13:53.107 UTC [39623][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-18 14:13:53.108 UTC [39623][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-18 14:13:53.112 UTC [39613][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:53.112 UTC [39613][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:53.113 UTC [39613][postmaster] LOG: background worker "logical replication launcher" (PID 39619) exited with exit code 1 2024-03-18 14:13:53.113 UTC [39614][checkpointer] LOG: shutting down 2024-03-18 14:13:53.113 UTC [39614][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:53.113 UTC [39614][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/4103788, redo lsn=0/4103788 2024-03-18 14:13:53.114 UTC [39613][postmaster] LOG: database system is shut down 2024-03-18 14:13:53.261 UTC [39625][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:53.262 UTC [39625][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:53.262 UTC [39625][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:53.265 UTC [39628][startup] LOG: database system was shut down at 2024-03-18 14:13:53 UTC 2024-03-18 14:13:53.267 UTC [39625][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:53.366 UTC [39633][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-18 14:13:53.376 UTC [39633][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:53.376 UTC [39633][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:53.390 UTC [39635][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-18 14:13:53.400 UTC [39635][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-18 14:13:53.400 UTC [39635][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-18 14:13:53.402 UTC [39635][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-18 14:13:53.403 UTC [39635][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-18 14:13:53.407 UTC [39625][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:53.407 UTC [39625][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:53.408 UTC [39625][postmaster] LOG: background worker "logical replication launcher" (PID 39631) exited with exit code 1 2024-03-18 14:13:53.408 UTC [39626][checkpointer] LOG: shutting down 2024-03-18 14:13:53.408 UTC [39626][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:53.408 UTC [39626][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/4103800, redo lsn=0/4103800 2024-03-18 14:13:53.409 UTC [39625][postmaster] LOG: database system is shut down 2024-03-18 14:13:53.548 UTC [39637][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:53.548 UTC [39637][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:53.549 UTC [39637][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:53.552 UTC [39640][startup] LOG: database system was shut down at 2024-03-18 14:13:53 UTC 2024-03-18 14:13:53.554 UTC [39637][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:53.665 UTC [39645][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-18 14:13:53.675 UTC [39645][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:53.675 UTC [39645][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:53.690 UTC [39647][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-18 14:13:53.699 UTC [39647][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-18 14:13:53.699 UTC [39647][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-18 14:13:53.702 UTC [39647][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-18 14:13:53.702 UTC [39647][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-18 14:13:53.706 UTC [39637][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:53.706 UTC [39637][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:53.707 UTC [39637][postmaster] LOG: background worker "logical replication launcher" (PID 39643) exited with exit code 1 2024-03-18 14:13:53.707 UTC [39638][checkpointer] LOG: shutting down 2024-03-18 14:13:53.707 UTC [39638][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:53.708 UTC [39638][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/4103878, redo lsn=0/4103878 2024-03-18 14:13:53.709 UTC [39637][postmaster] LOG: database system is shut down 2024-03-18 14:13:53.854 UTC [39649][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:53.855 UTC [39649][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:53.855 UTC [39649][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:53.858 UTC [39652][startup] LOG: database system was shut down at 2024-03-18 14:13:53 UTC 2024-03-18 14:13:53.860 UTC [39649][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:53.965 UTC [39657][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-18 14:13:53.975 UTC [39657][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:53.976 UTC [39657][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:53.991 UTC [39659][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-18 14:13:54.000 UTC [39659][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-18 14:13:54.000 UTC [39659][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-18 14:13:54.002 UTC [39659][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-18 14:13:54.002 UTC [39659][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-18 14:13:54.007 UTC [39649][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:54.007 UTC [39649][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:54.008 UTC [39649][postmaster] LOG: background worker "logical replication launcher" (PID 39655) exited with exit code 1 2024-03-18 14:13:54.008 UTC [39650][checkpointer] LOG: shutting down 2024-03-18 14:13:54.008 UTC [39650][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:54.008 UTC [39650][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/41038F0, redo lsn=0/41038F0 2024-03-18 14:13:54.009 UTC [39649][postmaster] LOG: database system is shut down 2024-03-18 14:13:54.141 UTC [39661][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:54.142 UTC [39661][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:54.142 UTC [39661][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:54.145 UTC [39664][startup] LOG: database system was shut down at 2024-03-18 14:13:54 UTC 2024-03-18 14:13:54.146 UTC [39661][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:54.258 UTC [39669][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-18 14:13:54.268 UTC [39669][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:54.269 UTC [39669][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:54.282 UTC [39671][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-18 14:13:54.291 UTC [39671][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-18 14:13:54.291 UTC [39671][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-18 14:13:54.293 UTC [39671][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-18 14:13:54.294 UTC [39671][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-18 14:13:54.298 UTC [39661][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:54.298 UTC [39661][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:54.298 UTC [39661][postmaster] LOG: background worker "logical replication launcher" (PID 39667) exited with exit code 1 2024-03-18 14:13:54.298 UTC [39662][checkpointer] LOG: shutting down 2024-03-18 14:13:54.299 UTC [39662][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:54.299 UTC [39662][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/4103968, redo lsn=0/4103968 2024-03-18 14:13:54.300 UTC [39661][postmaster] LOG: database system is shut down 2024-03-18 14:13:54.435 UTC [39673][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:54.435 UTC [39673][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:54.436 UTC [39673][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:54.439 UTC [39676][startup] LOG: database system was shut down at 2024-03-18 14:13:54 UTC 2024-03-18 14:13:54.441 UTC [39673][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:54.539 UTC [39681][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-18 14:13:54.549 UTC [39681][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:54.549 UTC [39681][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:54.563 UTC [39683][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-18 14:13:54.572 UTC [39683][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-18 14:13:54.572 UTC [39683][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-18 14:13:54.574 UTC [39683][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-18 14:13:54.575 UTC [39683][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-18 14:13:54.579 UTC [39673][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:54.579 UTC [39673][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:54.579 UTC [39673][postmaster] LOG: background worker "logical replication launcher" (PID 39679) exited with exit code 1 2024-03-18 14:13:54.580 UTC [39674][checkpointer] LOG: shutting down 2024-03-18 14:13:54.580 UTC [39674][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:54.580 UTC [39674][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/41039E0, redo lsn=0/41039E0 2024-03-18 14:13:54.581 UTC [39673][postmaster] LOG: database system is shut down 2024-03-18 14:13:54.726 UTC [39685][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:54.726 UTC [39685][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:54.727 UTC [39685][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:54.730 UTC [39688][startup] LOG: database system was shut down at 2024-03-18 14:13:54 UTC 2024-03-18 14:13:54.732 UTC [39685][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:54.846 UTC [39693][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-18 14:13:54.858 UTC [39693][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:54.858 UTC [39693][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:54.873 UTC [39695][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-18 14:13:54.882 UTC [39695][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-18 14:13:54.882 UTC [39695][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-18 14:13:54.885 UTC [39695][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-18 14:13:54.885 UTC [39695][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-18 14:13:54.890 UTC [39685][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:54.890 UTC [39685][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:54.890 UTC [39685][postmaster] LOG: background worker "logical replication launcher" (PID 39691) exited with exit code 1 2024-03-18 14:13:54.890 UTC [39686][checkpointer] LOG: shutting down 2024-03-18 14:13:54.890 UTC [39686][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:54.891 UTC [39686][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/4103A58, redo lsn=0/4103A58 2024-03-18 14:13:54.892 UTC [39685][postmaster] LOG: database system is shut down 2024-03-18 14:13:55.039 UTC [39697][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-18 14:13:55.040 UTC [39697][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 52553 2024-03-18 14:13:55.040 UTC [39697][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZR82MUyoSI/.s.PGSQL.52553" 2024-03-18 14:13:55.044 UTC [39700][startup] LOG: database system was shut down at 2024-03-18 14:13:54 UTC 2024-03-18 14:13:55.045 UTC [39697][postmaster] LOG: database system is ready to accept connections 2024-03-18 14:13:55.136 UTC [39705][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-18 14:13:55.147 UTC [39705][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-18 14:13:55.147 UTC [39705][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-18 14:13:55.161 UTC [39707][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-18 14:13:55.169 UTC [39707][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-18 14:13:55.169 UTC [39707][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-18 14:13:55.172 UTC [39707][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-18 14:13:55.172 UTC [39707][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-18 14:13:55.176 UTC [39697][postmaster] LOG: received fast shutdown request 2024-03-18 14:13:55.176 UTC [39697][postmaster] LOG: aborting any active transactions 2024-03-18 14:13:55.177 UTC [39697][postmaster] LOG: background worker "logical replication launcher" (PID 39703) exited with exit code 1 2024-03-18 14:13:55.177 UTC [39698][checkpointer] LOG: shutting down 2024-03-18 14:13:55.177 UTC [39698][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-18 14:13:55.177 UTC [39698][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/4103AD0, redo lsn=0/4103AD0 2024-03-18 14:13:55.178 UTC [39697][postmaster] LOG: database system is shut down 2024-03-18 14:13:55.315 UTC [39709][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-18 14:13:55.315 UTC [39709][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:13:55.394](0.221s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"