# Checking port 62952
# Found port 62952
Name: primary
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/archives
Connection string: port=62952 host=/tmp/o_2rhsuQe0
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/log/025_stuck_on_old_timeline_primary.log
[21:06:07.265](0.013s) # 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/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/pgdata
### Enabling WAL archiving for node "primary"
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/log/025_stuck_on_old_timeline_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 24023
# Taking pg_basebackup my_backup from node "primary"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/backup/my_backup -h /tmp/o_2rhsuQe0 -p 62952 --checkpoint fast --no-sync
# Backup finished
# Checking port 62953
# Found port 62953
Name: standby
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/archives
Connection string: port=62953 host=/tmp/o_2rhsuQe0
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/log/025_stuck_on_old_timeline_standby.log
# Initializing node "standby" from backup "my_backup" of node "primary"
### Enabling streaming replication for node "standby"
### Starting node "standby"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/log/025_stuck_on_old_timeline_standby.log -o --cluster-name=standby start
waiting for server to start.... done
server started
# Postmaster PID for node "standby" is 24120
# Taking pg_basebackup my_backup from node "standby"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/backup/my_backup -h /tmp/o_2rhsuQe0 -p 62953 --checkpoint fast --no-sync -Xnone
NOTICE:  WAL archiving is not enabled; you must ensure that all required WAL segments are copied through other means to complete the backup
# Backup finished
# Checking port 62954
# Found port 62954
Name: cascade
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_cascade_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_cascade_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_cascade_data/archives
Connection string: port=62954 host=/tmp/o_2rhsuQe0
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/log/025_stuck_on_old_timeline_cascade.log
# Initializing node "cascade" from backup "my_backup" of node "standby"
### Enabling streaming replication for node "cascade"
### Enabling WAL restore for node "cascade"
### Promoting node "standby"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/log/025_stuck_on_old_timeline_standby.log promote
waiting for server to promote.... done
server promoted
[21:09:25.176](197.911s) # poll_query_until timed out executing this query:
# SELECT NOT pg_is_in_recovery();
# expecting this output:
# t
# last actual query output:
# 
# with stderr:
# psql: error: connection to server on socket "/tmp/o_2rhsuQe0/.s.PGSQL.62953" failed: FATAL:  database "postgres" does not exist
Timed out while waiting for promotion at /tmp/cirrus-ci-build/src/test/recovery/t/025_stuck_on_old_timeline.pl line 76.
# Postmaster PID for node "primary" is 24023
### Stopping node "primary" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_primary_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
# Postmaster PID for node "standby" is 24120
### Stopping node "standby" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/025_stuck_on_old_timeline/data/t_025_stuck_on_old_timeline_standby_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby"
# No postmaster PID for node "cascade"