[18:23:27.752](0.043s) # setting up data directory
# Checking port 57308
# Found port 57308
Name: primary
Data directory: /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives
Connection string: port=57308 host=/tmp/W67fiBtXMJ
Log file: /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[18:23:27.768](0.016s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/build-32/tmp_install/initdb-template /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build-32/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/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 95300
[18:23:28.018](0.250s) ok 1 - ssl_library parameter
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/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 95619
[18:23:29.087](1.069s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/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-30 18:23:27.921 UTC [95300][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:27.923 UTC [95307][startup] LOG:  database system was shut down at 2024-03-30 18:20:58 UTC
2024-03-30 18:23:27.926 UTC [95300][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:28.010 UTC [95340][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.017 UTC [95340][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 18:23:28.020 UTC [95340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.032 UTC [95358][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.034 UTC [95358][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 18:23:28.035 UTC [95358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.083 UTC [95381][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.084 UTC [95381][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 18:23:28.085 UTC [95381][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.113 UTC [95395][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.116 UTC [95395][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 18:23:28.117 UTC [95395][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.161 UTC [95421][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.167 UTC [95421][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 18:23:28.168 UTC [95421][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.205 UTC [95438][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.206 UTC [95438][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 18:23:28.245 UTC [95438][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.330 UTC [95468][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.332 UTC [95468][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 18:23:28.400 UTC [95468][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.450 UTC [95487][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.452 UTC [95487][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 18:23:28.500 UTC [95487][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.587 UTC [95521][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.589 UTC [95521][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 18:23:28.653 UTC [95521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.065 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.714 UTC [95543][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.716 UTC [95543][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 18:23:28.755 UTC [95543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.835 UTC [95577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.836 UTC [95577][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 18:23:28.867 UTC [95577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:28.885 UTC [95300][postmaster] LOG:  background worker "logical replication launcher" (PID 95311) exited with exit code 1
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  shutting down
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:28.945 UTC [95305][checkpointer] LOG:  checkpoint complete: wrote 5476 buffers (33.4%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.060 s, sync=0.001 s, total=0.061 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44047 kB, estimate=44047 kB; lsn=0/3FFC18C, redo lsn=0/3FFC18C
2024-03-30 18:23:28.955 UTC [95300][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57308
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:29.004 UTC [95627][startup] LOG:  database system was shut down at 2024-03-30 18:23:28 UTC
2024-03-30 18:23:29.007 UTC [95619][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:29.093 UTC [95619][postmaster] LOG:  background worker "logical replication launcher" (PID 95630) exited with exit code 1
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  shutting down
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:29.094 UTC [95625][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/3FFC1F4, redo lsn=0/3FFC1F4
2024-03-30 18:23:29.096 UTC [95619][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.209 UTC [95678][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 18:23:29.209 UTC [95678][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[18:23:29.293](0.205s) ok 2 - restart fails with password-protected key file with wrong password
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build-32/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.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:27.923 UTC [95307][startup] LOG:  database system was shut down at 2024-03-30 18:20:58 UTC
2024-03-30 18:23:27.926 UTC [95300][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:28.010 UTC [95340][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.017 UTC [95340][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 18:23:28.020 UTC [95340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.032 UTC [95358][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.034 UTC [95358][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 18:23:28.035 UTC [95358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.083 UTC [95381][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.084 UTC [95381][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 18:23:28.085 UTC [95381][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.113 UTC [95395][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.116 UTC [95395][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 18:23:28.117 UTC [95395][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.161 UTC [95421][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.167 UTC [95421][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 18:23:28.168 UTC [95421][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.205 UTC [95438][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.206 UTC [95438][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 18:23:28.245 UTC [95438][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.330 UTC [95468][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.332 UTC [95468][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 18:23:28.400 UTC [95468][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.450 UTC [95487][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.452 UTC [95487][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 18:23:28.500 UTC [95487][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.587 UTC [95521][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.589 UTC [95521][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 18:23:28.653 UTC [95521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.065 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.714 UTC [95543][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.716 UTC [95543][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 18:23:28.755 UTC [95543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.835 UTC [95577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.836 UTC [95577][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 18:23:28.867 UTC [95577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:28.885 UTC [95300][postmaster] LOG:  background worker "logical replication launcher" (PID 95311) exited with exit code 1
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  shutting down
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:28.945 UTC [95305][checkpointer] LOG:  checkpoint complete: wrote 5476 buffers (33.4%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.060 s, sync=0.001 s, total=0.061 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44047 kB, estimate=44047 kB; lsn=0/3FFC18C, redo lsn=0/3FFC18C
2024-03-30 18:23:28.955 UTC [95300][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57308
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:29.004 UTC [95627][startup] LOG:  database system was shut down at 2024-03-30 18:23:28 UTC
2024-03-30 18:23:29.007 UTC [95619][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:29.093 UTC [95619][postmaster] LOG:  background worker "logical replication launcher" (PID 95630) exited with exit code 1
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  shutting down
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:29.094 UTC [95625][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/3FFC1F4, redo lsn=0/3FFC1F4
2024-03-30 18:23:29.096 UTC [95619][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.209 UTC [95678][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 18:23:29.209 UTC [95678][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.317 UTC [95710][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.317 UTC [95710][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.317 UTC [95710][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.318 UTC [95710][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[18:23:29.400](0.107s) not ok 3 - restart succeeds with password-protected key file
[18:23:29.400](0.000s) #   Failed test 'restart succeeds with password-protected key file'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 100.
[18:23:29.400](0.000s) #          got: '0'
#     expected: '1'
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build-32/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.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:27.923 UTC [95307][startup] LOG:  database system was shut down at 2024-03-30 18:20:58 UTC
2024-03-30 18:23:27.926 UTC [95300][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:28.010 UTC [95340][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.017 UTC [95340][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 18:23:28.020 UTC [95340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.032 UTC [95358][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.034 UTC [95358][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 18:23:28.035 UTC [95358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.083 UTC [95381][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.084 UTC [95381][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 18:23:28.085 UTC [95381][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.113 UTC [95395][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.116 UTC [95395][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 18:23:28.117 UTC [95395][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.161 UTC [95421][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.167 UTC [95421][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 18:23:28.168 UTC [95421][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.205 UTC [95438][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.206 UTC [95438][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 18:23:28.245 UTC [95438][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.330 UTC [95468][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.332 UTC [95468][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 18:23:28.400 UTC [95468][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.450 UTC [95487][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.452 UTC [95487][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 18:23:28.500 UTC [95487][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.587 UTC [95521][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.589 UTC [95521][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 18:23:28.653 UTC [95521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.065 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.714 UTC [95543][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.716 UTC [95543][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 18:23:28.755 UTC [95543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.835 UTC [95577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.836 UTC [95577][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 18:23:28.867 UTC [95577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:28.885 UTC [95300][postmaster] LOG:  background worker "logical replication launcher" (PID 95311) exited with exit code 1
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  shutting down
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:28.945 UTC [95305][checkpointer] LOG:  checkpoint complete: wrote 5476 buffers (33.4%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.060 s, sync=0.001 s, total=0.061 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44047 kB, estimate=44047 kB; lsn=0/3FFC18C, redo lsn=0/3FFC18C
2024-03-30 18:23:28.955 UTC [95300][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57308
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:29.004 UTC [95627][startup] LOG:  database system was shut down at 2024-03-30 18:23:28 UTC
2024-03-30 18:23:29.007 UTC [95619][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:29.093 UTC [95619][postmaster] LOG:  background worker "logical replication launcher" (PID 95630) exited with exit code 1
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  shutting down
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:29.094 UTC [95625][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/3FFC1F4, redo lsn=0/3FFC1F4
2024-03-30 18:23:29.096 UTC [95619][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.209 UTC [95678][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 18:23:29.209 UTC [95678][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.317 UTC [95710][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.317 UTC [95710][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.317 UTC [95710][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.318 UTC [95710][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.415 UTC [95731][postmaster] FATAL:  could not set SSL protocol version range
2024-03-30 18:23:29.415 UTC [95731][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-30 18:23:29.415 UTC [95731][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[18:23:29.503](0.103s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build-32/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.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:27.923 UTC [95307][startup] LOG:  database system was shut down at 2024-03-30 18:20:58 UTC
2024-03-30 18:23:27.926 UTC [95300][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:28.010 UTC [95340][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.017 UTC [95340][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 18:23:28.020 UTC [95340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.032 UTC [95358][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.034 UTC [95358][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 18:23:28.035 UTC [95358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.083 UTC [95381][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.084 UTC [95381][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 18:23:28.085 UTC [95381][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.113 UTC [95395][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.116 UTC [95395][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 18:23:28.117 UTC [95395][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.161 UTC [95421][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.167 UTC [95421][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 18:23:28.168 UTC [95421][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.205 UTC [95438][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.206 UTC [95438][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 18:23:28.245 UTC [95438][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.330 UTC [95468][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.332 UTC [95468][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 18:23:28.400 UTC [95468][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.450 UTC [95487][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.452 UTC [95487][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 18:23:28.500 UTC [95487][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.587 UTC [95521][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.589 UTC [95521][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 18:23:28.653 UTC [95521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.065 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.714 UTC [95543][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.716 UTC [95543][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 18:23:28.755 UTC [95543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.835 UTC [95577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.836 UTC [95577][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 18:23:28.867 UTC [95577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:28.885 UTC [95300][postmaster] LOG:  background worker "logical replication launcher" (PID 95311) exited with exit code 1
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  shutting down
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:28.945 UTC [95305][checkpointer] LOG:  checkpoint complete: wrote 5476 buffers (33.4%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.060 s, sync=0.001 s, total=0.061 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44047 kB, estimate=44047 kB; lsn=0/3FFC18C, redo lsn=0/3FFC18C
2024-03-30 18:23:28.955 UTC [95300][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57308
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:29.004 UTC [95627][startup] LOG:  database system was shut down at 2024-03-30 18:23:28 UTC
2024-03-30 18:23:29.007 UTC [95619][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:29.093 UTC [95619][postmaster] LOG:  background worker "logical replication launcher" (PID 95630) exited with exit code 1
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  shutting down
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:29.094 UTC [95625][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/3FFC1F4, redo lsn=0/3FFC1F4
2024-03-30 18:23:29.096 UTC [95619][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.209 UTC [95678][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 18:23:29.209 UTC [95678][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.317 UTC [95710][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.317 UTC [95710][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.317 UTC [95710][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.318 UTC [95710][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.415 UTC [95731][postmaster] FATAL:  could not set SSL protocol version range
2024-03-30 18:23:29.415 UTC [95731][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-30 18:23:29.415 UTC [95731][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.524 UTC [95760][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.524 UTC [95760][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.524 UTC [95760][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.524 UTC [95760][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.524 UTC [95760][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.525 UTC [95760][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[18:23:29.607](0.104s) not ok 5 - restart succeeds with correct SSL protocol bounds
[18:23:29.607](0.000s) #   Failed test 'restart succeeds with correct SSL protocol bounds'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 117.
[18:23:29.607](0.000s) #          got: '0'
#     expected: '1'
[18:23:29.608](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build-32/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.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:27.921 UTC [95300][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:27.923 UTC [95307][startup] LOG:  database system was shut down at 2024-03-30 18:20:58 UTC
2024-03-30 18:23:27.926 UTC [95300][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:28.010 UTC [95340][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.011 UTC [95340][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.017 UTC [95340][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 18:23:28.020 UTC [95340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.032 UTC [95358][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.033 UTC [95358][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.034 UTC [95358][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 18:23:28.035 UTC [95358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.083 UTC [95381][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.083 UTC [95381][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.084 UTC [95381][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 18:23:28.085 UTC [95381][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.113 UTC [95395][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.114 UTC [95395][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.116 UTC [95395][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 18:23:28.117 UTC [95395][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.161 UTC [95421][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.162 UTC [95421][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.167 UTC [95421][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 18:23:28.168 UTC [95421][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.205 UTC [95438][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.205 UTC [95438][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.206 UTC [95438][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 18:23:28.245 UTC [95438][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.330 UTC [95468][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.331 UTC [95468][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.332 UTC [95468][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 18:23:28.400 UTC [95468][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.069 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.450 UTC [95487][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.451 UTC [95487][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.452 UTC [95487][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 18:23:28.500 UTC [95487][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.587 UTC [95521][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.588 UTC [95521][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.589 UTC [95521][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 18:23:28.653 UTC [95521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.065 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.714 UTC [95543][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.715 UTC [95543][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.716 UTC [95543][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 18:23:28.755 UTC [95543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.835 UTC [95577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 18:23:28.835 UTC [95577][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 18:23:28.836 UTC [95577][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 18:23:28.867 UTC [95577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:28.881 UTC [95300][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:28.885 UTC [95300][postmaster] LOG:  background worker "logical replication launcher" (PID 95311) exited with exit code 1
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  shutting down
2024-03-30 18:23:28.885 UTC [95305][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:28.945 UTC [95305][checkpointer] LOG:  checkpoint complete: wrote 5476 buffers (33.4%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.060 s, sync=0.001 s, total=0.061 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44047 kB, estimate=44047 kB; lsn=0/3FFC18C, redo lsn=0/3FFC18C
2024-03-30 18:23:28.955 UTC [95300][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57308
2024-03-30 18:23:29.002 UTC [95619][postmaster] LOG:  listening on Unix socket "/tmp/W67fiBtXMJ/.s.PGSQL.57308"
2024-03-30 18:23:29.004 UTC [95627][startup] LOG:  database system was shut down at 2024-03-30 18:23:28 UTC
2024-03-30 18:23:29.007 UTC [95619][postmaster] LOG:  database system is ready to accept connections
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  received fast shutdown request
2024-03-30 18:23:29.090 UTC [95619][postmaster] LOG:  aborting any active transactions
2024-03-30 18:23:29.093 UTC [95619][postmaster] LOG:  background worker "logical replication launcher" (PID 95630) exited with exit code 1
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  shutting down
2024-03-30 18:23:29.093 UTC [95625][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 18:23:29.094 UTC [95625][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/3FFC1F4, redo lsn=0/3FFC1F4
2024-03-30 18:23:29.096 UTC [95619][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.209 UTC [95678][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 18:23:29.209 UTC [95678][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.317 UTC [95710][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.317 UTC [95710][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.317 UTC [95710][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.317 UTC [95710][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.318 UTC [95710][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.415 UTC [95731][postmaster] FATAL:  could not set SSL protocol version range
2024-03-30 18:23:29.415 UTC [95731][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-30 18:23:29.415 UTC [95731][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.524 UTC [95760][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.524 UTC [95760][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.524 UTC [95760][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.524 UTC [95760][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.524 UTC [95760][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.525 UTC [95760][postmaster] LOG:  database system is shut down
2024-03-30 18:23:29.625 UTC [95786][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-30 18:23:29.625 UTC [95786][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-30 18:23:29.625 UTC [95786][postmaster] HINT:  Is another postmaster already running on port 57308? If not, wait a few seconds and retry.
2024-03-30 18:23:29.625 UTC [95786][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-30 18:23:29.625 UTC [95786][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-30 18:23:29.626 UTC [95786][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[18:23:29.711](0.103s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"