# Checking port 54806
# Found port 54806
Name: alpha
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/archives
Connection string: port=54806 host=/tmp/s0K_j258uD
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/log/015_promotion_pages_alpha.log
[13:40:27.621](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/015_promotion_pages/data/t_015_promotion_pages_alpha_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/pgdata
### Starting node "alpha"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/log/015_promotion_pages_alpha.log -o --cluster-name=alpha start
waiting for server to start.... done
server started
# Postmaster PID for node "alpha" is 36528
# Taking pg_basebackup bkp from node "alpha"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/backup/bkp -h /tmp/s0K_j258uD -p 54806 --checkpoint fast --no-sync
# Backup finished
# Checking port 54807
# Found port 54807
Name: bravo
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_bravo_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_bravo_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_bravo_data/archives
Connection string: port=54807 host=/tmp/s0K_j258uD
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/log/015_promotion_pages_bravo.log
# Initializing node "bravo" from backup "bkp" of node "alpha"
### Enabling streaming replication for node "bravo"
### Starting node "bravo"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_bravo_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/log/015_promotion_pages_bravo.log -o --cluster-name=bravo start
waiting for server to start.... done
server started
# Postmaster PID for node "bravo" is 36569
#### Begin standard error
psql:<stdin>:1: WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
#### End standard error
#### Begin standard error
psql:<stdin>:1: INFO:  vacuuming "postgres.public.test1"
psql:<stdin>:1: INFO:  finished vacuuming "postgres.public.test1": index scans: 0
pages: 0 removed, 45 remain, 45 scanned (100.00% of total)
tuples: 0 removed, 10000 remain, 0 are dead but not yet removable
removable cutoff: 740, which was 0 XIDs old when operation ended
new relfrozenxid: 739, which is 1 XIDs ahead of previous value
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
avg read rate: 16.886 MB/s, avg write rate: 281.430 MB/s
buffer usage: 144 hits, 3 misses, 50 dirtied
WAL usage: 46 records, 2 full page images, 11725 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
#### End standard error
Waiting for replication conn bravo's replay_lsn to pass 0/30B8000 on alpha
[13:44:06.140](218.519s) # poll_query_until timed out executing this query:
# SELECT '0/30B8000' <= replay_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('bravo', 'walreceiver')
# expecting this output:
# t
# last actual query output:
# t
# with stderr:
# WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
timed out waiting for catchup at /tmp/cirrus-ci-build/src/test/recovery/t/015_promotion_pages.pl line 47.
# Postmaster PID for node "alpha" is 36528
### Stopping node "alpha" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_alpha_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "alpha"
# Postmaster PID for node "bravo" is 36569
### Stopping node "bravo" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/015_promotion_pages/data/t_015_promotion_pages_bravo_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "bravo"