# Checking port 53835
# Found port 53835
Name: london
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/archives
Connection string: port=53835 host=/tmp/DoUrahh4NY
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log
[19:06:02.771](0.026s) # 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/recovery/009_twophase/data/t_009_twophase_london_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london 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 "london" is 23406
# Taking pg_basebackup london_backup from node "london"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/backup/london_backup -h /tmp/DoUrahh4NY -p 53835 --checkpoint fast --no-sync
# Backup finished
# Checking port 53836
# Found port 53836
Name: paris
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/archives
Connection string: port=53836 host=/tmp/DoUrahh4NY
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log
# Initializing node "paris" from backup "london_backup" of node "london"
### Enabling streaming replication for node "paris"
### Starting node "paris"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log -o --cluster-name=paris start
waiting for server to start.... done
server started
# Postmaster PID for node "paris" is 23492
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:03.216](0.445s) not ok 1 - reload node london with synchronous_standby_names = 'paris'
[19:06:03.216](0.000s) #   Failed test 'reload node london with synchronous_standby_names = 'paris''
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 51.
[19:06:03.217](0.000s) #          got: ''
#     expected: 't'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
[19:06:03.228](0.011s) not ok 2 - reload node paris with synchronous_standby_names = 'london'
[19:06:03.228](0.000s) #   Failed test 'reload node paris with synchronous_standby_names = 'london''
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 52.
[19:06:03.228](0.000s) #          got: ''
#     expected: 't'
[19:06:03.228](0.000s) # Initially, london is primary and paris is standby
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 23601
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:03.573](0.344s) not ok 3 - Commit prepared transaction after restart
[19:06:03.573](0.000s) #   Failed test 'Commit prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85.
[19:06:03.573](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:03.616](0.043s) not ok 4 - Rollback prepared transaction after restart
[19:06:03.616](0.000s) #   Failed test 'Rollback prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88.
[19:06:03.617](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 23728
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:03.917](0.301s) not ok 5 - Commit prepared transaction after teardown
[19:06:03.918](0.000s) #   Failed test 'Commit prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113.
[19:06:03.918](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:03.961](0.043s) not ok 6 - Rollback prepared transaction after teardown
[19:06:03.961](0.000s) #   Failed test 'Rollback prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116.
[19:06:03.961](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 23840
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:04.384](0.423s) not ok 7 - Replay several transactions with same GID
[19:06:04.384](0.000s) #   Failed test 'Replay several transactions with same GID'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 140.
[19:06:04.384](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 23979
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:04.905](0.520s) not ok 8 - Cleanup of shared memory state for 2PC commit
[19:06:04.905](0.000s) #   Failed test 'Cleanup of shared memory state for 2PC commit'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 166.
[19:06:04.905](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
[19:06:04.999](0.094s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint
[19:06:04.999](0.000s) #   Failed test 'Cleanup of shared memory state on running standby without checkpoint'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 186.
[19:06:05.000](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
[19:06:05.136](0.136s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint
[19:06:05.136](0.000s) #   Failed test 'Cleanup of shared memory state on running standby after checkpoint'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 207.
[19:06:05.136](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Promoting node "paris"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log promote
waiting for server to promote.... done
server promoted
[19:06:05.385](0.249s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
[19:06:05.428](0.043s) not ok 11 - Restore of prepared transaction on promoted standby
[19:06:05.428](0.000s) #   Failed test 'Restore of prepared transaction on promoted standby'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 233.
[19:06:05.428](0.000s) #          got: '2'
#     expected: '0'
### Enabling streaming replication for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 24262
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
### Stopping node "paris" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "paris"
### Restarting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 24380
### Promoting node "london"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log promote
waiting for server to promote.... done
server promoted
[19:06:06.001](0.573s) # Now london is primary and paris is standby
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
[19:06:06.010](0.009s) not ok 12 - Restore prepared transactions from files with primary down
[19:06:06.010](0.000s) #   Failed test 'Restore prepared transactions from files with primary down'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 266.
[19:06:06.010](0.000s) #          got: ''
#     expected: '1'
### Enabling streaming replication for node "paris"
### Starting node "paris"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log -o --cluster-name=paris start
waiting for server to start.... done
server started
# Postmaster PID for node "paris" is 24476
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53835" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Stopping node "paris" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "paris"
### Starting node "paris"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log -o --cluster-name=paris start
waiting for server to start.... done
server started
# Postmaster PID for node "paris" is 24567
### Promoting node "paris"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log promote
waiting for server to promote.... done
server promoted
[19:06:06.568](0.558s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
[19:06:06.597](0.029s) not ok 13 - Restore prepared transactions from records with primary down
[19:06:06.598](0.000s) #   Failed test 'Restore prepared transactions from records with primary down'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 302.
[19:06:06.598](0.000s) #          got: ''
#     expected: '1'
### Enabling streaming replication for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 24665
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist
connection error: 'psql: error: connection to server on socket "/tmp/DoUrahh4NY/.s.PGSQL.53836" failed: FATAL:  database "postgres" does not exist'
while running 'psql -XAtq -d port=53836 host=/tmp/DoUrahh4NY dbname='postgres' -f - -v ON_ERROR_STOP=1' at /tmp/cirrus-ci-build/src/test/perl/PostgreSQL/Test/Cluster.pm line 2043.
# Postmaster PID for node "london" is 24665
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
# Postmaster PID for node "paris" is 24567
### Stopping node "paris" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "paris"
[19:06:07.031](0.433s) # Tests were run but no plan was declared and done_testing() was not seen.
[19:06:07.031](0.000s) # Looks like your test exited with 29 just after 13.