# Checking port 24263
# Found port 24263
Name: primary
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/archives
Connection string: port=24263 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log
[14:51:42.909](0.021s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log --options --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 27434
# Taking pg_basebackup my_backup from node "primary"
# Running: pg_basebackup --no-sync --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/backup/my_backup --host /tmp/p6TNHBF0Ek --port 24263 --checkpoint fast
# Backup finished
# Checking port 24264
# Found port 24264
Name: standby
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/archives
Connection string: port=24264 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_standby.log
# Initializing node "standby" from backup "my_backup" of node "primary"
### Enabling streaming replication for node "standby"
### Starting node "standby"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_standby.log --options --cluster-name=standby start
waiting for server to start.... done
server started
# Postmaster PID for node "standby" is 4726
[14:51:44.566](1.657s) ok 1 - standby reached the same LSN as primary after WAIT FOR
[14:51:45.597](1.032s) ok 2 - standby reached the same LSN as primary
[14:51:45.645](0.047s) ok 3 - standby wrote WAL up to target LSN after WAIT FOR with MODE 'standby_write'
[14:51:45.704](0.059s) ok 4 - standby flushed WAL up to target LSN after WAIT FOR with MODE 'standby_flush'
[14:51:45.750](0.046s) ok 5 - primary flushed WAL up to target LSN after WAIT FOR with MODE 'primary_flush'
[14:51:46.811](1.061s) ok 6 - get timeout on waiting for unreachable LSN
[14:51:46.831](0.020s) ok 7 - WAIT FOR returns correct status after successful waiting
[14:51:46.887](0.056s) ok 8 - WAIT FOR returns correct status after timeout
[14:51:46.933](0.046s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:47.002](0.069s) ok 9 - canceled WAIT FOR LSN in subtransaction
[14:51:47.015](0.013s) ok 10 - query cancel interrupted WAIT FOR LSN in subtransaction
[14:51:47.015](0.000s) ok 11 - second WAIT FOR LSN timed out after savepoint rollback
[14:51:47.015](0.000s) ok 12 - WAIT FOR LSN after savepoint rollback did not disconnect
[14:51:47.038](0.023s) ok 13 - get an error when running standby_flush on the primary
[14:51:47.066](0.028s) ok 14 - get an error when running primary_flush on the standby
1
[14:51:47.086](0.020s) ok 15 - get an error when running in a transaction with an isolation level higher than REPEATABLE READ
Waiting for replication conn standby's replay_lsn to pass 0/030133E0 on primary
done
[14:51:48.139](1.053s) ok 16 - get an error when running within a function
[14:51:48.176](0.037s) ok 17 - get an error when running within a procedure
[14:51:48.193](0.018s) ok 18 - get an error when running within a DO block
[14:51:48.239](0.046s) ok 19 - get error for negative timeout
[14:51:48.258](0.018s) ok 20 - get error for unknown parameter
[14:51:48.275](0.017s) ok 21 - get error for duplicate TIMEOUT parameter
[14:51:48.294](0.019s) ok 22 - get error for duplicate NO_THROW parameter
[14:51:48.326](0.033s) ok 23 - get syntax error when options specified without WITH keyword
[14:51:48.341](0.015s) ok 24 - get syntax error for missing LSN
[14:51:48.361](0.020s) ok 25 - get error for invalid LSN format
[14:51:48.378](0.017s) ok 26 - get error for invalid timeout format
[14:51:48.396](0.018s) ok 27 - WAIT FOR WITH clause syntax works correctly
[14:51:48.514](0.118s) ok 28 - WAIT FOR WITH clause returns correct timeout status
[14:51:48.532](0.018s) ok 29 - get error for invalid WITH clause option
[14:51:48.548](0.017s) ok 30 - get error for invalid MODE value
[14:51:48.565](0.017s) ok 31 - get error for duplicate MODE parameter
[14:51:48.655](0.090s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:48.703](0.048s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:48.774](0.070s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:48.825](0.052s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:48.882](0.057s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:49.888](1.006s) ok 32 - multiple standby_replay waiters reported consistent data
[14:51:50.185](0.297s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:50.206](0.021s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:50.222](0.016s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:50.239](0.017s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:50.268](0.028s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:50.381](0.113s) ok 33 - multiple standby_write waiters: standby wrote WAL up to target LSN
[14:51:51.392](1.011s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:51.408](0.016s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:51.425](0.017s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:51.443](0.019s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:51.459](0.015s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:51.666](0.207s) ok 34 - multiple standby_flush waiters: standby flushed WAL up to target LSN
[14:51:52.547](0.881s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:52.562](0.016s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:52.578](0.016s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:52.593](0.015s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:52.612](0.019s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:52.629](0.017s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:53.627](0.998s) ok 35 - mixed mode waiters: all modes completed and reached target LSN
[14:51:53.812](0.185s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:53.830](0.018s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:53.858](0.028s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:53.889](0.031s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:53.904](0.015s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:53.952](0.048s) ok 36 - multiple primary_flush waiters: primary flushed WAL up to target LSN
[14:51:54.015](0.062s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:54.030](0.015s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:51:54.058](0.028s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
Waiting for replication conn standby's replay_lsn to pass 0/04000000 on primary
done
### Promoting node "standby"
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_standby.log promote
waiting for server to promote.... done
server promoted
[14:51:54.938](0.880s) ok 37 - promotion interrupted all wait modes
[14:51:54.958](0.020s) ok 38 - wait for already replayed LSN exits immediately even after promotion
[14:51:55.378](0.420s) ok 39 - WAIT FOR returns correct status after standby promotion
### Stopping node "standby" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby"
### Stopping node "primary" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
# Checking port 24265
# Found port 24265
Name: arc_primary
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/archives
Connection string: port=24265 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log
[14:51:55.764](0.385s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata
### Enabling WAL archiving for node "arc_primary"
### Starting node "arc_primary"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log --options --cluster-name=arc_primary start
waiting for server to start.... done
server started
# Postmaster PID for node "arc_primary" is 22062
# Taking pg_basebackup arc_backup from node "arc_primary"
# Running: pg_basebackup --no-sync --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/backup/arc_backup --host /tmp/p6TNHBF0Ek --port 24265 --checkpoint fast
# Backup finished
# Checking port 24266
# Found port 24266
Name: arc_standby
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/archives
Connection string: port=24266 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_standby.log
# Initializing node "arc_standby" from backup "arc_backup" of node "arc_primary"
### Enabling WAL restore for node "arc_standby"
### Starting node "arc_standby"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_standby.log --options --cluster-name=arc_standby start
waiting for server to start.... done
server started
# Postmaster PID for node "arc_standby" is 9562
[14:52:00.124](4.361s) ok 40 - arc_standby has no walreceiver
[14:52:00.144](0.020s) ok 41 - standby_write succeeds on archive-only standby (getter fallback)
[14:52:00.169](0.025s) ok 42 - standby_flush succeeds on archive-only standby (getter fallback)
[14:52:00.434](0.265s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:00.453](0.019s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:00.763](0.311s) ok 43 - standby_write waiter woken by replay on archive-only standby
[14:52:00.765](0.001s) ok 44 - standby_flush waiter woken by replay on archive-only standby
### Stopping node "arc_standby" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "arc_standby"
### Stopping node "arc_primary" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "arc_primary"
# Checking port 24267
# Found port 24267
Name: rcv_primary
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/archives
Connection string: port=24267 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log
[14:52:01.053](0.289s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata
### Starting node "rcv_primary"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log --options --cluster-name=rcv_primary start
waiting for server to start.... done
server started
# Postmaster PID for node "rcv_primary" is 8429
# Taking pg_basebackup rcv_backup from node "rcv_primary"
# Running: pg_basebackup --no-sync --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/backup/rcv_backup --host /tmp/p6TNHBF0Ek --port 24267 --checkpoint fast
# Backup finished
# Checking port 24268
# Found port 24268
Name: rcv_standby
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/archives
Connection string: port=24268 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_standby.log
# Initializing node "rcv_standby" from backup "rcv_backup" of node "rcv_primary"
### Enabling streaming replication for node "rcv_standby"
### Starting node "rcv_standby"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_standby.log --options --cluster-name=rcv_standby start
waiting for server to start.... done
server started
# Postmaster PID for node "rcv_standby" is 10990
Waiting for replication conn rcv_standby's replay_lsn to pass 0/040002D0 on rcv_primary
done
### Stopping node "rcv_standby" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "rcv_standby"
### Stopping node "rcv_primary" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "rcv_primary"
### Starting node "rcv_standby"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_standby.log --options --cluster-name=rcv_standby start
waiting for server to start.... done
server started
# Postmaster PID for node "rcv_standby" is 7561
[14:52:04.950](3.897s) ok 45 - replay sits ahead of initial walreceiver flush position
[14:52:05.075](0.125s) ok 46 - initial walreceiver flush position is segment-aligned
[14:52:05.340](0.266s) ok 47 - standby_write succeeds for already-replayed LSN after standby restart
[14:52:05.468](0.128s) ok 48 - standby_flush succeeds for already-replayed LSN after standby restart
### Starting node "rcv_primary"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log --options --cluster-name=rcv_primary start
waiting for server to start.... done
server started
# Postmaster PID for node "rcv_primary" is 12662
Waiting for replication conn rcv_standby's replay_lsn to pass 0/04002E58 on rcv_primary
done
[14:52:07.473](2.005s) ok 49 - standby_replay: target == current succeeds
[14:52:07.517](0.044s) ok 50 - standby_replay: target == current - 1 succeeds
[14:52:08.135](0.619s) ok 51 - standby_replay: target == current + 1 times out
[14:52:08.230](0.095s) ok 52 - standby_flush boundary is not masked by replay floor
[14:52:08.311](0.081s) ok 53 - standby_flush: target == current succeeds
[14:52:08.352](0.041s) ok 54 - standby_flush: target == current - 1 succeeds
[14:52:08.884](0.533s) ok 55 - standby_flush: target == current + 1 times out
[14:52:08.989](0.104s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:10.039](1.050s) ok 56 - standby_replay: waiter at current + 1 wakes when replay advances
### Stopping node "rcv_standby" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "rcv_standby"
### Stopping node "rcv_primary" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "rcv_primary"
# Checking port 24269
# Found port 24269
Name: tl_primary
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/archives
Connection string: port=24269 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log
[14:52:10.505](0.466s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata
### Starting node "tl_primary"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log --options --cluster-name=tl_primary start
waiting for server to start.... done
server started
# Postmaster PID for node "tl_primary" is 23284
# Taking pg_basebackup tl_backup from node "tl_primary"
# Running: pg_basebackup --no-sync --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/backup/tl_backup --host /tmp/p6TNHBF0Ek --port 24269 --checkpoint fast
# Backup finished
# Checking port 24270
# Found port 24270
Name: tl_standby1
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/archives
Connection string: port=24270 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_standby1.log
# Initializing node "tl_standby1" from backup "tl_backup" of node "tl_primary"
### Enabling streaming replication for node "tl_standby1"
### Starting node "tl_standby1"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_standby1.log --options --cluster-name=tl_standby1 start
waiting for server to start.... done
server started
# Postmaster PID for node "tl_standby1" is 13405
# Taking pg_basebackup tl_backup2 from node "tl_standby1"
# Running: pg_basebackup --no-sync --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/backup/tl_backup2 --host /tmp/p6TNHBF0Ek --port 24270 --checkpoint fast
# Backup finished
# Checking port 24271
# Found port 24271
Name: tl_standby2
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/backup
Archive directory: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/archives
Connection string: port=24271 host=/tmp/p6TNHBF0Ek
Log file: /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_standby2.log
# Initializing node "tl_standby2" from backup "tl_backup2" of node "tl_standby1"
### Enabling streaming replication for node "tl_standby2"
### Starting node "tl_standby2"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_standby2.log --options --cluster-name=tl_standby2 start
waiting for server to start.... done
server started
# Postmaster PID for node "tl_standby2" is 15499
Waiting for replication conn tl_standby1's replay_lsn to pass 0/03002098 on tl_primary
done
Waiting for replication conn tl_standby2's replay_lsn to pass 0/03002098 on tl_standby1
done
[14:52:14.988](4.483s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
### Promoting node "tl_standby1"
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --log /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_standby1.log promote
waiting for server to promote.... done
server promoted
[14:52:15.736](0.748s) ok 57 - WAIT FOR LSN survives upstream promotion and timeline switch on cascade standby
### Stopping node "tl_standby2" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "tl_standby2"
### Stopping node "tl_standby1" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "tl_standby1"
### Stopping node "tl_primary" using mode fast
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata --mode fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "tl_primary"
[14:52:16.272](0.537s) 1..57