[14:10:57.186](0.030s) # setting up data directory # Checking port 55530 # Found port 55530 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=55530 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9 Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [14:10:57.197](0.011s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 38241 [14:10:57.782](0.584s) 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 38678 [14:11:00.308](2.526s) # 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-14 14:10:57.691 UTC [38241][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:10:57.691 UTC [38241][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:10:57.708 UTC [38258][startup] LOG: database system was shut down at 2024-03-14 14:07:51 UTC 2024-03-14 14:10:57.715 UTC [38241][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:10:57.775 UTC [38285][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.775 UTC [38285][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-14 14:10:57.775 UTC [38285][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.780 UTC [38285][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:10:57.781 UTC [38285][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=admin database=postgres host=[local] 2024-03-14 14:10:57.798 UTC [38288][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.798 UTC [38288][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-14 14:10:57.798 UTC [38288][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.799 UTC [38288][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:10:57.799 UTC [38288][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-14 14:10:57.842 UTC [38293][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.876 UTC [38293][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-14 14:10:57.876 UTC [38293][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.876 UTC [38293][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:10:57.876 UTC [38293][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=admin database=postgres host=[local] 2024-03-14 14:10:57.977 UTC [38322][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.088 UTC [38322][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-14 14:10:58.096 UTC [38322][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.097 UTC [38322][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:10:58.098 UTC [38322][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.121 user=admin database=postgres host=[local] 2024-03-14 14:10:58.278 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.278 UTC [38381][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-14 14:10:58.278 UTC [38381][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.279 UTC [38381][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:10:58.279 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-14 14:10:58.329 UTC [38393][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.329 UTC [38393][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-14 14:10:58.329 UTC [38393][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.330 UTC [38393][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:10:58.367 UTC [38393][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=admin database=postgres host=[local] 2024-03-14 14:10:58.383 UTC [38409][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.383 UTC [38409][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-14 14:10:58.383 UTC [38409][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.384 UTC [38409][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:10:58.430 UTC [38409][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=admin database=postgres host=[local] 2024-03-14 14:10:58.525 UTC [38448][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.526 UTC [38448][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-14 14:10:58.526 UTC [38448][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.526 UTC [38448][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:10:58.560 UTC [38448][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.035 user=admin database=postgres host=[local] 2024-03-14 14:10:58.589 UTC [38457][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.589 UTC [38457][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-14 14:10:58.589 UTC [38457][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.590 UTC [38457][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:10:58.715 UTC [38457][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.126 user=admin database=postgres host=[local] 2024-03-14 14:10:58.727 UTC [38491][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.727 UTC [38491][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-14 14:10:58.727 UTC [38491][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.728 UTC [38491][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:10:58.819 UTC [38491][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.092 user=admin database=postgres host=[local] 2024-03-14 14:10:58.872 UTC [38511][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.872 UTC [38511][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-14 14:10:58.872 UTC [38511][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.872 UTC [38511][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:10:59.037 UTC [38511][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.165 user=admin database=postgres host=[local] 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: received fast shutdown request 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: aborting any active transactions 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: background worker "logical replication launcher" (PID 38262) exited with exit code 1 2024-03-14 14:10:59.249 UTC [38256][checkpointer] LOG: shutting down 2024-03-14 14:10:59.249 UTC [38256][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.144 UTC [38256][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.894 s, sync=0.001 s, total=0.895 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104A78, redo lsn=0/4104A78 2024-03-14 14:11:00.152 UTC [38241][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.235 UTC [38678][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:00.236 UTC [38678][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:00.236 UTC [38678][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:00.243 UTC [38685][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:00.247 UTC [38678][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:00.313 UTC [38678][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:00.313 UTC [38678][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:00.314 UTC [38678][postmaster] LOG: background worker "logical replication launcher" (PID 38690) exited with exit code 1 2024-03-14 14:11:00.314 UTC [38682][checkpointer] LOG: shutting down 2024-03-14 14:11:00.314 UTC [38682][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.320 UTC [38682][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104AF0, redo lsn=0/4104AF0 2024-03-14 14:11:00.321 UTC [38678][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.440 UTC [38704][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:11:00.440 UTC [38704][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:11:00.534](0.226s) 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 38715 [14:11:00.643](0.109s) 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-14 14:10:57.691 UTC [38241][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:10:57.691 UTC [38241][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:10:57.708 UTC [38258][startup] LOG: database system was shut down at 2024-03-14 14:07:51 UTC 2024-03-14 14:10:57.715 UTC [38241][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:10:57.775 UTC [38285][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.775 UTC [38285][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-14 14:10:57.775 UTC [38285][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.780 UTC [38285][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:10:57.781 UTC [38285][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=admin database=postgres host=[local] 2024-03-14 14:10:57.798 UTC [38288][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.798 UTC [38288][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-14 14:10:57.798 UTC [38288][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.799 UTC [38288][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:10:57.799 UTC [38288][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-14 14:10:57.842 UTC [38293][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.876 UTC [38293][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-14 14:10:57.876 UTC [38293][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.876 UTC [38293][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:10:57.876 UTC [38293][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=admin database=postgres host=[local] 2024-03-14 14:10:57.977 UTC [38322][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.088 UTC [38322][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-14 14:10:58.096 UTC [38322][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.097 UTC [38322][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:10:58.098 UTC [38322][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.121 user=admin database=postgres host=[local] 2024-03-14 14:10:58.278 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.278 UTC [38381][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-14 14:10:58.278 UTC [38381][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.279 UTC [38381][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:10:58.279 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-14 14:10:58.329 UTC [38393][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.329 UTC [38393][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-14 14:10:58.329 UTC [38393][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.330 UTC [38393][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:10:58.367 UTC [38393][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=admin database=postgres host=[local] 2024-03-14 14:10:58.383 UTC [38409][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.383 UTC [38409][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-14 14:10:58.383 UTC [38409][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.384 UTC [38409][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:10:58.430 UTC [38409][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=admin database=postgres host=[local] 2024-03-14 14:10:58.525 UTC [38448][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.526 UTC [38448][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-14 14:10:58.526 UTC [38448][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.526 UTC [38448][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:10:58.560 UTC [38448][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.035 user=admin database=postgres host=[local] 2024-03-14 14:10:58.589 UTC [38457][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.589 UTC [38457][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-14 14:10:58.589 UTC [38457][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.590 UTC [38457][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:10:58.715 UTC [38457][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.126 user=admin database=postgres host=[local] 2024-03-14 14:10:58.727 UTC [38491][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.727 UTC [38491][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-14 14:10:58.727 UTC [38491][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.728 UTC [38491][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:10:58.819 UTC [38491][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.092 user=admin database=postgres host=[local] 2024-03-14 14:10:58.872 UTC [38511][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.872 UTC [38511][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-14 14:10:58.872 UTC [38511][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.872 UTC [38511][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:10:59.037 UTC [38511][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.165 user=admin database=postgres host=[local] 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: received fast shutdown request 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: aborting any active transactions 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: background worker "logical replication launcher" (PID 38262) exited with exit code 1 2024-03-14 14:10:59.249 UTC [38256][checkpointer] LOG: shutting down 2024-03-14 14:10:59.249 UTC [38256][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.144 UTC [38256][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.894 s, sync=0.001 s, total=0.895 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104A78, redo lsn=0/4104A78 2024-03-14 14:11:00.152 UTC [38241][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.235 UTC [38678][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:00.236 UTC [38678][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:00.236 UTC [38678][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:00.243 UTC [38685][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:00.247 UTC [38678][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:00.313 UTC [38678][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:00.313 UTC [38678][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:00.314 UTC [38678][postmaster] LOG: background worker "logical replication launcher" (PID 38690) exited with exit code 1 2024-03-14 14:11:00.314 UTC [38682][checkpointer] LOG: shutting down 2024-03-14 14:11:00.314 UTC [38682][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.320 UTC [38682][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104AF0, redo lsn=0/4104AF0 2024-03-14 14:11:00.321 UTC [38678][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.440 UTC [38704][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:11:00.440 UTC [38704][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.631 UTC [38715][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:00.631 UTC [38715][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:00.631 UTC [38715][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:00.636 UTC [38729][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:00.638 UTC [38715][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:00.647 UTC [38715][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:00.647 UTC [38715][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:00.647 UTC [38715][postmaster] LOG: background worker "logical replication launcher" (PID 38736) exited with exit code 1 2024-03-14 14:11:00.647 UTC [38724][checkpointer] LOG: shutting down 2024-03-14 14:11:00.647 UTC [38724][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.648 UTC [38724][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/4104B68, redo lsn=0/4104B68 2024-03-14 14:11:00.649 UTC [38715][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.772 UTC [38757][postmaster] FATAL: could not set SSL protocol version range 2024-03-14 14:11:00.772 UTC [38757][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-14 14:11:00.772 UTC [38757][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:11:00.867](0.224s) 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 38782 [14:11:00.975](0.109s) ok 5 - restart succeeds with correct SSL protocol bounds [14:11:00.976](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 38825 [14:11:01.217](0.241s) ok 6 - server doesn't accept non-SSL connections [14:11:01.217](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [14:11:01.238](0.021s) ok 8 - connect without server root cert sslmode=require [14:11:01.238](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [14:11:01.257](0.018s) ok 10 - connect without server root cert sslmode=verify-ca [14:11:01.257](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [14:11:01.276](0.019s) ok 12 - connect without server root cert sslmode=verify-full [14:11:01.276](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [14:11:01.299](0.024s) ok 14 - connect with wrong server root cert sslmode=require [14:11:01.299](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [14:11:01.322](0.023s) ok 16 - connect with wrong server root cert sslmode=verify-ca [14:11:01.322](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [14:11:01.342](0.020s) ok 18 - connect with wrong server root cert sslmode=verify-full [14:11:01.342](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [14:11:01.358](0.016s) ok 20 - connect with server CA cert, without root CA [14:11:01.358](0.000s) ok 21 - connect with server CA cert, without root CA: matches [14:11:01.376](0.017s) ok 22 - connect with correct server CA cert file sslmode=require [14:11:01.376](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [14:11:01.393](0.017s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [14:11:01.393](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [14:11:01.411](0.019s) ok 26 - connect with correct server CA cert file sslmode=verify-full [14:11:01.412](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [14:11:01.435](0.023s) ok 28 - cert root file that contains two certificates, order 1 [14:11:01.435](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [14:11:01.455](0.020s) ok 30 - cert root file that contains two certificates, order 2 [14:11:01.455](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [14:11:01.475](0.019s) ok 32 - connect with sslcertmode=disable [14:11:01.475](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [14:11:01.494](0.020s) ok 34 - connect with sslcertmode=allow [14:11:01.495](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [14:11:01.513](0.019s) ok 36 - connect with sslcertmode=require fails without a client certificate [14:11:01.513](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [14:11:01.531](0.018s) ok 38 - sslcrl option with invalid file name [14:11:01.531](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [14:11:01.549](0.017s) ok 40 - CRL belonging to a different CA [14:11:01.549](0.000s) ok 41 - CRL belonging to a different CA: matches [14:11:01.565](0.016s) ok 42 - directory CRL belonging to a different CA [14:11:01.565](0.000s) ok 43 - directory CRL belonging to a different CA: matches [14:11:01.582](0.017s) ok 44 - CRL with a non-revoked cert [14:11:01.582](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [14:11:01.598](0.016s) ok 46 - directory CRL with a non-revoked cert [14:11:01.599](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [14:11:01.615](0.017s) ok 48 - mismatch between host name and server certificate sslmode=require [14:11:01.615](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [14:11:01.632](0.016s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [14:11:01.632](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [14:11:01.648](0.016s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [14:11:01.648](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 38930 [14:11:01.906](0.258s) ok 54 - IP address in the Common Name [14:11:01.906](0.000s) ok 55 - IP address in the Common Name: no stderr [14:11:01.926](0.020s) ok 56 - mismatch between host name and server certificate IP address [14:11:01.926](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 38945 [14:11:02.190](0.263s) ok 58 - IP address in a dNSName [14:11:02.190](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 38955 [14:11:02.481](0.291s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [14:11:02.481](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [14:11:02.504](0.023s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [14:11:02.504](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [14:11:02.522](0.018s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [14:11:02.522](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [14:11:02.538](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [14:11:02.538](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [14:11:02.554](0.016s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [14:11:02.554](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 38973 [14:11:02.838](0.284s) ok 70 - host name matching with a single X.509 Subject Alternative Name [14:11:02.838](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [14:11:02.859](0.021s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [14:11:02.859](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [14:11:02.876](0.017s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [14:11:02.876](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 38988 [14:11:03.156](0.281s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [14:11:03.157](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [14:11:03.178](0.021s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [14:11:03.178](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [14:11:03.195](0.017s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [14:11:03.195](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [14:11:03.212](0.017s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [14:11:03.212](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [14:11:03.228](0.017s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [14:11:03.228](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [14:11:03.245](0.017s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [14:11:03.245](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [14:11:03.261](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [14:11:03.261](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [14:11:03.277](0.016s) ok 90 - IPv6 host with CIDR mask does not match [14:11:03.277](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 39012 [14:11:03.549](0.272s) ok 92 - certificate with both a CN and SANs 1 [14:11:03.549](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [14:11:03.570](0.021s) ok 94 - certificate with both a CN and SANs 2 [14:11:03.570](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [14:11:03.587](0.017s) ok 96 - certificate with both a CN and SANs ignores CN [14:11:03.587](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 39027 [14:11:03.866](0.278s) ok 98 - certificate with both a CN and IP SANs matches CN [14:11:03.866](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [14:11:03.888](0.022s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [14:11:03.888](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [14:11:03.905](0.018s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [14:11:03.905](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 39041 [14:11:04.178](0.272s) ok 104 - certificate with both an IP CN and IP SANs 1 [14:11:04.178](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [14:11:04.200](0.022s) ok 106 - certificate with both an IP CN and IP SANs 2 [14:11:04.200](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [14:11:04.218](0.017s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [14:11:04.218](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 39055 [14:11:04.487](0.269s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [14:11:04.487](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [14:11:04.508](0.022s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [14:11:04.509](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [14:11:04.526](0.018s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [14:11:04.526](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 39069 [14:11:04.795](0.269s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [14:11:04.796](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [14:11:04.816](0.020s) ok 118 - server certificate without CN or SANs sslmode=verify-full [14:11:04.816](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 39081 [14:11:05.081](0.265s) ok 120 - sslrootcert=system does not connect with private CA [14:11:05.082](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [14:11:05.093](0.011s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [14:11:05.093](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [14:11:05.115](0.022s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [14:11:05.115](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [14:11:05.131](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [14:11:05.131](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 39096 [14:11:05.416](0.285s) ok 128 - connects without client-side CRL [14:11:05.416](0.000s) ok 129 - connects without client-side CRL: no stderr [14:11:05.437](0.021s) ok 130 - does not connect with client-side CRL file [14:11:05.437](0.000s) ok 131 - does not connect with client-side CRL file: matches [14:11:05.455](0.018s) ok 132 - does not connect with client-side CRL directory [14:11:05.455](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [14:11:05.472](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [14:11:05.472](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [14:11:05.472](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [14:11:05.489](0.017s) ok 137 - connection success with correct range of TLS protocol versions [14:11:05.489](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [14:11:05.497](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions [14:11:05.497](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [14:11:05.505](0.008s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [14:11:05.505](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [14:11:05.513](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [14:11:05.513](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [14:11:05.513](0.000s) # running server tests [14:11:05.529](0.017s) ok 145 - certificate authorization fails without client cert [14:11:05.530](0.000s) ok 146 - certificate authorization fails without client cert: matches [14:11:05.550](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [14:11:05.550](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [14:11:05.568](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [14:11:05.568](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [14:11:05.586](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [14:11:05.586](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [14:11:05.604](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [14:11:05.604](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [14:11:05.622](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [14:11:05.622](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [14:11:05.640](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [14:11:05.640](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [14:11:05.656](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [14:11:05.657](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [14:11:05.671](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [14:11:05.672](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [14:11:05.692](0.021s) ok 163 - certificate authorization succeeds with DN mapping [14:11:05.693](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [14:11:05.693](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [14:11:05.713](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [14:11:05.713](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [14:11:05.732](0.020s) ok 168 - certificate authorization succeeds with CN mapping [14:11:05.732](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [14:11:05.733](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [14:11:05.733](0.000s) not ok 171 # TODO & SKIP Need Pty support [14:11:05.733](0.000s) not ok 172 # TODO & SKIP Need Pty support [14:11:05.733](0.000s) not ok 173 # TODO & SKIP Need Pty support [14:11:05.733](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_vDPL/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [14:11:05.760](0.027s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [14:11:05.760](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [14:11:05.760](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [14:11:05.774](0.014s) ok 178 - certificate authorization fails because of file permissions [14:11:05.774](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [14:11:05.792](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [14:11:05.792](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [14:11:05.810](0.018s) ok 182 - certificate authorization fails with revoked client cert [14:11:05.810](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [14:11:05.810](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [14:11:05.830](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [14:11:05.830](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [14:11:05.830](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [14:11:05.847](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [14:11:05.848](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [14:11:05.848](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [14:11:05.865](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [14:11:05.866](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [14:11:05.866](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 39156 [14:11:06.132](0.266s) ok 194 - intermediate client certificate is provided by client [14:11:06.132](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [14:11:06.152](0.021s) ok 196 - intermediate client certificate is missing [14:11:06.152](0.000s) ok 197 - intermediate client certificate is missing: matches [14:11:06.172](0.019s) ok 198 - logged client certificate Subjects are truncated if they're too long [14:11:06.172](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 39170 [14:11:06.417](0.245s) ok 200 - intermediate client certificate is untrusted [14:11:06.417](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 39180 [14:11:06.686](0.269s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [14:11:06.686](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [14:11:06.709](0.023s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [14:11:06.709](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 39192 [14:11:06.976](0.266s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [14:11:06.976](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [14:11:06.976](0.000s) # got: '2' # expected: '0' [14:11:06.976](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:11:06.976](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [14:11:06.976](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 55530 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [14:11:07.002](0.025s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [14:11:07.002](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 39204 [14:11:07.274](0.272s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [14:11:07.299](0.026s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [14:11:07.299](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 39216 [14:11:07.560](0.261s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [14:11:07.585](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [14:11:07.585](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 39228 [14:11:07.838](0.253s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [14:11:07.864](0.025s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [14:11:07.864](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 39240 [14:11:08.137](0.273s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [14:11:08.137](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [14:11:08.137](0.000s) # got: '2' # expected: '0' [14:11:08.137](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:11:08.137](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [14:11:08.137](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 55530 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [14:11:08.164](0.026s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [14:11:08.164](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 39252 [14:11:08.435](0.272s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [14:11:08.461](0.026s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [14:11:08.461](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 39264 [14:11:08.711](0.250s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [14:11:08.735](0.024s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [14:11:08.735](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 39276 [14:11:08.986](0.250s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [14:11:09.005](0.020s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [14:11:09.005](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 39288 [14:11:09.267](0.262s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [14:11:09.292](0.025s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [14:11:09.293](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-14 14:10:57.691 UTC [38241][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:10:57.691 UTC [38241][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:10:57.708 UTC [38258][startup] LOG: database system was shut down at 2024-03-14 14:07:51 UTC 2024-03-14 14:10:57.715 UTC [38241][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:10:57.775 UTC [38285][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.775 UTC [38285][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-14 14:10:57.775 UTC [38285][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.780 UTC [38285][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:10:57.781 UTC [38285][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=admin database=postgres host=[local] 2024-03-14 14:10:57.798 UTC [38288][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.798 UTC [38288][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-14 14:10:57.798 UTC [38288][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.799 UTC [38288][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:10:57.799 UTC [38288][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-14 14:10:57.842 UTC [38293][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:57.876 UTC [38293][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-14 14:10:57.876 UTC [38293][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:57.876 UTC [38293][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:10:57.876 UTC [38293][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=admin database=postgres host=[local] 2024-03-14 14:10:57.977 UTC [38322][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.088 UTC [38322][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-14 14:10:58.096 UTC [38322][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.097 UTC [38322][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:10:58.098 UTC [38322][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.121 user=admin database=postgres host=[local] 2024-03-14 14:10:58.278 UTC [38381][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.278 UTC [38381][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-14 14:10:58.278 UTC [38381][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.279 UTC [38381][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:10:58.279 UTC [38381][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-14 14:10:58.329 UTC [38393][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.329 UTC [38393][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-14 14:10:58.329 UTC [38393][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.330 UTC [38393][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:10:58.367 UTC [38393][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.038 user=admin database=postgres host=[local] 2024-03-14 14:10:58.383 UTC [38409][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.383 UTC [38409][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-14 14:10:58.383 UTC [38409][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.384 UTC [38409][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:10:58.430 UTC [38409][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=admin database=postgres host=[local] 2024-03-14 14:10:58.525 UTC [38448][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.526 UTC [38448][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-14 14:10:58.526 UTC [38448][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.526 UTC [38448][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:10:58.560 UTC [38448][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.035 user=admin database=postgres host=[local] 2024-03-14 14:10:58.589 UTC [38457][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.589 UTC [38457][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-14 14:10:58.589 UTC [38457][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.590 UTC [38457][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:10:58.715 UTC [38457][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.126 user=admin database=postgres host=[local] 2024-03-14 14:10:58.727 UTC [38491][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.727 UTC [38491][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-14 14:10:58.727 UTC [38491][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.728 UTC [38491][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:10:58.819 UTC [38491][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.092 user=admin database=postgres host=[local] 2024-03-14 14:10:58.872 UTC [38511][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:10:58.872 UTC [38511][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-14 14:10:58.872 UTC [38511][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-14 14:10:58.872 UTC [38511][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:10:59.037 UTC [38511][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.165 user=admin database=postgres host=[local] 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: received fast shutdown request 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: aborting any active transactions 2024-03-14 14:10:59.248 UTC [38241][postmaster] LOG: background worker "logical replication launcher" (PID 38262) exited with exit code 1 2024-03-14 14:10:59.249 UTC [38256][checkpointer] LOG: shutting down 2024-03-14 14:10:59.249 UTC [38256][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.144 UTC [38256][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.894 s, sync=0.001 s, total=0.895 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104A78, redo lsn=0/4104A78 2024-03-14 14:11:00.152 UTC [38241][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.235 UTC [38678][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:00.236 UTC [38678][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:00.236 UTC [38678][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:00.243 UTC [38685][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:00.247 UTC [38678][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:00.313 UTC [38678][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:00.313 UTC [38678][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:00.314 UTC [38678][postmaster] LOG: background worker "logical replication launcher" (PID 38690) exited with exit code 1 2024-03-14 14:11:00.314 UTC [38682][checkpointer] LOG: shutting down 2024-03-14 14:11:00.314 UTC [38682][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.320 UTC [38682][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104AF0, redo lsn=0/4104AF0 2024-03-14 14:11:00.321 UTC [38678][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.440 UTC [38704][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:11:00.440 UTC [38704][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.631 UTC [38715][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:00.631 UTC [38715][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:00.631 UTC [38715][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:00.636 UTC [38729][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:00.638 UTC [38715][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:00.647 UTC [38715][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:00.647 UTC [38715][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:00.647 UTC [38715][postmaster] LOG: background worker "logical replication launcher" (PID 38736) exited with exit code 1 2024-03-14 14:11:00.647 UTC [38724][checkpointer] LOG: shutting down 2024-03-14 14:11:00.647 UTC [38724][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.648 UTC [38724][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/4104B68, redo lsn=0/4104B68 2024-03-14 14:11:00.649 UTC [38715][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.772 UTC [38757][postmaster] FATAL: could not set SSL protocol version range 2024-03-14 14:11:00.772 UTC [38757][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-14 14:11:00.772 UTC [38757][postmaster] LOG: database system is shut down 2024-03-14 14:11:00.887 UTC [38782][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:00.888 UTC [38782][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:00.888 UTC [38782][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:00.890 UTC [38787][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:00.891 UTC [38782][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:00.979 UTC [38782][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:00.979 UTC [38782][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:00.979 UTC [38782][postmaster] LOG: background worker "logical replication launcher" (PID 38790) exited with exit code 1 2024-03-14 14:11:00.979 UTC [38785][checkpointer] LOG: shutting down 2024-03-14 14:11:00.980 UTC [38785][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:00.980 UTC [38785][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104BE0, redo lsn=0/4104BE0 2024-03-14 14:11:00.981 UTC [38782][postmaster] LOG: database system is shut down 2024-03-14 14:11:01.108 UTC [38825][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:01.109 UTC [38825][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:01.109 UTC [38825][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:01.111 UTC [38832][startup] LOG: database system was shut down at 2024-03-14 14:11:00 UTC 2024-03-14 14:11:01.113 UTC [38825][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:01.212 UTC [38855][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49271 2024-03-14 14:11:01.216 UTC [38855][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-14 14:11:01.216 UTC [38855][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-14 14:11:01.226 UTC [38859][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49273 2024-03-14 14:11:01.234 UTC [38859][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-14 14:11:01.234 UTC [38859][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-14 14:11:01.237 UTC [38859][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-14 14:11:01.237 UTC [38859][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49273 2024-03-14 14:11:01.250 UTC [38863][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-14 14:11:01.256 UTC [38863][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:11:01.270 UTC [38866][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49276 2024-03-14 14:11:01.275 UTC [38866][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:11:01.289 UTC [38871][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-03-14 14:11:01.298 UTC [38871][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:01.313 UTC [38875][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-14 14:11:01.321 UTC [38875][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:01.334 UTC [38877][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49281 2024-03-14 14:11:01.341 UTC [38877][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:01.350 UTC [38879][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-14 14:11:01.357 UTC [38879][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:01.367 UTC [38881][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49283 2024-03-14 14:11:01.374 UTC [38881][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-14 14:11:01.374 UTC [38881][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-14 14:11:01.375 UTC [38881][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-14 14:11:01.375 UTC [38881][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49283 2024-03-14 14:11:01.384 UTC [38883][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-14 14:11:01.391 UTC [38883][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-14 14:11:01.391 UTC [38883][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-14 14:11:01.392 UTC [38883][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-14 14:11:01.392 UTC [38883][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-14 14:11:01.402 UTC [38885][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49285 2024-03-14 14:11:01.410 UTC [38885][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-14 14:11:01.410 UTC [38885][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-14 14:11:01.410 UTC [38885][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-14 14:11:01.411 UTC [38885][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49285 2024-03-14 14:11:01.422 UTC [38888][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-14 14:11:01.433 UTC [38888][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-14 14:11:01.433 UTC [38888][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-14 14:11:01.433 UTC [38888][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-14 14:11:01.434 UTC [38888][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-14 14:11:01.445 UTC [38896][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-14 14:11:01.453 UTC [38896][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-14 14:11:01.454 UTC [38896][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-14 14:11:01.454 UTC [38896][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-14 14:11:01.454 UTC [38896][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-14 14:11:01.465 UTC [38898][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-14 14:11:01.473 UTC [38898][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-14 14:11:01.473 UTC [38898][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-14 14:11:01.473 UTC [38898][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-14 14:11:01.474 UTC [38898][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-14 14:11:01.486 UTC [38900][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-14 14:11:01.493 UTC [38900][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-14 14:11:01.493 UTC [38900][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-14 14:11:01.493 UTC [38900][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-14 14:11:01.494 UTC [38900][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49289 2024-03-14 14:11:01.504 UTC [38902][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-14 14:11:01.512 UTC [38902][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-14 14:11:01.512 UTC [38902][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-14 14:11:01.512 UTC [38902][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-14 14:11:01.522 UTC [38904][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-14 14:11:01.530 UTC [38904][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-14 14:11:01.530 UTC [38904][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-14 14:11:01.530 UTC [38904][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-14 14:11:01.530 UTC [38904][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-14 14:11:01.540 UTC [38908][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-14 14:11:01.548 UTC [38908][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:01.557 UTC [38911][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-14 14:11:01.564 UTC [38911][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:01.573 UTC [38913][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-14 14:11:01.580 UTC [38913][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-14 14:11:01.580 UTC [38913][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-14 14:11:01.581 UTC [38913][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-14 14:11:01.581 UTC [38913][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49295 2024-03-14 14:11:01.590 UTC [38915][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-14 14:11:01.597 UTC [38915][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-14 14:11:01.597 UTC [38915][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-14 14:11:01.598 UTC [38915][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-14 14:11:01.598 UTC [38915][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-14 14:11:01.607 UTC [38917][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-14 14:11:01.614 UTC [38917][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-14 14:11:01.614 UTC [38917][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-14 14:11:01.614 UTC [38917][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-14 14:11:01.614 UTC [38917][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-14 14:11:01.623 UTC [38919][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-14 14:11:01.630 UTC [38919][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-14 14:11:01.630 UTC [38919][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-14 14:11:01.631 UTC [38919][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-14 14:11:01.631 UTC [38919][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-14 14:11:01.640 UTC [38921][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-14 14:11:01.651 UTC [38825][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:01.651 UTC [38825][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:01.651 UTC [38825][postmaster] LOG: background worker "logical replication launcher" (PID 38835) exited with exit code 1 2024-03-14 14:11:01.652 UTC [38830][checkpointer] LOG: shutting down 2024-03-14 14:11:01.652 UTC [38830][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:01.652 UTC [38830][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.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104C58, redo lsn=0/4104C58 2024-03-14 14:11:01.653 UTC [38825][postmaster] LOG: database system is shut down 2024-03-14 14:11:01.791 UTC [38930][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:01.791 UTC [38930][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:01.792 UTC [38930][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:01.794 UTC [38935][startup] LOG: database system was shut down at 2024-03-14 14:11:01 UTC 2024-03-14 14:11:01.796 UTC [38930][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:01.890 UTC [38941][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-14 14:11:01.901 UTC [38941][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-14 14:11:01.901 UTC [38941][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-14 14:11:01.904 UTC [38941][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-14 14:11:01.905 UTC [38941][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-14 14:11:01.918 UTC [38943][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-14 14:11:01.930 UTC [38930][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:01.930 UTC [38930][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:01.931 UTC [38930][postmaster] LOG: background worker "logical replication launcher" (PID 38939) exited with exit code 1 2024-03-14 14:11:01.931 UTC [38933][checkpointer] LOG: shutting down 2024-03-14 14:11:01.931 UTC [38933][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:01.932 UTC [38933][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.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/4104CD0, redo lsn=0/4104CD0 2024-03-14 14:11:01.933 UTC [38930][postmaster] LOG: database system is shut down 2024-03-14 14:11:02.065 UTC [38945][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:02.065 UTC [38945][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:02.065 UTC [38945][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:02.068 UTC [38948][startup] LOG: database system was shut down at 2024-03-14 14:11:01 UTC 2024-03-14 14:11:02.070 UTC [38945][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:02.172 UTC [38953][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-14 14:11:02.185 UTC [38953][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-14 14:11:02.185 UTC [38953][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-14 14:11:02.188 UTC [38953][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-14 14:11:02.188 UTC [38953][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-14 14:11:02.194 UTC [38945][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:02.194 UTC [38945][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:02.195 UTC [38945][postmaster] LOG: background worker "logical replication launcher" (PID 38951) exited with exit code 1 2024-03-14 14:11:02.195 UTC [38946][checkpointer] LOG: shutting down 2024-03-14 14:11:02.195 UTC [38946][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:02.195 UTC [38946][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/4104D48, redo lsn=0/4104D48 2024-03-14 14:11:02.196 UTC [38945][postmaster] LOG: database system is shut down 2024-03-14 14:11:02.347 UTC [38955][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:02.347 UTC [38955][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:02.347 UTC [38955][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:02.352 UTC [38958][startup] LOG: database system was shut down at 2024-03-14 14:11:02 UTC 2024-03-14 14:11:02.355 UTC [38955][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:02.460 UTC [38963][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-14 14:11:02.475 UTC [38963][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-14 14:11:02.475 UTC [38963][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-14 14:11:02.479 UTC [38963][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-14 14:11:02.479 UTC [38963][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-14 14:11:02.493 UTC [38965][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-14 14:11:02.502 UTC [38965][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-14 14:11:02.502 UTC [38965][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-14 14:11:02.502 UTC [38965][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-14 14:11:02.503 UTC [38965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-14 14:11:02.513 UTC [38967][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-14 14:11:02.520 UTC [38967][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-14 14:11:02.520 UTC [38967][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-14 14:11:02.521 UTC [38967][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-14 14:11:02.521 UTC [38967][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-14 14:11:02.530 UTC [38969][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-14 14:11:02.546 UTC [38971][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-14 14:11:02.557 UTC [38955][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:02.557 UTC [38955][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:02.558 UTC [38955][postmaster] LOG: background worker "logical replication launcher" (PID 38961) exited with exit code 1 2024-03-14 14:11:02.558 UTC [38956][checkpointer] LOG: shutting down 2024-03-14 14:11:02.558 UTC [38956][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:02.558 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/4104DC0, redo lsn=0/4104DC0 2024-03-14 14:11:02.559 UTC [38955][postmaster] LOG: database system is shut down 2024-03-14 14:11:02.701 UTC [38973][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:02.701 UTC [38973][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:02.702 UTC [38973][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:02.705 UTC [38976][startup] LOG: database system was shut down at 2024-03-14 14:11:02 UTC 2024-03-14 14:11:02.707 UTC [38973][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:02.819 UTC [38981][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-14 14:11:02.832 UTC [38981][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-14 14:11:02.832 UTC [38981][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-14 14:11:02.836 UTC [38981][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-14 14:11:02.836 UTC [38981][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-14 14:11:02.849 UTC [38983][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-14 14:11:02.868 UTC [38985][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-14 14:11:02.882 UTC [38973][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:02.882 UTC [38973][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:02.883 UTC [38973][postmaster] LOG: background worker "logical replication launcher" (PID 38979) exited with exit code 1 2024-03-14 14:11:02.883 UTC [38974][checkpointer] LOG: shutting down 2024-03-14 14:11:02.883 UTC [38974][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:02.883 UTC [38974][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/4104E38, redo lsn=0/4104E38 2024-03-14 14:11:02.884 UTC [38973][postmaster] LOG: database system is shut down 2024-03-14 14:11:03.025 UTC [38988][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:03.025 UTC [38988][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:03.026 UTC [38988][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:03.029 UTC [38991][startup] LOG: database system was shut down at 2024-03-14 14:11:02 UTC 2024-03-14 14:11:03.030 UTC [38988][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:03.140 UTC [38996][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-14 14:11:03.151 UTC [38996][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-14 14:11:03.151 UTC [38996][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-14 14:11:03.155 UTC [38996][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-14 14:11:03.155 UTC [38996][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-14 14:11:03.167 UTC [38998][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-14 14:11:03.176 UTC [38998][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-14 14:11:03.176 UTC [38998][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-14 14:11:03.177 UTC [38998][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-14 14:11:03.177 UTC [38998][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-14 14:11:03.187 UTC [39000][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-14 14:11:03.203 UTC [39002][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-14 14:11:03.210 UTC [39002][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-14 14:11:03.210 UTC [39002][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-14 14:11:03.211 UTC [39002][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-14 14:11:03.211 UTC [39002][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-14 14:11:03.220 UTC [39004][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-14 14:11:03.227 UTC [39004][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-14 14:11:03.227 UTC [39004][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-14 14:11:03.227 UTC [39004][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-14 14:11:03.228 UTC [39004][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-14 14:11:03.236 UTC [39006][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-14 14:11:03.244 UTC [39006][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-14 14:11:03.244 UTC [39006][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-14 14:11:03.244 UTC [39006][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-14 14:11:03.244 UTC [39006][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-14 14:11:03.253 UTC [39008][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-14 14:11:03.269 UTC [39010][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-14 14:11:03.280 UTC [38988][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:03.280 UTC [38988][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:03.280 UTC [38988][postmaster] LOG: background worker "logical replication launcher" (PID 38994) exited with exit code 1 2024-03-14 14:11:03.280 UTC [38989][checkpointer] LOG: shutting down 2024-03-14 14:11:03.280 UTC [38989][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:03.281 UTC [38989][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/4104EB0, redo lsn=0/4104EB0 2024-03-14 14:11:03.282 UTC [38988][postmaster] LOG: database system is shut down 2024-03-14 14:11:03.413 UTC [39012][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:03.413 UTC [39012][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:03.413 UTC [39012][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:03.416 UTC [39015][startup] LOG: database system was shut down at 2024-03-14 14:11:03 UTC 2024-03-14 14:11:03.418 UTC [39012][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:03.531 UTC [39020][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-14 14:11:03.544 UTC [39020][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-14 14:11:03.544 UTC [39020][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-14 14:11:03.548 UTC [39020][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-14 14:11:03.548 UTC [39020][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-14 14:11:03.560 UTC [39022][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-14 14:11:03.569 UTC [39022][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-14 14:11:03.569 UTC [39022][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-14 14:11:03.569 UTC [39022][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-14 14:11:03.569 UTC [39022][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-14 14:11:03.579 UTC [39024][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-14 14:11:03.593 UTC [39012][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:03.594 UTC [39012][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:03.594 UTC [39012][postmaster] LOG: background worker "logical replication launcher" (PID 39018) exited with exit code 1 2024-03-14 14:11:03.594 UTC [39013][checkpointer] LOG: shutting down 2024-03-14 14:11:03.594 UTC [39013][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:03.595 UTC [39013][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/4104F28, redo lsn=0/4104F28 2024-03-14 14:11:03.596 UTC [39012][postmaster] LOG: database system is shut down 2024-03-14 14:11:03.738 UTC [39027][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:03.738 UTC [39027][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:03.738 UTC [39027][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:03.742 UTC [39030][startup] LOG: database system was shut down at 2024-03-14 14:11:03 UTC 2024-03-14 14:11:03.744 UTC [39027][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:03.848 UTC [39035][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-14 14:11:03.861 UTC [39035][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-14 14:11:03.861 UTC [39035][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-14 14:11:03.864 UTC [39035][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-14 14:11:03.865 UTC [39035][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-14 14:11:03.877 UTC [39037][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-14 14:11:03.886 UTC [39037][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-14 14:11:03.886 UTC [39037][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-14 14:11:03.886 UTC [39037][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-14 14:11:03.887 UTC [39037][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-14 14:11:03.897 UTC [39039][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-14 14:11:03.904 UTC [39039][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-14 14:11:03.904 UTC [39039][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-14 14:11:03.904 UTC [39039][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-14 14:11:03.905 UTC [39039][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-14 14:11:03.908 UTC [39027][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:03.908 UTC [39027][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:03.909 UTC [39027][postmaster] LOG: background worker "logical replication launcher" (PID 39033) exited with exit code 1 2024-03-14 14:11:03.909 UTC [39028][checkpointer] LOG: shutting down 2024-03-14 14:11:03.909 UTC [39028][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:03.910 UTC [39028][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/4104FA0, redo lsn=0/4104FA0 2024-03-14 14:11:03.911 UTC [39027][postmaster] LOG: database system is shut down 2024-03-14 14:11:04.039 UTC [39041][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:04.039 UTC [39041][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:04.039 UTC [39041][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:04.042 UTC [39044][startup] LOG: database system was shut down at 2024-03-14 14:11:03 UTC 2024-03-14 14:11:04.044 UTC [39041][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:04.159 UTC [39049][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-14 14:11:04.173 UTC [39049][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-14 14:11:04.173 UTC [39049][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-14 14:11:04.176 UTC [39049][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-14 14:11:04.177 UTC [39049][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-14 14:11:04.189 UTC [39051][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-14 14:11:04.198 UTC [39051][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-14 14:11:04.198 UTC [39051][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-14 14:11:04.199 UTC [39051][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-14 14:11:04.199 UTC [39051][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-14 14:11:04.210 UTC [39053][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-14 14:11:04.221 UTC [39041][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:04.221 UTC [39041][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:04.221 UTC [39041][postmaster] LOG: background worker "logical replication launcher" (PID 39047) exited with exit code 1 2024-03-14 14:11:04.222 UTC [39042][checkpointer] LOG: shutting down 2024-03-14 14:11:04.222 UTC [39042][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:04.222 UTC [39042][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/4105018, redo lsn=0/4105018 2024-03-14 14:11:04.223 UTC [39041][postmaster] LOG: database system is shut down 2024-03-14 14:11:04.361 UTC [39055][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:04.362 UTC [39055][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:04.362 UTC [39055][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:04.365 UTC [39058][startup] LOG: database system was shut down at 2024-03-14 14:11:04 UTC 2024-03-14 14:11:04.367 UTC [39055][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:04.468 UTC [39063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-14 14:11:04.482 UTC [39063][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-14 14:11:04.482 UTC [39063][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-14 14:11:04.485 UTC [39063][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-14 14:11:04.485 UTC [39063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-14 14:11:04.498 UTC [39065][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-14 14:11:04.507 UTC [39065][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-14 14:11:04.507 UTC [39065][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-14 14:11:04.507 UTC [39065][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-14 14:11:04.507 UTC [39065][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-14 14:11:04.518 UTC [39067][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-14 14:11:04.525 UTC [39067][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-14 14:11:04.525 UTC [39067][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-14 14:11:04.525 UTC [39067][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-14 14:11:04.525 UTC [39067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-14 14:11:04.529 UTC [39055][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:04.529 UTC [39055][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:04.530 UTC [39055][postmaster] LOG: background worker "logical replication launcher" (PID 39061) exited with exit code 1 2024-03-14 14:11:04.530 UTC [39056][checkpointer] LOG: shutting down 2024-03-14 14:11:04.530 UTC [39056][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:04.531 UTC [39056][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105090, redo lsn=0/4105090 2024-03-14 14:11:04.532 UTC [39055][postmaster] LOG: database system is shut down 2024-03-14 14:11:04.659 UTC [39069][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:04.659 UTC [39069][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:04.659 UTC [39069][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:04.662 UTC [39072][startup] LOG: database system was shut down at 2024-03-14 14:11:04 UTC 2024-03-14 14:11:04.663 UTC [39069][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:04.777 UTC [39077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-14 14:11:04.790 UTC [39077][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:11:04.790 UTC [39077][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-14 14:11:04.794 UTC [39077][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-14 14:11:04.794 UTC [39077][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-14 14:11:04.806 UTC [39079][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-14 14:11:04.819 UTC [39069][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:04.819 UTC [39069][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:04.820 UTC [39069][postmaster] LOG: background worker "logical replication launcher" (PID 39075) exited with exit code 1 2024-03-14 14:11:04.820 UTC [39070][checkpointer] LOG: shutting down 2024-03-14 14:11:04.820 UTC [39070][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:04.821 UTC [39070][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/4105108, redo lsn=0/4105108 2024-03-14 14:11:04.822 UTC [39069][postmaster] LOG: database system is shut down 2024-03-14 14:11:04.960 UTC [39081][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:04.960 UTC [39081][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:04.960 UTC [39081][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:04.964 UTC [39084][startup] LOG: database system was shut down at 2024-03-14 14:11:04 UTC 2024-03-14 14:11:04.966 UTC [39081][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:05.067 UTC [39089][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-14 14:11:05.080 UTC [39089][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:11:05.103 UTC [39092][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-14 14:11:05.111 UTC [39092][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-14 14:11:05.111 UTC [39092][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-14 14:11:05.113 UTC [39092][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-14 14:11:05.114 UTC [39092][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-14 14:11:05.123 UTC [39094][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-14 14:11:05.134 UTC [39081][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:05.134 UTC [39081][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:05.135 UTC [39081][postmaster] LOG: background worker "logical replication launcher" (PID 39087) exited with exit code 1 2024-03-14 14:11:05.135 UTC [39082][checkpointer] LOG: shutting down 2024-03-14 14:11:05.135 UTC [39082][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:05.135 UTC [39082][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/4105180, redo lsn=0/4105180 2024-03-14 14:11:05.136 UTC [39081][postmaster] LOG: database system is shut down 2024-03-14 14:11:05.277 UTC [39096][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:05.278 UTC [39096][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:05.278 UTC [39096][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:05.281 UTC [39099][startup] LOG: database system was shut down at 2024-03-14 14:11:05 UTC 2024-03-14 14:11:05.283 UTC [39096][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:05.397 UTC [39104][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-14 14:11:05.411 UTC [39104][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-14 14:11:05.411 UTC [39104][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-14 14:11:05.414 UTC [39104][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-14 14:11:05.414 UTC [39104][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-14 14:11:05.428 UTC [39106][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-14 14:11:05.436 UTC [39106][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-14 14:11:05.447 UTC [39108][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-14 14:11:05.454 UTC [39108][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-14 14:11:05.463 UTC [39110][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-14 14:11:05.470 UTC [39110][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-14 14:11:05.470 UTC [39110][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-14 14:11:05.471 UTC [39110][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-14 14:11:05.472 UTC [39110][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-14 14:11:05.481 UTC [39112][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-14 14:11:05.488 UTC [39112][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-14 14:11:05.488 UTC [39112][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-14 14:11:05.488 UTC [39112][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-14 14:11:05.489 UTC [39112][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-14 14:11:05.521 UTC [39117][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-14 14:11:05.529 UTC [39117][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-14 14:11:05.538 UTC [39119][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-14 14:11:05.546 UTC [39119][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-14 14:11:05.546 UTC [39119][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-14 14:11:05.549 UTC [39119][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_vDPL/client.key$$ 2024-03-14 14:11:05.549 UTC [39119][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-14 14:11:05.558 UTC [39121][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-14 14:11:05.566 UTC [39121][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-14 14:11:05.566 UTC [39121][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-14 14:11:05.567 UTC [39121][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_vDPL/client-der.key$$ 2024-03-14 14:11:05.567 UTC [39121][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-14 14:11:05.576 UTC [39123][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-14 14:11:05.585 UTC [39123][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-14 14:11:05.585 UTC [39123][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-14 14:11:05.585 UTC [39123][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_vDPL/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-14 14:11:05.585 UTC [39123][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-14 14:11:05.594 UTC [39125][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-14 14:11:05.603 UTC [39125][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-14 14:11:05.603 UTC [39125][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-14 14:11:05.603 UTC [39125][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_vDPL/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-14 14:11:05.603 UTC [39125][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-14 14:11:05.612 UTC [39127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-14 14:11:05.621 UTC [39127][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-14 14:11:05.621 UTC [39127][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-14 14:11:05.621 UTC [39127][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_vDPL/client.key$$ 2024-03-14 14:11:05.621 UTC [39127][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-14 14:11:05.630 UTC [39129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-14 14:11:05.639 UTC [39129][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-14 14:11:05.639 UTC [39129][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-14 14:11:05.639 UTC [39129][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_vDPL/client.key$$ 2024-03-14 14:11:05.639 UTC [39129][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-14 14:11:05.648 UTC [39131][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-14 14:11:05.656 UTC [39131][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-14 14:11:05.665 UTC [39133][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-14 14:11:05.671 UTC [39133][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:11:05.679 UTC [39135][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-14 14:11:05.689 UTC [39135][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-14 14:11:05.689 UTC [39135][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-14 14:11:05.691 UTC [39135][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_vDPL/client-dn.key$$ 2024-03-14 14:11:05.692 UTC [39135][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-14 14:11:05.701 UTC [39137][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-14 14:11:05.709 UTC [39137][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-14 14:11:05.709 UTC [39137][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-14 14:11:05.712 UTC [39137][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_vDPL/client-dn.key$$ 2024-03-14 14:11:05.712 UTC [39137][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=49352 2024-03-14 14:11:05.721 UTC [39139][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-14 14:11:05.729 UTC [39139][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-14 14:11:05.729 UTC [39139][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-14 14:11:05.731 UTC [39139][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_vDPL/client-dn.key$$ 2024-03-14 14:11:05.732 UTC [39139][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-14 14:11:05.750 UTC [39142][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-14 14:11:05.758 UTC [39142][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-14 14:11:05.758 UTC [39142][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-14 14:11:05.758 UTC [39142][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-14 14:11:05.759 UTC [39142][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49354 2024-03-14 14:11:05.768 UTC [39144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-14 14:11:05.773 UTC [39144][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:11:05.782 UTC [39146][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-14 14:11:05.791 UTC [39146][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-14 14:11:05.791 UTC [39146][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-14 14:11:05.791 UTC [39146][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-14 14:11:05.791 UTC [39146][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-14 14:11:05.800 UTC [39148][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-14 14:11:05.809 UTC [39148][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:11:05.809 UTC [39148][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-14 14:11:05.818 UTC [39150][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-14 14:11:05.827 UTC [39150][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-14 14:11:05.827 UTC [39150][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-14 14:11:05.829 UTC [39150][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_vDPL/client.key$$ 2024-03-14 14:11:05.829 UTC [39150][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-14 14:11:05.838 UTC [39152][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-14 14:11:05.847 UTC [39152][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-14 14:11:05.847 UTC [39152][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-14 14:11:05.847 UTC [39152][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-14 14:11:05.847 UTC [39152][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-14 14:11:05.856 UTC [39154][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-14 14:11:05.864 UTC [39154][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-14 14:11:05.864 UTC [39154][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-14 14:11:05.865 UTC [39154][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_vDPL/client.key$$ 2024-03-14 14:11:05.865 UTC [39154][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-14 14:11:05.869 UTC [39096][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:05.869 UTC [39096][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:05.869 UTC [39096][postmaster] LOG: background worker "logical replication launcher" (PID 39102) exited with exit code 1 2024-03-14 14:11:05.869 UTC [39097][checkpointer] LOG: shutting down 2024-03-14 14:11:05.870 UTC [39097][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:05.870 UTC [39097][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/41051F8, redo lsn=0/41051F8 2024-03-14 14:11:05.871 UTC [39096][postmaster] LOG: database system is shut down 2024-03-14 14:11:06.007 UTC [39156][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:06.007 UTC [39156][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:06.007 UTC [39156][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:06.011 UTC [39159][startup] LOG: database system was shut down at 2024-03-14 14:11:05 UTC 2024-03-14 14:11:06.013 UTC [39156][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:06.111 UTC [39164][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-14 14:11:06.127 UTC [39164][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-14 14:11:06.127 UTC [39164][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-14 14:11:06.130 UTC [39164][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_vDPL/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-14 14:11:06.131 UTC [39164][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=49361 2024-03-14 14:11:06.142 UTC [39166][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-14 14:11:06.151 UTC [39166][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:11:06.151 UTC [39166][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-14 14:11:06.162 UTC [39168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-14 14:11:06.171 UTC [39168][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:11:06.171 UTC [39168][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-14 14:11:06.175 UTC [39156][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:06.175 UTC [39156][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:06.176 UTC [39156][postmaster] LOG: background worker "logical replication launcher" (PID 39162) exited with exit code 1 2024-03-14 14:11:06.176 UTC [39157][checkpointer] LOG: shutting down 2024-03-14 14:11:06.176 UTC [39157][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:06.177 UTC [39157][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/4105270, redo lsn=0/4105270 2024-03-14 14:11:06.178 UTC [39156][postmaster] LOG: database system is shut down 2024-03-14 14:11:06.295 UTC [39170][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:06.295 UTC [39170][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:06.296 UTC [39170][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:06.298 UTC [39173][startup] LOG: database system was shut down at 2024-03-14 14:11:06 UTC 2024-03-14 14:11:06.300 UTC [39170][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:06.404 UTC [39178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-14 14:11:06.416 UTC [39178][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:11:06.416 UTC [39178][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-14 14:11:06.421 UTC [39170][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:06.421 UTC [39170][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:06.422 UTC [39170][postmaster] LOG: background worker "logical replication launcher" (PID 39176) exited with exit code 1 2024-03-14 14:11:06.422 UTC [39171][checkpointer] LOG: shutting down 2024-03-14 14:11:06.422 UTC [39171][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:06.423 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.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/41052E8, redo lsn=0/41052E8 2024-03-14 14:11:06.424 UTC [39170][postmaster] LOG: database system is shut down 2024-03-14 14:11:06.563 UTC [39180][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:06.564 UTC [39180][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:06.564 UTC [39180][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:06.568 UTC [39183][startup] LOG: database system was shut down at 2024-03-14 14:11:06 UTC 2024-03-14 14:11:06.570 UTC [39180][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:06.670 UTC [39188][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-14 14:11:06.685 UTC [39188][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:11:06.685 UTC [39188][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-14 14:11:06.697 UTC [39190][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-14 14:11:06.708 UTC [39190][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:11:06.708 UTC [39190][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-14 14:11:06.713 UTC [39180][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:06.713 UTC [39180][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:06.713 UTC [39180][postmaster] LOG: background worker "logical replication launcher" (PID 39186) exited with exit code 1 2024-03-14 14:11:06.713 UTC [39181][checkpointer] LOG: shutting down 2024-03-14 14:11:06.713 UTC [39181][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:06.714 UTC [39181][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/4105360, redo lsn=0/4105360 2024-03-14 14:11:06.715 UTC [39180][postmaster] LOG: database system is shut down 2024-03-14 14:11:06.854 UTC [39192][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:06.854 UTC [39192][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:06.854 UTC [39192][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:06.858 UTC [39195][startup] LOG: database system was shut down at 2024-03-14 14:11:06 UTC 2024-03-14 14:11:06.859 UTC [39192][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:06.963 UTC [39200][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-14 14:11:06.974 UTC [39200][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:06.974 UTC [39200][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:06.989 UTC [39202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-14 14:11:06.998 UTC [39202][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-14 14:11:06.998 UTC [39202][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-14 14:11:07.000 UTC [39202][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-14 14:11:07.001 UTC [39202][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-14 14:11:07.005 UTC [39192][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:07.005 UTC [39192][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:07.006 UTC [39192][postmaster] LOG: background worker "logical replication launcher" (PID 39198) exited with exit code 1 2024-03-14 14:11:07.006 UTC [39193][checkpointer] LOG: shutting down 2024-03-14 14:11:07.006 UTC [39193][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:07.007 UTC [39193][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/41053D8, redo lsn=0/41053D8 2024-03-14 14:11:07.007 UTC [39192][postmaster] LOG: database system is shut down 2024-03-14 14:11:07.143 UTC [39204][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:07.143 UTC [39204][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:07.143 UTC [39204][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:07.147 UTC [39207][startup] LOG: database system was shut down at 2024-03-14 14:11:07 UTC 2024-03-14 14:11:07.149 UTC [39204][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:07.261 UTC [39212][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-14 14:11:07.271 UTC [39212][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:07.271 UTC [39212][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:07.286 UTC [39214][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-14 14:11:07.295 UTC [39214][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-14 14:11:07.295 UTC [39214][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-14 14:11:07.298 UTC [39214][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-14 14:11:07.298 UTC [39214][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-14 14:11:07.303 UTC [39204][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:07.303 UTC [39204][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:07.304 UTC [39204][postmaster] LOG: background worker "logical replication launcher" (PID 39210) exited with exit code 1 2024-03-14 14:11:07.304 UTC [39205][checkpointer] LOG: shutting down 2024-03-14 14:11:07.304 UTC [39205][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:07.304 UTC [39205][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/4105450, redo lsn=0/4105450 2024-03-14 14:11:07.306 UTC [39204][postmaster] LOG: database system is shut down 2024-03-14 14:11:07.447 UTC [39216][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:07.448 UTC [39216][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:07.448 UTC [39216][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:07.451 UTC [39219][startup] LOG: database system was shut down at 2024-03-14 14:11:07 UTC 2024-03-14 14:11:07.454 UTC [39216][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:07.547 UTC [39224][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-14 14:11:07.558 UTC [39224][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:07.558 UTC [39224][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:07.572 UTC [39226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-14 14:11:07.581 UTC [39226][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-14 14:11:07.581 UTC [39226][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-14 14:11:07.584 UTC [39226][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-14 14:11:07.584 UTC [39226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-14 14:11:07.588 UTC [39216][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:07.588 UTC [39216][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:07.589 UTC [39216][postmaster] LOG: background worker "logical replication launcher" (PID 39222) exited with exit code 1 2024-03-14 14:11:07.589 UTC [39217][checkpointer] LOG: shutting down 2024-03-14 14:11:07.589 UTC [39217][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:07.590 UTC [39217][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/41054C8, redo lsn=0/41054C8 2024-03-14 14:11:07.591 UTC [39216][postmaster] LOG: database system is shut down 2024-03-14 14:11:07.719 UTC [39228][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:07.720 UTC [39228][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:07.720 UTC [39228][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:07.723 UTC [39231][startup] LOG: database system was shut down at 2024-03-14 14:11:07 UTC 2024-03-14 14:11:07.725 UTC [39228][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:07.825 UTC [39236][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-14 14:11:07.836 UTC [39236][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:07.836 UTC [39236][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:07.850 UTC [39238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-14 14:11:07.860 UTC [39238][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-14 14:11:07.860 UTC [39238][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-14 14:11:07.862 UTC [39238][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-14 14:11:07.863 UTC [39238][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-14 14:11:07.867 UTC [39228][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:07.867 UTC [39228][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:07.868 UTC [39228][postmaster] LOG: background worker "logical replication launcher" (PID 39234) exited with exit code 1 2024-03-14 14:11:07.868 UTC [39229][checkpointer] LOG: shutting down 2024-03-14 14:11:07.868 UTC [39229][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:07.869 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.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/4105540, redo lsn=0/4105540 2024-03-14 14:11:07.870 UTC [39228][postmaster] LOG: database system is shut down 2024-03-14 14:11:08.007 UTC [39240][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:08.008 UTC [39240][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:08.008 UTC [39240][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:08.012 UTC [39243][startup] LOG: database system was shut down at 2024-03-14 14:11:07 UTC 2024-03-14 14:11:08.013 UTC [39240][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:08.124 UTC [39248][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-14 14:11:08.135 UTC [39248][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:08.135 UTC [39248][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:08.150 UTC [39250][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-14 14:11:08.159 UTC [39250][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-14 14:11:08.159 UTC [39250][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-14 14:11:08.162 UTC [39250][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-14 14:11:08.162 UTC [39250][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-14 14:11:08.167 UTC [39240][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:08.168 UTC [39240][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:08.168 UTC [39240][postmaster] LOG: background worker "logical replication launcher" (PID 39246) exited with exit code 1 2024-03-14 14:11:08.168 UTC [39241][checkpointer] LOG: shutting down 2024-03-14 14:11:08.168 UTC [39241][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:08.169 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/41055B8, redo lsn=0/41055B8 2024-03-14 14:11:08.170 UTC [39240][postmaster] LOG: database system is shut down 2024-03-14 14:11:08.315 UTC [39252][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:08.315 UTC [39252][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:08.315 UTC [39252][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:08.319 UTC [39255][startup] LOG: database system was shut down at 2024-03-14 14:11:08 UTC 2024-03-14 14:11:08.321 UTC [39252][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:08.423 UTC [39260][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-14 14:11:08.434 UTC [39260][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:08.434 UTC [39260][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:08.449 UTC [39262][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-14 14:11:08.458 UTC [39262][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-14 14:11:08.458 UTC [39262][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-14 14:11:08.460 UTC [39262][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-14 14:11:08.460 UTC [39262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-14 14:11:08.465 UTC [39252][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:08.465 UTC [39252][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:08.466 UTC [39252][postmaster] LOG: background worker "logical replication launcher" (PID 39258) exited with exit code 1 2024-03-14 14:11:08.466 UTC [39253][checkpointer] LOG: shutting down 2024-03-14 14:11:08.466 UTC [39253][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:08.466 UTC [39253][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/4105630, redo lsn=0/4105630 2024-03-14 14:11:08.468 UTC [39252][postmaster] LOG: database system is shut down 2024-03-14 14:11:08.596 UTC [39264][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:08.596 UTC [39264][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:08.597 UTC [39264][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:08.599 UTC [39267][startup] LOG: database system was shut down at 2024-03-14 14:11:08 UTC 2024-03-14 14:11:08.601 UTC [39264][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:08.701 UTC [39272][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-14 14:11:08.710 UTC [39272][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:08.710 UTC [39272][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:08.723 UTC [39274][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-14 14:11:08.732 UTC [39274][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-14 14:11:08.732 UTC [39274][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-14 14:11:08.734 UTC [39274][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-14 14:11:08.734 UTC [39274][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-14 14:11:08.739 UTC [39264][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:08.739 UTC [39264][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:08.739 UTC [39264][postmaster] LOG: background worker "logical replication launcher" (PID 39270) exited with exit code 1 2024-03-14 14:11:08.739 UTC [39265][checkpointer] LOG: shutting down 2024-03-14 14:11:08.739 UTC [39265][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:08.740 UTC [39265][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/41056A8, redo lsn=0/41056A8 2024-03-14 14:11:08.741 UTC [39264][postmaster] LOG: database system is shut down 2024-03-14 14:11:08.874 UTC [39276][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:08.874 UTC [39276][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:08.875 UTC [39276][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:08.896 UTC [39279][startup] LOG: database system was shut down at 2024-03-14 14:11:08 UTC 2024-03-14 14:11:08.898 UTC [39276][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:08.978 UTC [39284][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-14 14:11:08.984 UTC [39284][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:08.984 UTC [39284][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:08.994 UTC [39286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-14 14:11:09.002 UTC [39286][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-14 14:11:09.002 UTC [39286][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-14 14:11:09.004 UTC [39286][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-14 14:11:09.004 UTC [39286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-14 14:11:09.009 UTC [39276][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:09.009 UTC [39276][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:09.009 UTC [39276][postmaster] LOG: background worker "logical replication launcher" (PID 39282) exited with exit code 1 2024-03-14 14:11:09.009 UTC [39277][checkpointer] LOG: shutting down 2024-03-14 14:11:09.009 UTC [39277][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:09.010 UTC [39277][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/4105720, redo lsn=0/4105720 2024-03-14 14:11:09.011 UTC [39276][postmaster] LOG: database system is shut down 2024-03-14 14:11:09.148 UTC [39288][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-14 14:11:09.148 UTC [39288][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 55530 2024-03-14 14:11:09.148 UTC [39288][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/jPlJzDdPm9/.s.PGSQL.55530" 2024-03-14 14:11:09.151 UTC [39291][startup] LOG: database system was shut down at 2024-03-14 14:11:09 UTC 2024-03-14 14:11:09.153 UTC [39288][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:11:09.255 UTC [39296][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-14 14:11:09.265 UTC [39296][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:11:09.265 UTC [39296][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:11:09.279 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-14 14:11:09.288 UTC [39298][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-14 14:11:09.288 UTC [39298][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-14 14:11:09.291 UTC [39298][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-14 14:11:09.291 UTC [39298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-14 14:11:09.296 UTC [39288][postmaster] LOG: received fast shutdown request 2024-03-14 14:11:09.296 UTC [39288][postmaster] LOG: aborting any active transactions 2024-03-14 14:11:09.297 UTC [39288][postmaster] LOG: background worker "logical replication launcher" (PID 39294) exited with exit code 1 2024-03-14 14:11:09.297 UTC [39289][checkpointer] LOG: shutting down 2024-03-14 14:11:09.297 UTC [39289][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:11:09.297 UTC [39289][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/4105798, redo lsn=0/4105798 2024-03-14 14:11:09.298 UTC [39288][postmaster] LOG: database system is shut down 2024-03-14 14:11:09.432 UTC [39300][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-14 14:11:09.432 UTC [39300][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:11:09.519](0.227s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"