# Checking port 59095
# Found port 59095
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=59095 host=/tmp/VM2q4pe22U
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log
[12:37:47.540](0.022s) # 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 22748
# 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/VM2q4pe22U -p 59095 --checkpoint fast --no-sync
# Backup finished
# Checking port 59096
# Found port 59096
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=59096 host=/tmp/VM2q4pe22U
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 22855
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:47.966](0.425s) not ok 1 - reload node london with synchronous_standby_names = 'paris'
[12:37:47.966](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 55.
[12:37:47.966](0.000s) #          got: ''
#     expected: 't'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
[12:37:47.977](0.011s) not ok 2 - reload node paris with synchronous_standby_names = 'london'
[12:37:47.977](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 56.
[12:37:47.977](0.000s) #          got: ''
#     expected: 't'
[12:37:47.977](0.000s) # Initially, london is primary and paris is standby
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" 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 22960
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:48.327](0.350s) not ok 3 - Commit prepared transaction after restart
[12:37:48.327](0.000s) #   Failed test 'Commit prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 89.
[12:37:48.328](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:48.371](0.044s) not ok 4 - Rollback prepared transaction after restart
[12:37:48.372](0.000s) #   Failed test 'Rollback prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 92.
[12:37:48.372](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" 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 23088
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:48.789](0.417s) not ok 5 - Commit prepared transaction after teardown
[12:37:48.790](0.000s) #   Failed test 'Commit prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 117.
[12:37:48.790](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:48.833](0.043s) not ok 6 - Rollback prepared transaction after teardown
[12:37:48.833](0.000s) #   Failed test 'Rollback prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 120.
[12:37:48.833](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" 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 23205
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:49.148](0.315s) not ok 7 - Replay several transactions with same GID
[12:37:49.148](0.000s) #   Failed test 'Replay several transactions with same GID'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 144.
[12:37:49.149](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" 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 23311
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:49.358](0.209s) not ok 8 - Cleanup of shared memory state for 2PC commit
[12:37:49.358](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 170.
[12:37:49.359](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
[12:37:49.453](0.095s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint
[12:37:49.453](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 190.
[12:37:49.454](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
[12:37:49.603](0.150s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint
[12:37:49.604](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 211.
[12:37:49.604](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" 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
[12:37:49.852](0.248s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
[12:37:49.895](0.043s) not ok 11 - Restore of prepared transaction on promoted standby
[12:37:49.895](0.000s) #   Failed test 'Restore of prepared transaction on promoted standby'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 237.
[12:37:49.896](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 23553
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" 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 23659
### 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
[12:37:50.464](0.569s) # Now london is primary and paris is standby
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
[12:37:50.477](0.013s) not ok 12 - Restore prepared transactions from files with primary down
[12:37:50.477](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 270.
[12:37:50.478](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 23746
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59095" 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 23887
### 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
[12:37:51.137](0.660s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
[12:37:51.146](0.009s) not ok 13 - Restore prepared transactions from records with primary down
[12:37:51.146](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 306.
[12:37:51.146](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 23954
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist
connection error: 'psql: error: connection to server on socket "/tmp/VM2q4pe22U/.s.PGSQL.59096" failed: FATAL:  database "postgres" does not exist'
while running 'psql -XAtq -d port=59096 host=/tmp/VM2q4pe22U dbname='postgres' -f - -v ON_ERROR_STOP=1' at /tmp/cirrus-ci-build/src/test/perl/PostgreSQL/Test/Cluster.pm line 2040.
# Postmaster PID for node "london" is 23954
### 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 23887
### 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"
[12:37:51.566](0.419s) # Tests were run but no plan was declared and done_testing() was not seen.
[12:37:51.566](0.000s) # Looks like your test exited with 29 just after 13.