# Checking port 60356 # Found port 60356 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=60356 host=/tmp/4CzvBA6cOS Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log [01:00:05.509](0.014s) # 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 22659 # 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/4CzvBA6cOS -p 60356 --checkpoint fast --no-sync # Backup finished # Checking port 60357 # Found port 60357 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=60357 host=/tmp/4CzvBA6cOS 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 22739 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:05.939](0.430s) not ok 1 - reload node london with synchronous_standby_names = 'paris' [01:00:05.939](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. [01:00:05.940](0.000s) # got: '' # expected: 't' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist [01:00:05.953](0.014s) not ok 2 - reload node paris with synchronous_standby_names = 'london' [01:00:05.953](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. [01:00:05.954](0.000s) # got: '' # expected: 't' [01:00:05.954](0.000s) # Initially, london is primary and paris is standby psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" 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 22865 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:06.450](0.497s) not ok 3 - Commit prepared transaction after restart [01:00:06.450](0.000s) # Failed test 'Commit prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 89. [01:00:06.451](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:06.501](0.050s) not ok 4 - Rollback prepared transaction after restart [01:00:06.501](0.000s) # Failed test 'Rollback prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 92. [01:00:06.501](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" 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 23024 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:06.816](0.314s) not ok 5 - Commit prepared transaction after teardown [01:00:06.816](0.000s) # Failed test 'Commit prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 117. [01:00:06.816](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:06.872](0.056s) not ok 6 - Rollback prepared transaction after teardown [01:00:06.872](0.000s) # Failed test 'Rollback prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 120. [01:00:06.872](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" 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 23150 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:07.295](0.422s) not ok 7 - Replay several transactions with same GID [01:00:07.295](0.000s) # Failed test 'Replay several transactions with same GID' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 144. [01:00:07.295](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" 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 23284 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:07.613](0.318s) not ok 8 - Cleanup of shared memory state for 2PC commit [01:00:07.613](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. [01:00:07.613](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist [01:00:07.711](0.098s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint [01:00:07.712](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. [01:00:07.712](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist [01:00:07.860](0.148s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint [01:00:07.860](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. [01:00:07.860](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" 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 [01:00:08.110](0.250s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist [01:00:08.154](0.044s) not ok 11 - Restore of prepared transaction on promoted standby [01:00:08.155](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. [01:00:08.155](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 23498 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" 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 23624 ### 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 [01:00:08.870](0.716s) # Now london is primary and paris is standby psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist [01:00:08.883](0.013s) not ok 12 - Restore prepared transactions from files with primary down [01:00:08.883](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. [01:00:08.883](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 23731 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60356" 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 23856 ### 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 [01:00:09.528](0.645s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist [01:00:09.542](0.014s) not ok 13 - Restore prepared transactions from records with primary down [01:00:09.542](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. [01:00:09.542](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 23943 psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist connection error: 'psql: error: connection to server on socket "/tmp/4CzvBA6cOS/.s.PGSQL.60357" failed: FATAL: database "postgres" does not exist' while running 'psql -XAtq -d port=60357 host=/tmp/4CzvBA6cOS 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 23943 ### 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 23856 ### 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" [01:00:10.006](0.464s) # Tests were run but no plan was declared and done_testing() was not seen. [01:00:10.006](0.000s) # Looks like your test exited with 29 just after 13.