[07:40:03.824](0.033s) # setting up data directory # Checking port 58245 # Found port 58245 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=58245 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0 Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [07:40:03.843](0.018s) # 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 38293 [07:40:04.460](0.617s) 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 38838 [07:40:09.818](5.358s) # 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-12 07:40:04.327 UTC [38293][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:04.327 UTC [38293][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:04.346 UTC [38314][startup] LOG: database system was shut down at 2024-03-12 07:35:40 UTC 2024-03-12 07:40:04.352 UTC [38293][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:04.453 UTC [38350][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.453 UTC [38350][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-12 07:40:04.453 UTC [38350][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.458 UTC [38350][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-12 07:40:04.460 UTC [38350][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=admin database=postgres host=[local] 2024-03-12 07:40:04.479 UTC [38364][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.481 UTC [38364][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-12 07:40:04.481 UTC [38364][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.482 UTC [38364][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-12 07:40:04.482 UTC [38364][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-12 07:40:04.531 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.531 UTC [38381][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-12 07:40:04.531 UTC [38381][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.532 UTC [38381][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-12 07:40:04.532 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.590 UTC [38401][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.591 UTC [38401][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-12 07:40:04.591 UTC [38401][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.591 UTC [38401][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-12 07:40:04.592 UTC [38401][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.633 UTC [38427][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.633 UTC [38427][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-12 07:40:04.633 UTC [38427][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.634 UTC [38427][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-12 07:40:04.634 UTC [38427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.692 UTC [38447][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.693 UTC [38447][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-12 07:40:04.693 UTC [38447][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.694 UTC [38447][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-12 07:40:04.990 UTC [38447][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.298 user=admin database=postgres host=[local] 2024-03-12 07:40:05.321 UTC [38475][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:05.442 UTC [38475][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-12 07:40:05.580 UTC [38475][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:05.582 UTC [38475][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-12 07:40:05.948 UTC [38475][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.627 user=admin database=postgres host=[local] 2024-03-12 07:40:05.962 UTC [38498][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:05.962 UTC [38498][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-12 07:40:05.962 UTC [38498][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:05.963 UTC [38498][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-12 07:40:06.711 UTC [38498][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.749 user=admin database=postgres host=[local] 2024-03-12 07:40:07.130 UTC [38544][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:07.131 UTC [38544][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-12 07:40:07.131 UTC [38544][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:07.131 UTC [38544][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-12 07:40:07.409 UTC [38544][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.278 user=admin database=postgres host=[local] 2024-03-12 07:40:07.436 UTC [38548][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:07.556 UTC [38548][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-12 07:40:07.556 UTC [38548][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:07.557 UTC [38548][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-12 07:40:07.806 UTC [38548][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.370 user=admin database=postgres host=[local] 2024-03-12 07:40:08.063 UTC [38623][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:08.064 UTC [38623][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-12 07:40:08.064 UTC [38623][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:08.064 UTC [38623][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-12 07:40:08.174 UTC [38623][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.110 user=admin database=postgres host=[local] 2024-03-12 07:40:08.417 UTC [38293][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:08.417 UTC [38293][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:08.418 UTC [38293][postmaster] LOG: background worker "logical replication launcher" (PID 38324) exited with exit code 1 2024-03-12 07:40:08.419 UTC [38309][checkpointer] LOG: shutting down 2024-03-12 07:40:08.419 UTC [38309][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:09.518 UTC [38309][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.099 s, sync=0.001 s, total=1.100 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104958, redo lsn=0/4104958 2024-03-12 07:40:09.527 UTC [38293][postmaster] LOG: database system is shut down 2024-03-12 07:40:09.708 UTC [38838][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:09.709 UTC [38838][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:09.709 UTC [38838][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:09.712 UTC [38845][startup] LOG: database system was shut down at 2024-03-12 07:40:09 UTC 2024-03-12 07:40:09.713 UTC [38838][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:09.822 UTC [38838][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:09.822 UTC [38838][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:09.823 UTC [38838][postmaster] LOG: background worker "logical replication launcher" (PID 38851) exited with exit code 1 2024-03-12 07:40:09.823 UTC [38841][checkpointer] LOG: shutting down 2024-03-12 07:40:09.823 UTC [38841][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:09.825 UTC [38841][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41049D0, redo lsn=0/41049D0 2024-03-12 07:40:09.826 UTC [38838][postmaster] LOG: database system is shut down 2024-03-12 07:40:09.998 UTC [38866][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-12 07:40:09.998 UTC [38866][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [07:40:10.043](0.225s) 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 38880 [07:40:10.366](0.323s) 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-12 07:40:04.327 UTC [38293][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:04.327 UTC [38293][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:04.346 UTC [38314][startup] LOG: database system was shut down at 2024-03-12 07:35:40 UTC 2024-03-12 07:40:04.352 UTC [38293][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:04.453 UTC [38350][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.453 UTC [38350][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-12 07:40:04.453 UTC [38350][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.458 UTC [38350][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-12 07:40:04.460 UTC [38350][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=admin database=postgres host=[local] 2024-03-12 07:40:04.479 UTC [38364][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.481 UTC [38364][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-12 07:40:04.481 UTC [38364][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.482 UTC [38364][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-12 07:40:04.482 UTC [38364][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-12 07:40:04.531 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.531 UTC [38381][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-12 07:40:04.531 UTC [38381][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.532 UTC [38381][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-12 07:40:04.532 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.590 UTC [38401][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.591 UTC [38401][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-12 07:40:04.591 UTC [38401][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.591 UTC [38401][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-12 07:40:04.592 UTC [38401][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.633 UTC [38427][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.633 UTC [38427][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-12 07:40:04.633 UTC [38427][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.634 UTC [38427][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-12 07:40:04.634 UTC [38427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.692 UTC [38447][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.693 UTC [38447][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-12 07:40:04.693 UTC [38447][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.694 UTC [38447][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-12 07:40:04.990 UTC [38447][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.298 user=admin database=postgres host=[local] 2024-03-12 07:40:05.321 UTC [38475][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:05.442 UTC [38475][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-12 07:40:05.580 UTC [38475][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:05.582 UTC [38475][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-12 07:40:05.948 UTC [38475][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.627 user=admin database=postgres host=[local] 2024-03-12 07:40:05.962 UTC [38498][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:05.962 UTC [38498][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-12 07:40:05.962 UTC [38498][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:05.963 UTC [38498][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-12 07:40:06.711 UTC [38498][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.749 user=admin database=postgres host=[local] 2024-03-12 07:40:07.130 UTC [38544][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:07.131 UTC [38544][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-12 07:40:07.131 UTC [38544][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:07.131 UTC [38544][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-12 07:40:07.409 UTC [38544][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.278 user=admin database=postgres host=[local] 2024-03-12 07:40:07.436 UTC [38548][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:07.556 UTC [38548][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-12 07:40:07.556 UTC [38548][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:07.557 UTC [38548][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-12 07:40:07.806 UTC [38548][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.370 user=admin database=postgres host=[local] 2024-03-12 07:40:08.063 UTC [38623][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:08.064 UTC [38623][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-12 07:40:08.064 UTC [38623][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:08.064 UTC [38623][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-12 07:40:08.174 UTC [38623][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.110 user=admin database=postgres host=[local] 2024-03-12 07:40:08.417 UTC [38293][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:08.417 UTC [38293][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:08.418 UTC [38293][postmaster] LOG: background worker "logical replication launcher" (PID 38324) exited with exit code 1 2024-03-12 07:40:08.419 UTC [38309][checkpointer] LOG: shutting down 2024-03-12 07:40:08.419 UTC [38309][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:09.518 UTC [38309][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.099 s, sync=0.001 s, total=1.100 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104958, redo lsn=0/4104958 2024-03-12 07:40:09.527 UTC [38293][postmaster] LOG: database system is shut down 2024-03-12 07:40:09.708 UTC [38838][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:09.709 UTC [38838][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:09.709 UTC [38838][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:09.712 UTC [38845][startup] LOG: database system was shut down at 2024-03-12 07:40:09 UTC 2024-03-12 07:40:09.713 UTC [38838][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:09.822 UTC [38838][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:09.822 UTC [38838][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:09.823 UTC [38838][postmaster] LOG: background worker "logical replication launcher" (PID 38851) exited with exit code 1 2024-03-12 07:40:09.823 UTC [38841][checkpointer] LOG: shutting down 2024-03-12 07:40:09.823 UTC [38841][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:09.825 UTC [38841][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41049D0, redo lsn=0/41049D0 2024-03-12 07:40:09.826 UTC [38838][postmaster] LOG: database system is shut down 2024-03-12 07:40:09.998 UTC [38866][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-12 07:40:09.998 UTC [38866][postmaster] LOG: database system is shut down 2024-03-12 07:40:10.251 UTC [38880][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:10.252 UTC [38880][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:10.252 UTC [38880][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:10.257 UTC [38903][startup] LOG: database system was shut down at 2024-03-12 07:40:09 UTC 2024-03-12 07:40:10.260 UTC [38880][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:10.370 UTC [38880][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:10.370 UTC [38880][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:10.370 UTC [38880][postmaster] LOG: background worker "logical replication launcher" (PID 38906) exited with exit code 1 2024-03-12 07:40:10.370 UTC [38899][checkpointer] LOG: shutting down 2024-03-12 07:40:10.371 UTC [38899][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:10.371 UTC [38899][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/4104A48, redo lsn=0/4104A48 2024-03-12 07:40:10.372 UTC [38880][postmaster] LOG: database system is shut down 2024-03-12 07:40:10.498 UTC [38922][postmaster] FATAL: could not set SSL protocol version range 2024-03-12 07:40:10.498 UTC [38922][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-12 07:40:10.498 UTC [38922][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [07:40:10.583](0.217s) 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 38946 [07:40:10.697](0.114s) ok 5 - restart succeeds with correct SSL protocol bounds [07:40:10.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 38985 [07:40:10.926](0.229s) ok 6 - server doesn't accept non-SSL connections [07:40:10.926](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [07:40:10.945](0.020s) ok 8 - connect without server root cert sslmode=require [07:40:10.945](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [07:40:10.959](0.014s) ok 10 - connect without server root cert sslmode=verify-ca [07:40:10.959](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [07:40:10.975](0.015s) ok 12 - connect without server root cert sslmode=verify-full [07:40:10.975](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [07:40:10.992](0.017s) ok 14 - connect with wrong server root cert sslmode=require [07:40:10.992](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [07:40:11.007](0.015s) ok 16 - connect with wrong server root cert sslmode=verify-ca [07:40:11.008](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [07:40:11.023](0.015s) ok 18 - connect with wrong server root cert sslmode=verify-full [07:40:11.023](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [07:40:11.038](0.015s) ok 20 - connect with server CA cert, without root CA [07:40:11.038](0.000s) ok 21 - connect with server CA cert, without root CA: matches [07:40:11.055](0.017s) ok 22 - connect with correct server CA cert file sslmode=require [07:40:11.055](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [07:40:11.072](0.016s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [07:40:11.072](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [07:40:11.092](0.021s) ok 26 - connect with correct server CA cert file sslmode=verify-full [07:40:11.093](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [07:40:11.116](0.023s) ok 28 - cert root file that contains two certificates, order 1 [07:40:11.116](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [07:40:11.138](0.022s) ok 30 - cert root file that contains two certificates, order 2 [07:40:11.138](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [07:40:11.158](0.020s) ok 32 - connect with sslcertmode=disable [07:40:11.158](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [07:40:11.178](0.019s) ok 34 - connect with sslcertmode=allow [07:40:11.178](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [07:40:11.196](0.018s) ok 36 - connect with sslcertmode=require fails without a client certificate [07:40:11.196](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [07:40:11.212](0.017s) ok 38 - sslcrl option with invalid file name [07:40:11.213](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [07:40:11.230](0.017s) ok 40 - CRL belonging to a different CA [07:40:11.230](0.000s) ok 41 - CRL belonging to a different CA: matches [07:40:11.246](0.016s) ok 42 - directory CRL belonging to a different CA [07:40:11.246](0.000s) ok 43 - directory CRL belonging to a different CA: matches [07:40:11.263](0.017s) ok 44 - CRL with a non-revoked cert [07:40:11.263](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [07:40:11.280](0.017s) ok 46 - directory CRL with a non-revoked cert [07:40:11.280](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [07:40:11.297](0.016s) ok 48 - mismatch between host name and server certificate sslmode=require [07:40:11.297](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [07:40:11.313](0.016s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [07:40:11.313](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [07:40:11.329](0.016s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [07:40:11.330](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 39081 [07:40:11.598](0.268s) ok 54 - IP address in the Common Name [07:40:11.598](0.000s) ok 55 - IP address in the Common Name: no stderr [07:40:11.617](0.019s) ok 56 - mismatch between host name and server certificate IP address [07:40:11.617](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 39103 [07:40:11.863](0.246s) ok 58 - IP address in a dNSName [07:40:11.863](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 39113 [07:40:12.127](0.264s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [07:40:12.127](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [07:40:12.148](0.021s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [07:40:12.148](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [07:40:12.165](0.017s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [07:40:12.166](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [07:40:12.181](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [07:40:12.181](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [07:40:12.197](0.015s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [07:40:12.197](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 39131 [07:40:12.469](0.272s) ok 70 - host name matching with a single X.509 Subject Alternative Name [07:40:12.469](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [07:40:12.488](0.019s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [07:40:12.488](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [07:40:12.504](0.016s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [07:40:12.504](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 39146 [07:40:12.759](0.254s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [07:40:12.759](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [07:40:12.779](0.020s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [07:40:12.779](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [07:40:12.795](0.016s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [07:40:12.795](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [07:40:12.812](0.016s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [07:40:12.812](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [07:40:12.828](0.016s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [07:40:12.828](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [07:40:12.844](0.016s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [07:40:12.844](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [07:40:12.860](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [07:40:12.860](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [07:40:12.876](0.015s) ok 90 - IPv6 host with CIDR mask does not match [07:40:12.876](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 39170 [07:40:13.124](0.249s) ok 92 - certificate with both a CN and SANs 1 [07:40:13.124](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [07:40:13.144](0.020s) ok 94 - certificate with both a CN and SANs 2 [07:40:13.145](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [07:40:13.161](0.016s) ok 96 - certificate with both a CN and SANs ignores CN [07:40:13.161](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 39185 [07:40:13.439](0.278s) ok 98 - certificate with both a CN and IP SANs matches CN [07:40:13.439](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [07:40:13.459](0.020s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [07:40:13.459](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [07:40:13.476](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [07:40:13.476](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 39199 [07:40:13.741](0.264s) ok 104 - certificate with both an IP CN and IP SANs 1 [07:40:13.741](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [07:40:13.762](0.021s) ok 106 - certificate with both an IP CN and IP SANs 2 [07:40:13.762](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [07:40:13.778](0.016s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [07:40:13.778](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 39213 [07:40:14.044](0.265s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [07:40:14.044](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [07:40:14.064](0.020s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [07:40:14.064](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [07:40:14.081](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [07:40:14.081](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 39228 [07:40:14.356](0.275s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [07:40:14.356](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [07:40:14.376](0.020s) ok 118 - server certificate without CN or SANs sslmode=verify-full [07:40:14.377](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 39240 [07:40:14.644](0.267s) ok 120 - sslrootcert=system does not connect with private CA [07:40:14.644](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [07:40:14.655](0.011s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [07:40:14.655](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [07:40:14.676](0.021s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [07:40:14.676](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [07:40:14.692](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [07:40:14.692](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 39255 [07:40:14.966](0.273s) ok 128 - connects without client-side CRL [07:40:14.966](0.000s) ok 129 - connects without client-side CRL: no stderr [07:40:14.985](0.019s) ok 130 - does not connect with client-side CRL file [07:40:14.985](0.000s) ok 131 - does not connect with client-side CRL file: matches [07:40:15.002](0.016s) ok 132 - does not connect with client-side CRL directory [07:40:15.002](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() [07:40:15.018](0.016s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [07:40:15.018](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [07:40:15.018](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [07:40:15.034](0.016s) ok 137 - connection success with correct range of TLS protocol versions [07:40:15.034](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [07:40:15.042](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [07:40:15.042](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [07:40:15.049](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [07:40:15.049](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [07:40:15.056](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [07:40:15.057](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [07:40:15.057](0.000s) # running server tests [07:40:15.073](0.016s) ok 145 - certificate authorization fails without client cert [07:40:15.073](0.000s) ok 146 - certificate authorization fails without client cert: matches [07:40:15.092](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [07:40:15.092](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [07:40:15.110](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [07:40:15.110](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [07:40:15.129](0.019s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [07:40:15.130](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [07:40:15.148](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [07:40:15.148](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [07:40:15.168](0.020s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [07:40:15.168](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [07:40:15.187](0.019s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [07:40:15.187](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [07:40:15.203](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [07:40:15.203](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [07:40:15.217](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [07:40:15.217](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [07:40:15.240](0.022s) ok 163 - certificate authorization succeeds with DN mapping [07:40:15.240](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [07:40:15.240](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [07:40:15.261](0.021s) ok 166 - certificate authorization succeeds with DN regex mapping [07:40:15.261](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [07:40:15.281](0.020s) ok 168 - certificate authorization succeeds with CN mapping [07:40:15.281](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [07:40:15.281](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [07:40:15.281](0.000s) not ok 171 # TODO & SKIP Need Pty support [07:40:15.281](0.000s) not ok 172 # TODO & SKIP Need Pty support [07:40:15.281](0.000s) not ok 173 # TODO & SKIP Need Pty support [07:40:15.281](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_lMPj/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [07:40:15.309](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [07:40:15.310](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [07:40:15.310](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [07:40:15.324](0.014s) ok 178 - certificate authorization fails because of file permissions [07:40:15.324](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [07:40:15.341](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [07:40:15.341](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [07:40:15.358](0.017s) ok 182 - certificate authorization fails with revoked client cert [07:40:15.358](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [07:40:15.358](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [07:40:15.378](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [07:40:15.378](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [07:40:15.378](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [07:40:15.395](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [07:40:15.395](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [07:40:15.396](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [07:40:15.413](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [07:40:15.413](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [07:40:15.413](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 39315 [07:40:15.684](0.271s) ok 194 - intermediate client certificate is provided by client [07:40:15.684](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [07:40:15.705](0.020s) ok 196 - intermediate client certificate is missing [07:40:15.705](0.000s) ok 197 - intermediate client certificate is missing: matches [07:40:15.722](0.018s) ok 198 - logged client certificate Subjects are truncated if they're too long [07:40:15.723](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 39329 [07:40:15.993](0.271s) ok 200 - intermediate client certificate is untrusted [07:40:15.993](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 39339 [07:40:16.259](0.266s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [07:40:16.259](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [07:40:16.282](0.023s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [07:40:16.282](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 39351 [07:40:16.545](0.263s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [07:40:16.545](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. [07:40:16.545](0.000s) # got: '2' # expected: '0' [07:40:16.546](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [07:40:16.546](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. [07:40:16.546](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 58245 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: '' [07:40:16.571](0.025s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [07:40:16.571](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 39363 [07:40:16.831](0.260s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [07:40:16.855](0.024s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [07:40:16.855](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 39375 [07:40:17.131](0.276s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [07:40:17.156](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [07:40:17.156](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 39387 [07:40:17.435](0.279s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [07:40:17.460](0.026s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [07:40:17.460](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 39399 [07:40:17.714](0.253s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [07:40:17.714](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. [07:40:17.714](0.000s) # got: '2' # expected: '0' [07:40:17.714](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [07:40:17.714](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. [07:40:17.714](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 58245 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: '' [07:40:17.739](0.025s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [07:40:17.739](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 39411 [07:40:17.995](0.255s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [07:40:18.019](0.024s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [07:40:18.019](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 39423 [07:40:18.266](0.247s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [07:40:18.288](0.023s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [07:40:18.288](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 39435 [07:40:18.544](0.256s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [07:40:18.568](0.023s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [07:40:18.568](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 39448 [07:40:18.822](0.254s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [07:40:18.845](0.023s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [07:40:18.845](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-12 07:40:04.327 UTC [38293][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:04.327 UTC [38293][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:04.346 UTC [38314][startup] LOG: database system was shut down at 2024-03-12 07:35:40 UTC 2024-03-12 07:40:04.352 UTC [38293][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:04.453 UTC [38350][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.453 UTC [38350][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-12 07:40:04.453 UTC [38350][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.458 UTC [38350][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-12 07:40:04.460 UTC [38350][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=admin database=postgres host=[local] 2024-03-12 07:40:04.479 UTC [38364][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.481 UTC [38364][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-12 07:40:04.481 UTC [38364][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.482 UTC [38364][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-12 07:40:04.482 UTC [38364][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-12 07:40:04.531 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.531 UTC [38381][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-12 07:40:04.531 UTC [38381][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.532 UTC [38381][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-12 07:40:04.532 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.590 UTC [38401][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.591 UTC [38401][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-12 07:40:04.591 UTC [38401][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.591 UTC [38401][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-12 07:40:04.592 UTC [38401][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.633 UTC [38427][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.633 UTC [38427][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-12 07:40:04.633 UTC [38427][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.634 UTC [38427][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-12 07:40:04.634 UTC [38427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-12 07:40:04.692 UTC [38447][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:04.693 UTC [38447][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-12 07:40:04.693 UTC [38447][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:04.694 UTC [38447][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-12 07:40:04.990 UTC [38447][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.298 user=admin database=postgres host=[local] 2024-03-12 07:40:05.321 UTC [38475][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:05.442 UTC [38475][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-12 07:40:05.580 UTC [38475][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:05.582 UTC [38475][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-12 07:40:05.948 UTC [38475][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.627 user=admin database=postgres host=[local] 2024-03-12 07:40:05.962 UTC [38498][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:05.962 UTC [38498][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-12 07:40:05.962 UTC [38498][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:05.963 UTC [38498][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-12 07:40:06.711 UTC [38498][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.749 user=admin database=postgres host=[local] 2024-03-12 07:40:07.130 UTC [38544][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:07.131 UTC [38544][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-12 07:40:07.131 UTC [38544][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:07.131 UTC [38544][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-12 07:40:07.409 UTC [38544][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.278 user=admin database=postgres host=[local] 2024-03-12 07:40:07.436 UTC [38548][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:07.556 UTC [38548][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-12 07:40:07.556 UTC [38548][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:07.557 UTC [38548][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-12 07:40:07.806 UTC [38548][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.370 user=admin database=postgres host=[local] 2024-03-12 07:40:08.063 UTC [38623][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-12 07:40:08.064 UTC [38623][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-12 07:40:08.064 UTC [38623][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-12 07:40:08.064 UTC [38623][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-12 07:40:08.174 UTC [38623][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.110 user=admin database=postgres host=[local] 2024-03-12 07:40:08.417 UTC [38293][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:08.417 UTC [38293][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:08.418 UTC [38293][postmaster] LOG: background worker "logical replication launcher" (PID 38324) exited with exit code 1 2024-03-12 07:40:08.419 UTC [38309][checkpointer] LOG: shutting down 2024-03-12 07:40:08.419 UTC [38309][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:09.518 UTC [38309][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.099 s, sync=0.001 s, total=1.100 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104958, redo lsn=0/4104958 2024-03-12 07:40:09.527 UTC [38293][postmaster] LOG: database system is shut down 2024-03-12 07:40:09.708 UTC [38838][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:09.709 UTC [38838][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:09.709 UTC [38838][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:09.712 UTC [38845][startup] LOG: database system was shut down at 2024-03-12 07:40:09 UTC 2024-03-12 07:40:09.713 UTC [38838][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:09.822 UTC [38838][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:09.822 UTC [38838][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:09.823 UTC [38838][postmaster] LOG: background worker "logical replication launcher" (PID 38851) exited with exit code 1 2024-03-12 07:40:09.823 UTC [38841][checkpointer] LOG: shutting down 2024-03-12 07:40:09.823 UTC [38841][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:09.825 UTC [38841][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41049D0, redo lsn=0/41049D0 2024-03-12 07:40:09.826 UTC [38838][postmaster] LOG: database system is shut down 2024-03-12 07:40:09.998 UTC [38866][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-12 07:40:09.998 UTC [38866][postmaster] LOG: database system is shut down 2024-03-12 07:40:10.251 UTC [38880][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:10.252 UTC [38880][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:10.252 UTC [38880][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:10.257 UTC [38903][startup] LOG: database system was shut down at 2024-03-12 07:40:09 UTC 2024-03-12 07:40:10.260 UTC [38880][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:10.370 UTC [38880][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:10.370 UTC [38880][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:10.370 UTC [38880][postmaster] LOG: background worker "logical replication launcher" (PID 38906) exited with exit code 1 2024-03-12 07:40:10.370 UTC [38899][checkpointer] LOG: shutting down 2024-03-12 07:40:10.371 UTC [38899][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:10.371 UTC [38899][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/4104A48, redo lsn=0/4104A48 2024-03-12 07:40:10.372 UTC [38880][postmaster] LOG: database system is shut down 2024-03-12 07:40:10.498 UTC [38922][postmaster] FATAL: could not set SSL protocol version range 2024-03-12 07:40:10.498 UTC [38922][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-12 07:40:10.498 UTC [38922][postmaster] LOG: database system is shut down 2024-03-12 07:40:10.615 UTC [38946][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:10.615 UTC [38946][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:10.615 UTC [38946][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:10.619 UTC [38958][startup] LOG: database system was shut down at 2024-03-12 07:40:10 UTC 2024-03-12 07:40:10.620 UTC [38946][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:10.700 UTC [38946][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:10.700 UTC [38946][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:10.701 UTC [38946][postmaster] LOG: background worker "logical replication launcher" (PID 38962) exited with exit code 1 2024-03-12 07:40:10.701 UTC [38956][checkpointer] LOG: shutting down 2024-03-12 07:40:10.701 UTC [38956][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:10.702 UTC [38956][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/4104AC0, redo lsn=0/4104AC0 2024-03-12 07:40:10.703 UTC [38946][postmaster] LOG: database system is shut down 2024-03-12 07:40:10.816 UTC [38985][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:10.817 UTC [38985][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:10.817 UTC [38985][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:10.820 UTC [38990][startup] LOG: database system was shut down at 2024-03-12 07:40:10 UTC 2024-03-12 07:40:10.822 UTC [38985][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:10.921 UTC [39008][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49272 2024-03-12 07:40:10.925 UTC [39008][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-12 07:40:10.925 UTC [39008][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-12 07:40:10.934 UTC [39012][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49274 2024-03-12 07:40:10.941 UTC [39012][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-12 07:40:10.941 UTC [39012][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-12 07:40:10.944 UTC [39012][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-12 07:40:10.944 UTC [39012][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49274 2024-03-12 07:40:10.954 UTC [39016][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49276 2024-03-12 07:40:10.958 UTC [39016][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:40:10.969 UTC [39019][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-12 07:40:10.974 UTC [39019][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:40:10.983 UTC [39022][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-03-12 07:40:10.991 UTC [39022][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:11.000 UTC [39030][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49279 2024-03-12 07:40:11.007 UTC [39030][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:11.016 UTC [39032][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-12 07:40:11.022 UTC [39032][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:11.031 UTC [39034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49281 2024-03-12 07:40:11.038 UTC [39034][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:11.046 UTC [39036][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-12 07:40:11.054 UTC [39036][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-12 07:40:11.054 UTC [39036][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-12 07:40:11.054 UTC [39036][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-12 07:40:11.054 UTC [39036][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49282 2024-03-12 07:40:11.063 UTC [39038][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49283 2024-03-12 07:40:11.070 UTC [39038][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-12 07:40:11.070 UTC [39038][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-12 07:40:11.071 UTC [39038][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-12 07:40:11.071 UTC [39038][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49283 2024-03-12 07:40:11.083 UTC [39041][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-12 07:40:11.091 UTC [39041][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-12 07:40:11.091 UTC [39041][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-12 07:40:11.091 UTC [39041][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-12 07:40:11.091 UTC [39041][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-12 07:40:11.103 UTC [39044][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-12 07:40:11.113 UTC [39044][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-12 07:40:11.113 UTC [39044][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-12 07:40:11.114 UTC [39044][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-12 07:40:11.115 UTC [39044][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-12 07:40:11.126 UTC [39047][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-12 07:40:11.136 UTC [39047][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-12 07:40:11.136 UTC [39047][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-12 07:40:11.136 UTC [39047][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-12 07:40:11.137 UTC [39047][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-12 07:40:11.149 UTC [39049][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-12 07:40:11.157 UTC [39049][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-12 07:40:11.157 UTC [39049][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-12 07:40:11.157 UTC [39049][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-12 07:40:11.157 UTC [39049][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-12 07:40:11.168 UTC [39051][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-12 07:40:11.176 UTC [39051][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-12 07:40:11.176 UTC [39051][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-12 07:40:11.177 UTC [39051][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-12 07:40:11.177 UTC [39051][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49289 2024-03-12 07:40:11.187 UTC [39053][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-12 07:40:11.194 UTC [39053][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-12 07:40:11.194 UTC [39053][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-12 07:40:11.195 UTC [39053][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-12 07:40:11.204 UTC [39055][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-12 07:40:11.211 UTC [39055][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-12 07:40:11.211 UTC [39055][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-12 07:40:11.211 UTC [39055][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-12 07:40:11.212 UTC [39055][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-12 07:40:11.221 UTC [39058][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-12 07:40:11.229 UTC [39058][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:11.238 UTC [39066][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-12 07:40:11.246 UTC [39066][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:11.254 UTC [39068][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-12 07:40:11.262 UTC [39068][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-12 07:40:11.262 UTC [39068][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-12 07:40:11.262 UTC [39068][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-12 07:40:11.263 UTC [39068][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49294 2024-03-12 07:40:11.271 UTC [39070][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-12 07:40:11.279 UTC [39070][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-12 07:40:11.279 UTC [39070][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-12 07:40:11.279 UTC [39070][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-12 07:40:11.279 UTC [39070][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49295 2024-03-12 07:40:11.288 UTC [39072][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-12 07:40:11.295 UTC [39072][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-12 07:40:11.295 UTC [39072][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-12 07:40:11.296 UTC [39072][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-12 07:40:11.296 UTC [39072][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-12 07:40:11.305 UTC [39074][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-12 07:40:11.312 UTC [39074][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-12 07:40:11.312 UTC [39074][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-12 07:40:11.312 UTC [39074][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-12 07:40:11.312 UTC [39074][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-12 07:40:11.321 UTC [39076][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-12 07:40:11.333 UTC [38985][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:11.333 UTC [38985][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:11.334 UTC [38985][postmaster] LOG: background worker "logical replication launcher" (PID 38993) exited with exit code 1 2024-03-12 07:40:11.334 UTC [38988][checkpointer] LOG: shutting down 2024-03-12 07:40:11.334 UTC [38988][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:11.335 UTC [38988][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104B38, redo lsn=0/4104B38 2024-03-12 07:40:11.336 UTC [38985][postmaster] LOG: database system is shut down 2024-03-12 07:40:11.464 UTC [39081][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:11.464 UTC [39081][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:11.465 UTC [39081][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:11.467 UTC [39085][startup] LOG: database system was shut down at 2024-03-12 07:40:11 UTC 2024-03-12 07:40:11.469 UTC [39081][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:11.582 UTC [39098][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-12 07:40:11.593 UTC [39098][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-12 07:40:11.593 UTC [39098][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-12 07:40:11.596 UTC [39098][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-12 07:40:11.597 UTC [39098][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49300 2024-03-12 07:40:11.608 UTC [39101][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-12 07:40:11.620 UTC [39081][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:11.620 UTC [39081][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:11.621 UTC [39081][postmaster] LOG: background worker "logical replication launcher" (PID 39088) exited with exit code 1 2024-03-12 07:40:11.621 UTC [39083][checkpointer] LOG: shutting down 2024-03-12 07:40:11.621 UTC [39083][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:11.621 UTC [39083][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/4104BB0, redo lsn=0/4104BB0 2024-03-12 07:40:11.622 UTC [39081][postmaster] LOG: database system is shut down 2024-03-12 07:40:11.746 UTC [39103][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:11.747 UTC [39103][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:11.747 UTC [39103][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:11.750 UTC [39106][startup] LOG: database system was shut down at 2024-03-12 07:40:11 UTC 2024-03-12 07:40:11.751 UTC [39103][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:11.849 UTC [39111][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-12 07:40:11.859 UTC [39111][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-12 07:40:11.859 UTC [39111][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-12 07:40:11.862 UTC [39111][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-12 07:40:11.862 UTC [39111][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-12 07:40:11.866 UTC [39103][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:11.866 UTC [39103][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:11.867 UTC [39103][postmaster] LOG: background worker "logical replication launcher" (PID 39109) exited with exit code 1 2024-03-12 07:40:11.867 UTC [39104][checkpointer] LOG: shutting down 2024-03-12 07:40:11.867 UTC [39104][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:11.868 UTC [39104][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104C28, redo lsn=0/4104C28 2024-03-12 07:40:11.869 UTC [39103][postmaster] LOG: database system is shut down 2024-03-12 07:40:11.993 UTC [39113][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:11.993 UTC [39113][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:11.994 UTC [39113][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:11.996 UTC [39116][startup] LOG: database system was shut down at 2024-03-12 07:40:11 UTC 2024-03-12 07:40:11.997 UTC [39113][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:12.112 UTC [39121][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-12 07:40:12.122 UTC [39121][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-12 07:40:12.122 UTC [39121][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-12 07:40:12.126 UTC [39121][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-12 07:40:12.126 UTC [39121][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-12 07:40:12.138 UTC [39123][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-12 07:40:12.147 UTC [39123][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-12 07:40:12.147 UTC [39123][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-12 07:40:12.147 UTC [39123][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-12 07:40:12.147 UTC [39123][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-12 07:40:12.157 UTC [39125][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-12 07:40:12.164 UTC [39125][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-12 07:40:12.164 UTC [39125][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-12 07:40:12.165 UTC [39125][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-12 07:40:12.165 UTC [39125][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-12 07:40:12.173 UTC [39127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-12 07:40:12.189 UTC [39129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-12 07:40:12.200 UTC [39113][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:12.200 UTC [39113][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:12.200 UTC [39113][postmaster] LOG: background worker "logical replication launcher" (PID 39119) exited with exit code 1 2024-03-12 07:40:12.200 UTC [39114][checkpointer] LOG: shutting down 2024-03-12 07:40:12.200 UTC [39114][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:12.201 UTC [39114][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/4104CA0, redo lsn=0/4104CA0 2024-03-12 07:40:12.202 UTC [39113][postmaster] LOG: database system is shut down 2024-03-12 07:40:12.338 UTC [39131][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:12.338 UTC [39131][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:12.338 UTC [39131][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:12.341 UTC [39134][startup] LOG: database system was shut down at 2024-03-12 07:40:12 UTC 2024-03-12 07:40:12.343 UTC [39131][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:12.450 UTC [39139][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-12 07:40:12.464 UTC [39139][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-12 07:40:12.464 UTC [39139][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-12 07:40:12.467 UTC [39139][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-12 07:40:12.467 UTC [39139][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-12 07:40:12.479 UTC [39141][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-12 07:40:12.497 UTC [39143][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-12 07:40:12.510 UTC [39131][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:12.510 UTC [39131][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:12.513 UTC [39131][postmaster] LOG: background worker "logical replication launcher" (PID 39137) exited with exit code 1 2024-03-12 07:40:12.513 UTC [39132][checkpointer] LOG: shutting down 2024-03-12 07:40:12.513 UTC [39132][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:12.514 UTC [39132][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/4104D18, redo lsn=0/4104D18 2024-03-12 07:40:12.515 UTC [39131][postmaster] LOG: database system is shut down 2024-03-12 07:40:12.645 UTC [39146][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:12.646 UTC [39146][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:12.646 UTC [39146][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:12.649 UTC [39149][startup] LOG: database system was shut down at 2024-03-12 07:40:12 UTC 2024-03-12 07:40:12.650 UTC [39146][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:12.742 UTC [39154][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-12 07:40:12.754 UTC [39154][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-12 07:40:12.754 UTC [39154][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-12 07:40:12.757 UTC [39154][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-12 07:40:12.757 UTC [39154][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-12 07:40:12.769 UTC [39156][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-12 07:40:12.777 UTC [39156][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-12 07:40:12.777 UTC [39156][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-12 07:40:12.778 UTC [39156][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-12 07:40:12.778 UTC [39156][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-12 07:40:12.787 UTC [39158][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-12 07:40:12.803 UTC [39160][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-12 07:40:12.810 UTC [39160][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-12 07:40:12.810 UTC [39160][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-12 07:40:12.811 UTC [39160][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-12 07:40:12.811 UTC [39160][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-12 07:40:12.819 UTC [39162][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-12 07:40:12.827 UTC [39162][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-12 07:40:12.827 UTC [39162][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-12 07:40:12.827 UTC [39162][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-12 07:40:12.827 UTC [39162][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-12 07:40:12.836 UTC [39164][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-12 07:40:12.843 UTC [39164][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-12 07:40:12.843 UTC [39164][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-12 07:40:12.843 UTC [39164][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-12 07:40:12.844 UTC [39164][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-12 07:40:12.852 UTC [39166][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-12 07:40:12.868 UTC [39168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-12 07:40:12.878 UTC [39146][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:12.879 UTC [39146][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:12.879 UTC [39146][postmaster] LOG: background worker "logical replication launcher" (PID 39152) exited with exit code 1 2024-03-12 07:40:12.879 UTC [39147][checkpointer] LOG: shutting down 2024-03-12 07:40:12.879 UTC [39147][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:12.880 UTC [39147][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/4104D90, redo lsn=0/4104D90 2024-03-12 07:40:12.881 UTC [39146][postmaster] LOG: database system is shut down 2024-03-12 07:40:13.000 UTC [39170][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:13.000 UTC [39170][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:13.000 UTC [39170][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:13.003 UTC [39173][startup] LOG: database system was shut down at 2024-03-12 07:40:12 UTC 2024-03-12 07:40:13.004 UTC [39170][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:13.107 UTC [39178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-12 07:40:13.120 UTC [39178][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-12 07:40:13.120 UTC [39178][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-12 07:40:13.123 UTC [39178][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-12 07:40:13.123 UTC [39178][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-12 07:40:13.135 UTC [39180][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-12 07:40:13.143 UTC [39180][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-12 07:40:13.143 UTC [39180][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-12 07:40:13.143 UTC [39180][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-12 07:40:13.144 UTC [39180][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-12 07:40:13.153 UTC [39182][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-12 07:40:13.167 UTC [39170][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:13.167 UTC [39170][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:13.168 UTC [39170][postmaster] LOG: background worker "logical replication launcher" (PID 39176) exited with exit code 1 2024-03-12 07:40:13.168 UTC [39171][checkpointer] LOG: shutting down 2024-03-12 07:40:13.168 UTC [39171][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:13.168 UTC [39171][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/4104E08, redo lsn=0/4104E08 2024-03-12 07:40:13.169 UTC [39170][postmaster] LOG: database system is shut down 2024-03-12 07:40:13.306 UTC [39185][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:13.306 UTC [39185][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:13.307 UTC [39185][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:13.310 UTC [39188][startup] LOG: database system was shut down at 2024-03-12 07:40:13 UTC 2024-03-12 07:40:13.311 UTC [39185][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:13.420 UTC [39193][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-12 07:40:13.434 UTC [39193][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-12 07:40:13.434 UTC [39193][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-12 07:40:13.437 UTC [39193][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-12 07:40:13.437 UTC [39193][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-12 07:40:13.449 UTC [39195][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-12 07:40:13.458 UTC [39195][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-12 07:40:13.458 UTC [39195][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-12 07:40:13.458 UTC [39195][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-12 07:40:13.458 UTC [39195][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-12 07:40:13.468 UTC [39197][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-12 07:40:13.475 UTC [39197][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-12 07:40:13.475 UTC [39197][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-12 07:40:13.475 UTC [39197][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-12 07:40:13.476 UTC [39197][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-12 07:40:13.479 UTC [39185][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:13.479 UTC [39185][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:13.480 UTC [39185][postmaster] LOG: background worker "logical replication launcher" (PID 39191) exited with exit code 1 2024-03-12 07:40:13.480 UTC [39186][checkpointer] LOG: shutting down 2024-03-12 07:40:13.480 UTC [39186][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:13.481 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.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104E80, redo lsn=0/4104E80 2024-03-12 07:40:13.482 UTC [39185][postmaster] LOG: database system is shut down 2024-03-12 07:40:13.603 UTC [39199][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:13.603 UTC [39199][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:13.603 UTC [39199][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:13.606 UTC [39202][startup] LOG: database system was shut down at 2024-03-12 07:40:13 UTC 2024-03-12 07:40:13.607 UTC [39199][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:13.722 UTC [39207][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-12 07:40:13.736 UTC [39207][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-12 07:40:13.736 UTC [39207][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-12 07:40:13.739 UTC [39207][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-12 07:40:13.740 UTC [39207][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-12 07:40:13.752 UTC [39209][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-12 07:40:13.760 UTC [39209][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-12 07:40:13.760 UTC [39209][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-12 07:40:13.761 UTC [39209][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-12 07:40:13.761 UTC [39209][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-12 07:40:13.771 UTC [39211][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-12 07:40:13.781 UTC [39199][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:13.781 UTC [39199][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:13.782 UTC [39199][postmaster] LOG: background worker "logical replication launcher" (PID 39205) exited with exit code 1 2024-03-12 07:40:13.782 UTC [39200][checkpointer] LOG: shutting down 2024-03-12 07:40:13.782 UTC [39200][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:13.782 UTC [39200][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/4104EF8, redo lsn=0/4104EF8 2024-03-12 07:40:13.783 UTC [39199][postmaster] LOG: database system is shut down 2024-03-12 07:40:13.912 UTC [39213][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:13.912 UTC [39213][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:13.913 UTC [39213][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:13.915 UTC [39217][startup] LOG: database system was shut down at 2024-03-12 07:40:13 UTC 2024-03-12 07:40:13.917 UTC [39213][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:14.025 UTC [39222][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-12 07:40:14.039 UTC [39222][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-12 07:40:14.039 UTC [39222][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-12 07:40:14.042 UTC [39222][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-12 07:40:14.042 UTC [39222][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-12 07:40:14.054 UTC [39224][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-12 07:40:14.063 UTC [39224][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-12 07:40:14.063 UTC [39224][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-12 07:40:14.063 UTC [39224][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-12 07:40:14.063 UTC [39224][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-12 07:40:14.073 UTC [39226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-12 07:40:14.080 UTC [39226][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-12 07:40:14.080 UTC [39226][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-12 07:40:14.080 UTC [39226][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-12 07:40:14.080 UTC [39226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-12 07:40:14.084 UTC [39213][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:14.084 UTC [39213][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:14.085 UTC [39213][postmaster] LOG: background worker "logical replication launcher" (PID 39220) exited with exit code 1 2024-03-12 07:40:14.085 UTC [39215][checkpointer] LOG: shutting down 2024-03-12 07:40:14.085 UTC [39215][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:14.085 UTC [39215][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/4104F70, redo lsn=0/4104F70 2024-03-12 07:40:14.086 UTC [39213][postmaster] LOG: database system is shut down 2024-03-12 07:40:14.221 UTC [39228][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:14.221 UTC [39228][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:14.221 UTC [39228][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:14.224 UTC [39231][startup] LOG: database system was shut down at 2024-03-12 07:40:14 UTC 2024-03-12 07:40:14.226 UTC [39228][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:14.337 UTC [39236][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-12 07:40:14.351 UTC [39236][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-12 07:40:14.351 UTC [39236][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-12 07:40:14.354 UTC [39236][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-12 07:40:14.355 UTC [39236][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-12 07:40:14.367 UTC [39238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-12 07:40:14.380 UTC [39228][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:14.380 UTC [39228][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:14.380 UTC [39228][postmaster] LOG: background worker "logical replication launcher" (PID 39234) exited with exit code 1 2024-03-12 07:40:14.380 UTC [39229][checkpointer] LOG: shutting down 2024-03-12 07:40:14.380 UTC [39229][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:14.381 UTC [39229][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/4104FE8, redo lsn=0/4104FE8 2024-03-12 07:40:14.382 UTC [39228][postmaster] LOG: database system is shut down 2024-03-12 07:40:14.522 UTC [39240][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:14.523 UTC [39240][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:14.523 UTC [39240][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:14.526 UTC [39243][startup] LOG: database system was shut down at 2024-03-12 07:40:14 UTC 2024-03-12 07:40:14.528 UTC [39240][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:14.628 UTC [39248][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-12 07:40:14.642 UTC [39248][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-12 07:40:14.665 UTC [39251][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-12 07:40:14.673 UTC [39251][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-12 07:40:14.673 UTC [39251][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-12 07:40:14.675 UTC [39251][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-12 07:40:14.675 UTC [39251][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-12 07:40:14.685 UTC [39253][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-12 07:40:14.695 UTC [39240][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:14.695 UTC [39240][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:14.696 UTC [39240][postmaster] LOG: background worker "logical replication launcher" (PID 39246) exited with exit code 1 2024-03-12 07:40:14.696 UTC [39241][checkpointer] LOG: shutting down 2024-03-12 07:40:14.696 UTC [39241][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:14.696 UTC [39241][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/4105060, redo lsn=0/4105060 2024-03-12 07:40:14.697 UTC [39240][postmaster] LOG: database system is shut down 2024-03-12 07:40:14.835 UTC [39255][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:14.836 UTC [39255][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:14.836 UTC [39255][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:14.839 UTC [39258][startup] LOG: database system was shut down at 2024-03-12 07:40:14 UTC 2024-03-12 07:40:14.840 UTC [39255][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:14.947 UTC [39263][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-12 07:40:14.960 UTC [39263][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-12 07:40:14.960 UTC [39263][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-12 07:40:14.964 UTC [39263][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-12 07:40:14.964 UTC [39263][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-12 07:40:14.976 UTC [39265][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-12 07:40:14.984 UTC [39265][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-12 07:40:14.994 UTC [39267][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-12 07:40:15.001 UTC [39267][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-12 07:40:15.009 UTC [39269][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-12 07:40:15.016 UTC [39269][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-12 07:40:15.016 UTC [39269][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-12 07:40:15.017 UTC [39269][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-12 07:40:15.017 UTC [39269][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-12 07:40:15.026 UTC [39271][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-12 07:40:15.033 UTC [39271][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-12 07:40:15.033 UTC [39271][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-12 07:40:15.033 UTC [39271][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-12 07:40:15.034 UTC [39271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-12 07:40:15.064 UTC [39276][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-12 07:40:15.072 UTC [39276][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-12 07:40:15.080 UTC [39278][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-12 07:40:15.089 UTC [39278][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-12 07:40:15.089 UTC [39278][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-12 07:40:15.091 UTC [39278][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_lMPj/client.key$$ 2024-03-12 07:40:15.091 UTC [39278][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-12 07:40:15.100 UTC [39280][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-12 07:40:15.109 UTC [39280][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-12 07:40:15.109 UTC [39280][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-12 07:40:15.109 UTC [39280][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_lMPj/client-der.key$$ 2024-03-12 07:40:15.110 UTC [39280][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-12 07:40:15.119 UTC [39282][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-12 07:40:15.128 UTC [39282][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-12 07:40:15.128 UTC [39282][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-12 07:40:15.128 UTC [39282][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_lMPj/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-12 07:40:15.129 UTC [39282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49345 2024-03-12 07:40:15.138 UTC [39284][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-12 07:40:15.146 UTC [39284][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-12 07:40:15.146 UTC [39284][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-12 07:40:15.147 UTC [39284][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_lMPj/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-12 07:40:15.147 UTC [39284][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-12 07:40:15.157 UTC [39286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-12 07:40:15.166 UTC [39286][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-12 07:40:15.166 UTC [39286][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-12 07:40:15.167 UTC [39286][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_lMPj/client.key$$ 2024-03-12 07:40:15.167 UTC [39286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49347 2024-03-12 07:40:15.177 UTC [39288][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-12 07:40:15.185 UTC [39288][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-12 07:40:15.185 UTC [39288][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-12 07:40:15.186 UTC [39288][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_lMPj/client.key$$ 2024-03-12 07:40:15.186 UTC [39288][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-12 07:40:15.195 UTC [39290][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-12 07:40:15.202 UTC [39290][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-12 07:40:15.211 UTC [39292][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-12 07:40:15.217 UTC [39292][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:40:15.225 UTC [39294][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-12 07:40:15.236 UTC [39294][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-12 07:40:15.236 UTC [39294][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-12 07:40:15.239 UTC [39294][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_lMPj/client-dn.key$$ 2024-03-12 07:40:15.239 UTC [39294][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-12 07:40:15.249 UTC [39296][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-12 07:40:15.257 UTC [39296][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-12 07:40:15.257 UTC [39296][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-12 07:40:15.259 UTC [39296][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_lMPj/client-dn.key$$ 2024-03-12 07:40:15.260 UTC [39296][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=49352 2024-03-12 07:40:15.269 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-12 07:40:15.277 UTC [39298][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-12 07:40:15.277 UTC [39298][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-12 07:40:15.280 UTC [39298][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_lMPj/client-dn.key$$ 2024-03-12 07:40:15.280 UTC [39298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-12 07:40:15.299 UTC [39301][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-12 07:40:15.308 UTC [39301][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-12 07:40:15.308 UTC [39301][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-12 07:40:15.308 UTC [39301][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-12 07:40:15.309 UTC [39301][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49354 2024-03-12 07:40:15.318 UTC [39303][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-12 07:40:15.323 UTC [39303][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-12 07:40:15.331 UTC [39305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-12 07:40:15.340 UTC [39305][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-12 07:40:15.340 UTC [39305][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-12 07:40:15.340 UTC [39305][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-12 07:40:15.340 UTC [39305][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-12 07:40:15.348 UTC [39307][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-12 07:40:15.357 UTC [39307][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:40:15.357 UTC [39307][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-12 07:40:15.366 UTC [39309][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-12 07:40:15.374 UTC [39309][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-12 07:40:15.374 UTC [39309][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-12 07:40:15.377 UTC [39309][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_lMPj/client.key$$ 2024-03-12 07:40:15.377 UTC [39309][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-12 07:40:15.386 UTC [39311][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-12 07:40:15.394 UTC [39311][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-12 07:40:15.394 UTC [39311][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-12 07:40:15.394 UTC [39311][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-12 07:40:15.394 UTC [39311][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-12 07:40:15.403 UTC [39313][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-12 07:40:15.412 UTC [39313][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-12 07:40:15.412 UTC [39313][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-12 07:40:15.412 UTC [39313][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_lMPj/client.key$$ 2024-03-12 07:40:15.412 UTC [39313][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-12 07:40:15.416 UTC [39255][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:15.416 UTC [39255][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:15.417 UTC [39255][postmaster] LOG: background worker "logical replication launcher" (PID 39261) exited with exit code 1 2024-03-12 07:40:15.417 UTC [39256][checkpointer] LOG: shutting down 2024-03-12 07:40:15.417 UTC [39256][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:15.418 UTC [39256][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/41050D8, redo lsn=0/41050D8 2024-03-12 07:40:15.419 UTC [39255][postmaster] LOG: database system is shut down 2024-03-12 07:40:15.554 UTC [39315][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:15.555 UTC [39315][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:15.555 UTC [39315][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:15.558 UTC [39318][startup] LOG: database system was shut down at 2024-03-12 07:40:15 UTC 2024-03-12 07:40:15.559 UTC [39315][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:15.663 UTC [39323][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-12 07:40:15.679 UTC [39323][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-12 07:40:15.679 UTC [39323][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-12 07:40:15.683 UTC [39323][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_lMPj/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-12 07:40:15.683 UTC [39323][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=49361 2024-03-12 07:40:15.695 UTC [39325][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-12 07:40:15.704 UTC [39325][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:40:15.704 UTC [39325][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-12 07:40:15.713 UTC [39327][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-12 07:40:15.722 UTC [39327][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:40:15.722 UTC [39327][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-12 07:40:15.725 UTC [39315][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:15.725 UTC [39315][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:15.726 UTC [39315][postmaster] LOG: background worker "logical replication launcher" (PID 39321) exited with exit code 1 2024-03-12 07:40:15.726 UTC [39316][checkpointer] LOG: shutting down 2024-03-12 07:40:15.726 UTC [39316][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:15.727 UTC [39316][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/4105150, redo lsn=0/4105150 2024-03-12 07:40:15.728 UTC [39315][postmaster] LOG: database system is shut down 2024-03-12 07:40:15.857 UTC [39329][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:15.858 UTC [39329][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:15.858 UTC [39329][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:15.861 UTC [39332][startup] LOG: database system was shut down at 2024-03-12 07:40:15 UTC 2024-03-12 07:40:15.862 UTC [39329][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:15.977 UTC [39337][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-12 07:40:15.992 UTC [39337][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:40:15.992 UTC [39337][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-12 07:40:15.998 UTC [39329][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:15.998 UTC [39329][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:15.999 UTC [39329][postmaster] LOG: background worker "logical replication launcher" (PID 39335) exited with exit code 1 2024-03-12 07:40:15.999 UTC [39330][checkpointer] LOG: shutting down 2024-03-12 07:40:15.999 UTC [39330][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:15.999 UTC [39330][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/41051C8, redo lsn=0/41051C8 2024-03-12 07:40:16.001 UTC [39329][postmaster] LOG: database system is shut down 2024-03-12 07:40:16.143 UTC [39339][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:16.144 UTC [39339][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:16.144 UTC [39339][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:16.148 UTC [39342][startup] LOG: database system was shut down at 2024-03-12 07:40:15 UTC 2024-03-12 07:40:16.150 UTC [39339][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:16.242 UTC [39347][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-12 07:40:16.258 UTC [39347][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:40:16.258 UTC [39347][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-12 07:40:16.271 UTC [39349][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-12 07:40:16.281 UTC [39349][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-12 07:40:16.281 UTC [39349][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-12 07:40:16.285 UTC [39339][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:16.286 UTC [39339][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:16.286 UTC [39339][postmaster] LOG: background worker "logical replication launcher" (PID 39345) exited with exit code 1 2024-03-12 07:40:16.286 UTC [39340][checkpointer] LOG: shutting down 2024-03-12 07:40:16.286 UTC [39340][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:16.287 UTC [39340][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/4105240, redo lsn=0/4105240 2024-03-12 07:40:16.288 UTC [39339][postmaster] LOG: database system is shut down 2024-03-12 07:40:16.433 UTC [39351][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:16.434 UTC [39351][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:16.434 UTC [39351][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:16.438 UTC [39354][startup] LOG: database system was shut down at 2024-03-12 07:40:16 UTC 2024-03-12 07:40:16.440 UTC [39351][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:16.532 UTC [39359][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-12 07:40:16.543 UTC [39359][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:16.543 UTC [39359][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:16.558 UTC [39361][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-12 07:40:16.567 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-12 07:40:16.567 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-12 07:40:16.569 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 hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-12 07:40:16.570 UTC [39361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-12 07:40:16.574 UTC [39351][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:16.574 UTC [39351][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:16.575 UTC [39351][postmaster] LOG: background worker "logical replication launcher" (PID 39357) exited with exit code 1 2024-03-12 07:40:16.575 UTC [39352][checkpointer] LOG: shutting down 2024-03-12 07:40:16.575 UTC [39352][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:16.575 UTC [39352][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/41052B8, redo lsn=0/41052B8 2024-03-12 07:40:16.576 UTC [39351][postmaster] LOG: database system is shut down 2024-03-12 07:40:16.724 UTC [39363][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:16.725 UTC [39363][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:16.725 UTC [39363][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:16.728 UTC [39366][startup] LOG: database system was shut down at 2024-03-12 07:40:16 UTC 2024-03-12 07:40:16.730 UTC [39363][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:16.819 UTC [39371][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-12 07:40:16.829 UTC [39371][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:16.829 UTC [39371][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:16.843 UTC [39373][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-12 07:40:16.851 UTC [39373][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-12 07:40:16.851 UTC [39373][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-12 07:40:16.854 UTC [39373][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-12 07:40:16.854 UTC [39373][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-12 07:40:16.858 UTC [39363][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:16.858 UTC [39363][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:16.859 UTC [39363][postmaster] LOG: background worker "logical replication launcher" (PID 39369) exited with exit code 1 2024-03-12 07:40:16.859 UTC [39364][checkpointer] LOG: shutting down 2024-03-12 07:40:16.859 UTC [39364][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:16.860 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.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/4105330, redo lsn=0/4105330 2024-03-12 07:40:16.861 UTC [39363][postmaster] LOG: database system is shut down 2024-03-12 07:40:17.006 UTC [39375][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:17.006 UTC [39375][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:17.006 UTC [39375][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:17.010 UTC [39378][startup] LOG: database system was shut down at 2024-03-12 07:40:16 UTC 2024-03-12 07:40:17.011 UTC [39375][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:17.118 UTC [39383][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-12 07:40:17.129 UTC [39383][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:17.129 UTC [39383][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:17.143 UTC [39385][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-12 07:40:17.152 UTC [39385][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-12 07:40:17.152 UTC [39385][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-12 07:40:17.154 UTC [39385][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-12 07:40:17.155 UTC [39385][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-12 07:40:17.159 UTC [39375][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:17.159 UTC [39375][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:17.160 UTC [39375][postmaster] LOG: background worker "logical replication launcher" (PID 39381) exited with exit code 1 2024-03-12 07:40:17.160 UTC [39376][checkpointer] LOG: shutting down 2024-03-12 07:40:17.160 UTC [39376][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:17.160 UTC [39376][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/41053A8, redo lsn=0/41053A8 2024-03-12 07:40:17.161 UTC [39375][postmaster] LOG: database system is shut down 2024-03-12 07:40:17.304 UTC [39387][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:17.304 UTC [39387][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:17.304 UTC [39387][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:17.307 UTC [39390][startup] LOG: database system was shut down at 2024-03-12 07:40:17 UTC 2024-03-12 07:40:17.309 UTC [39387][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:17.421 UTC [39395][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-12 07:40:17.432 UTC [39395][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:17.432 UTC [39395][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:17.447 UTC [39397][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-12 07:40:17.456 UTC [39397][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-12 07:40:17.456 UTC [39397][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-12 07:40:17.459 UTC [39397][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-12 07:40:17.459 UTC [39397][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-12 07:40:17.464 UTC [39387][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:17.464 UTC [39387][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:17.464 UTC [39387][postmaster] LOG: background worker "logical replication launcher" (PID 39393) exited with exit code 1 2024-03-12 07:40:17.464 UTC [39388][checkpointer] LOG: shutting down 2024-03-12 07:40:17.464 UTC [39388][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:17.465 UTC [39388][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/4105420, redo lsn=0/4105420 2024-03-12 07:40:17.466 UTC [39387][postmaster] LOG: database system is shut down 2024-03-12 07:40:17.596 UTC [39399][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:17.597 UTC [39399][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:17.597 UTC [39399][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:17.600 UTC [39402][startup] LOG: database system was shut down at 2024-03-12 07:40:17 UTC 2024-03-12 07:40:17.602 UTC [39399][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:17.703 UTC [39407][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-12 07:40:17.712 UTC [39407][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:17.712 UTC [39407][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:17.726 UTC [39409][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-12 07:40:17.736 UTC [39409][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-12 07:40:17.736 UTC [39409][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-12 07:40:17.738 UTC [39409][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-12 07:40:17.738 UTC [39409][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-12 07:40:17.743 UTC [39399][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:17.743 UTC [39399][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:17.744 UTC [39399][postmaster] LOG: background worker "logical replication launcher" (PID 39405) exited with exit code 1 2024-03-12 07:40:17.744 UTC [39400][checkpointer] LOG: shutting down 2024-03-12 07:40:17.744 UTC [39400][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:17.744 UTC [39400][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/4105498, redo lsn=0/4105498 2024-03-12 07:40:17.745 UTC [39399][postmaster] LOG: database system is shut down 2024-03-12 07:40:17.871 UTC [39411][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:17.871 UTC [39411][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:17.872 UTC [39411][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:17.874 UTC [39414][startup] LOG: database system was shut down at 2024-03-12 07:40:17 UTC 2024-03-12 07:40:17.876 UTC [39411][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:17.984 UTC [39419][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-12 07:40:17.993 UTC [39419][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:17.993 UTC [39419][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:18.007 UTC [39421][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-12 07:40:18.015 UTC [39421][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-12 07:40:18.015 UTC [39421][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-12 07:40:18.018 UTC [39421][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-12 07:40:18.018 UTC [39421][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-12 07:40:18.023 UTC [39411][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:18.023 UTC [39411][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:18.023 UTC [39411][postmaster] LOG: background worker "logical replication launcher" (PID 39417) exited with exit code 1 2024-03-12 07:40:18.023 UTC [39412][checkpointer] LOG: shutting down 2024-03-12 07:40:18.023 UTC [39412][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:18.024 UTC [39412][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/4105510, redo lsn=0/4105510 2024-03-12 07:40:18.025 UTC [39411][postmaster] LOG: database system is shut down 2024-03-12 07:40:18.150 UTC [39423][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:18.150 UTC [39423][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:18.150 UTC [39423][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:18.153 UTC [39426][startup] LOG: database system was shut down at 2024-03-12 07:40:18 UTC 2024-03-12 07:40:18.155 UTC [39423][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:18.256 UTC [39431][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-12 07:40:18.264 UTC [39431][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:18.264 UTC [39431][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:18.276 UTC [39433][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-12 07:40:18.285 UTC [39433][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-12 07:40:18.285 UTC [39433][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-12 07:40:18.287 UTC [39433][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-12 07:40:18.287 UTC [39433][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-12 07:40:18.292 UTC [39423][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:18.292 UTC [39423][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:18.292 UTC [39423][postmaster] LOG: background worker "logical replication launcher" (PID 39429) exited with exit code 1 2024-03-12 07:40:18.292 UTC [39424][checkpointer] LOG: shutting down 2024-03-12 07:40:18.292 UTC [39424][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:18.293 UTC [39424][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/4105588, redo lsn=0/4105588 2024-03-12 07:40:18.294 UTC [39423][postmaster] LOG: database system is shut down 2024-03-12 07:40:18.422 UTC [39435][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:18.423 UTC [39435][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:18.423 UTC [39435][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:18.425 UTC [39438][startup] LOG: database system was shut down at 2024-03-12 07:40:18 UTC 2024-03-12 07:40:18.427 UTC [39435][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:18.534 UTC [39443][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-12 07:40:18.542 UTC [39443][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:18.542 UTC [39443][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:18.555 UTC [39445][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-12 07:40:18.564 UTC [39445][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-12 07:40:18.564 UTC [39445][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-12 07:40:18.566 UTC [39445][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-12 07:40:18.567 UTC [39445][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-12 07:40:18.571 UTC [39435][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:18.571 UTC [39435][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:18.572 UTC [39435][postmaster] LOG: background worker "logical replication launcher" (PID 39441) exited with exit code 1 2024-03-12 07:40:18.572 UTC [39436][checkpointer] LOG: shutting down 2024-03-12 07:40:18.572 UTC [39436][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:18.572 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.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/4105600, redo lsn=0/4105600 2024-03-12 07:40:18.573 UTC [39435][postmaster] LOG: database system is shut down 2024-03-12 07:40:18.698 UTC [39448][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-12 07:40:18.699 UTC [39448][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 58245 2024-03-12 07:40:18.699 UTC [39448][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/GQBJ9qdnK0/.s.PGSQL.58245" 2024-03-12 07:40:18.701 UTC [39451][startup] LOG: database system was shut down at 2024-03-12 07:40:18 UTC 2024-03-12 07:40:18.702 UTC [39448][postmaster] LOG: database system is ready to accept connections 2024-03-12 07:40:18.813 UTC [39456][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-12 07:40:18.820 UTC [39456][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-12 07:40:18.820 UTC [39456][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-12 07:40:18.832 UTC [39458][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-12 07:40:18.841 UTC [39458][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-12 07:40:18.841 UTC [39458][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-12 07:40:18.844 UTC [39458][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-12 07:40:18.844 UTC [39458][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-12 07:40:18.848 UTC [39448][postmaster] LOG: received fast shutdown request 2024-03-12 07:40:18.848 UTC [39448][postmaster] LOG: aborting any active transactions 2024-03-12 07:40:18.849 UTC [39448][postmaster] LOG: background worker "logical replication launcher" (PID 39454) exited with exit code 1 2024-03-12 07:40:18.849 UTC [39449][checkpointer] LOG: shutting down 2024-03-12 07:40:18.849 UTC [39449][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-12 07:40:18.849 UTC [39449][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/4105678, redo lsn=0/4105678 2024-03-12 07:40:18.850 UTC [39448][postmaster] LOG: database system is shut down 2024-03-12 07:40:18.980 UTC [39460][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-12 07:40:18.980 UTC [39460][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [07:40:19.075](0.230s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"