# Checking port 52543 # Found port 52543 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=52543 host=/tmp/a6AMQxDLbc Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log [21:06:05.530](0.050s) # 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 23451 # 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/a6AMQxDLbc -p 52543 --checkpoint fast --no-sync # Backup finished # Checking port 52544 # Found port 52544 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=52544 host=/tmp/a6AMQxDLbc 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 23520 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:05.957](0.427s) not ok 1 - reload node london with synchronous_standby_names = 'paris' [21:06:05.957](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. [21:06:05.957](0.000s) # got: '' # expected: 't' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist [21:06:05.966](0.008s) not ok 2 - reload node paris with synchronous_standby_names = 'london' [21:06:05.966](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. [21:06:05.966](0.000s) # got: '' # expected: 't' [21:06:05.966](0.000s) # Initially, london is primary and paris is standby psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" 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 23633 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:06.318](0.352s) not ok 3 - Commit prepared transaction after restart [21:06:06.318](0.000s) # Failed test 'Commit prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85. [21:06:06.318](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:06.364](0.046s) not ok 4 - Rollback prepared transaction after restart [21:06:06.365](0.000s) # Failed test 'Rollback prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88. [21:06:06.365](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" 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 23714 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:06.585](0.220s) not ok 5 - Commit prepared transaction after teardown [21:06:06.585](0.000s) # Failed test 'Commit prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113. [21:06:06.585](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:06.628](0.043s) not ok 6 - Rollback prepared transaction after teardown [21:06:06.628](0.000s) # Failed test 'Rollback prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116. [21:06:06.628](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" 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 23858 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:06.931](0.303s) not ok 7 - Replay several transactions with same GID [21:06:06.932](0.000s) # Failed test 'Replay several transactions with same GID' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 140. [21:06:06.932](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" 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 23952 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:07.343](0.411s) not ok 8 - Cleanup of shared memory state for 2PC commit [21:06:07.343](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. [21:06:07.343](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist [21:06:07.445](0.102s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint [21:06:07.446](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. [21:06:07.446](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist [21:06:07.605](0.160s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint [21:06:07.605](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. [21:06:07.606](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" 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 [21:06:07.856](0.251s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist [21:06:07.899](0.043s) not ok 11 - Restore of prepared transaction on promoted standby [21:06:07.900](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. [21:06:07.900](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 24234 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" 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 24360 ### 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 [21:06:08.531](0.632s) # Now london is primary and paris is standby psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist [21:06:08.552](0.020s) not ok 12 - Restore prepared transactions from files with primary down [21:06:08.552](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. [21:06:08.552](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 24427 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52543" 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 24580 ### 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 [21:06:09.193](0.641s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist [21:06:09.217](0.024s) not ok 13 - Restore prepared transactions from records with primary down [21:06:09.217](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. [21:06:09.217](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 24651 psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist connection error: 'psql: error: connection to server on socket "/tmp/a6AMQxDLbc/.s.PGSQL.52544" failed: FATAL: database "postgres" does not exist' while running 'psql -XAtq -d port=52544 host=/tmp/a6AMQxDLbc 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 24651 ### 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 24580 ### 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" [21:06:09.662](0.444s) # Tests were run but no plan was declared and done_testing() was not seen. [21:06:09.662](0.000s) # Looks like your test exited with 29 just after 13.