[06:08:55.968](0.015s) # setting up data directory
# Checking port 59812
# Found port 59812
Name: primary
Data directory: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/archives
Connection string: port=59812 host=/tmp/V_vSXHXOvL
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_primary.log
[06:08:55.971](0.004s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_primary.log -o --cluster-name=primary start
pg_ctl: another server might be running; trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32932
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/V_vSXHXOvL/.s.PGSQL.59812" failed: FATAL:  database "postgres" does not exist
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_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 33143
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_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 33171
[06:08:57.225](1.253s) not ok 1 - Basic SCRAM authentication with SSL
[06:08:57.225](0.000s) #   Failed test 'Basic SCRAM authentication with SSL'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 82.
[06:08:57.225](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.225](0.000s) not ok 2 - Basic SCRAM authentication with SSL: no stderr
[06:08:57.225](0.000s) #   Failed test 'Basic SCRAM authentication with SSL: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 82.
[06:08:57.226](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"'
#     expected: ''
[06:08:57.232](0.006s) ok 3 - SCRAM with SSL and channel_binding=invalid_value
[06:08:57.232](0.000s) ok 4 - SCRAM with SSL and channel_binding=invalid_value: matches
[06:08:57.245](0.013s) not ok 5 - SCRAM with SSL and channel_binding=disable
[06:08:57.245](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=disable'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 91.
[06:08:57.245](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.245](0.000s) not ok 6 - SCRAM with SSL and channel_binding=disable: no stderr
[06:08:57.245](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=disable: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 91.
[06:08:57.245](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"'
#     expected: ''
[06:08:57.260](0.014s) not ok 7 - SCRAM with SSL and channel_binding=require
[06:08:57.260](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=require'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 93.
[06:08:57.260](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.260](0.000s) not ok 8 - SCRAM with SSL and channel_binding=require: no stderr
[06:08:57.260](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=require: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 93.
[06:08:57.261](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"'
#     expected: ''
[06:08:57.261](0.000s) ok 9 # skip MD5 not supported
[06:08:57.274](0.013s) ok 10 - Cert authentication and channel_binding=require
[06:08:57.274](0.000s) ok 11 - Cert authentication and channel_binding=require: matches
[06:08:57.290](0.017s) not ok 12 - SCRAM with clientcert=verify-full
[06:08:57.291](0.000s) #   Failed test 'SCRAM with clientcert=verify-full'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 128.
[06:08:57.291](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.291](0.000s) not ok 13 - SCRAM with clientcert=verify-full: no stderr
[06:08:57.291](0.000s) #   Failed test 'SCRAM with clientcert=verify-full: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 128.
[06:08:57.291](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"
# connection to server at "127.0.0.1", port 59812 failed: FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "verifydb", no encryption'
#     expected: ''
[06:08:57.291](0.000s) not ok 14 - SCRAM with clientcert=verify-full: log matches
[06:08:57.292](0.000s) #   Failed test 'SCRAM with clientcert=verify-full: log matches'
#   at /tmp/cirrus-ci-build/src/test/perl/PostgreSQL/Test/Cluster.pm line 2350.
[06:08:57.292](0.000s) #                   '2024-03-11 06:08:57.280 UTC [33206][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46012
# 2024-03-11 06:08:57.287 UTC [33206][client backend] [[unknown]][4/1:0] FATAL:  password authentication failed for user "ssltestuser"
# 2024-03-11 06:08:57.287 UTC [33206][client backend] [[unknown]][4/1:0] DETAIL:  Role "ssltestuser" does not exist.
# 	Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf" line 4: "hostssl verifydb        ssltestuser     127.0.0.1/32            scram-sha-256        clientcert=verify-full"
# 2024-03-11 06:08:57.288 UTC [33207][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46014
# 2024-03-11 06:08:57.289 UTC [33207][client backend] [[unknown]][5/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "verifydb", no encryption
# 2024-03-11 06:08:57.289 UTC [33207][client backend] [[unknown]][5/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
# '
#     doesn't match '(?^:connection authenticated: identity="ssltestuser" method=scram-sha-256)'
[06:08:57.306](0.014s) not ok 15 - SCRAM with SSL, channel_binding=disable, and require_auth=scram-sha-256
[06:08:57.306](0.000s) #   Failed test 'SCRAM with SSL, channel_binding=disable, and require_auth=scram-sha-256'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 136.
[06:08:57.306](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.306](0.000s) not ok 16 - SCRAM with SSL, channel_binding=disable, and require_auth=scram-sha-256: no stderr
[06:08:57.306](0.000s) #   Failed test 'SCRAM with SSL, channel_binding=disable, and require_auth=scram-sha-256: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 136.
[06:08:57.306](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"'
#     expected: ''
[06:08:57.307](0.000s) ok 17 # skip MD5 not supported
[06:08:57.320](0.014s) not ok 18 - SCRAM with SSL, channel_binding=require, and require_auth=scram-sha-256
[06:08:57.321](0.000s) #   Failed test 'SCRAM with SSL, channel_binding=require, and require_auth=scram-sha-256'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 151.
[06:08:57.321](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.321](0.000s) not ok 19 - SCRAM with SSL, channel_binding=require, and require_auth=scram-sha-256: no stderr
[06:08:57.321](0.000s) #   Failed test 'SCRAM with SSL, channel_binding=require, and require_auth=scram-sha-256: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 151.
[06:08:57.321](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"'
#     expected: ''
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_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 33225
[06:08:57.540](0.219s) not ok 20 - SCRAM with SSL and channel_binding=require, server certificate uses 'rsassaPss'
[06:08:57.540](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=require, server certificate uses 'rsassaPss''
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 161.
[06:08:57.540](0.000s) #          got: '2'
#     expected: '0'
[06:08:57.540](0.000s) not ok 21 - SCRAM with SSL and channel_binding=require, server certificate uses 'rsassaPss': no stderr
[06:08:57.540](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=require, server certificate uses 'rsassaPss': no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/002_scram.pl line 161.
[06:08:57.540](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 59812 failed: FATAL:  password authentication failed for user "ssltestuser"'
#     expected: ''
[06:08:57.541](0.000s) not ok 22 - SCRAM with SSL and channel_binding=require, server certificate uses 'rsassaPss': log matches
[06:08:57.541](0.000s) #   Failed test 'SCRAM with SSL and channel_binding=require, server certificate uses 'rsassaPss': log matches'
#   at /tmp/cirrus-ci-build/src/test/perl/PostgreSQL/Test/Cluster.pm line 2350.
[06:08:57.541](0.000s) #                   '2024-03-11 06:08:57.532 UTC [33239][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46056
# 2024-03-11 06:08:57.538 UTC [33239][client backend] [[unknown]][0/1:0] FATAL:  password authentication failed for user "ssltestuser"
# 2024-03-11 06:08:57.538 UTC [33239][client backend] [[unknown]][0/1:0] DETAIL:  Role "ssltestuser" does not exist.
# 	Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf" line 3: "hostssl trustdb         all             127.0.0.1/32            scram-sha-256"
# '
#     doesn't match '(?^:connection authenticated: identity="ssltestuser" method=scram-sha-256)'
[06:08:57.541](0.000s) 1..22
### Stopping node "primary" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
[06:08:57.645](0.104s) # Looks like you failed 16 tests of 22.