# Checking port 17276
# Found port 17276
Name: primary
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/archives
Connection string: port=17276 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log
[14:52:41.569](0.063s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl --wait --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata --log /Users/admin/pgsql/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 26907
# Taking pg_basebackup my_backup from node "primary"
# Running: pg_basebackup --no-sync --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/backup/my_backup --host /var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz --port 17276 --checkpoint fast
# Backup finished
# Checking port 17277
# Found port 17277
Name: standby
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/archives
Connection string: port=17277 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --log /Users/admin/pgsql/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 27158
[14:52:48.288](6.720s) ok 1 - standby reached the same LSN as primary after WAIT FOR
[14:52:49.344](1.056s) ok 2 - standby reached the same LSN as primary
[14:52:49.459](0.114s) ok 3 - standby wrote WAL up to target LSN after WAIT FOR with MODE 'standby_write'
[14:52:49.602](0.143s) ok 4 - standby flushed WAL up to target LSN after WAIT FOR with MODE 'standby_flush'
[14:52:49.859](0.258s) ok 5 - primary flushed WAL up to target LSN after WAIT FOR with MODE 'primary_flush'
[14:52:51.278](1.419s) ok 6 - get timeout on waiting for unreachable LSN
[14:52:51.319](0.041s) ok 7 - WAIT FOR returns correct status after successful waiting
[14:52:51.454](0.135s) ok 8 - WAIT FOR returns correct status after timeout
[14:52:51.585](0.130s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:51.687](0.102s) ok 9 - canceled WAIT FOR LSN in subtransaction
[14:52:51.698](0.011s) ok 10 - query cancel interrupted WAIT FOR LSN in subtransaction
[14:52:51.704](0.005s) ok 11 - second WAIT FOR LSN timed out after savepoint rollback
[14:52:51.706](0.002s) ok 12 - WAIT FOR LSN after savepoint rollback did not disconnect
[14:52:51.742](0.036s) ok 13 - get an error when running standby_flush on the primary
[14:52:51.788](0.046s) ok 14 - get an error when running primary_flush on the standby
1
[14:52:51.847](0.059s) 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:52:52.946](1.099s) ok 16 - get an error when running within a function
[14:52:52.974](0.028s) ok 17 - get an error when running within a procedure
[14:52:53.020](0.046s) ok 18 - get an error when running within a DO block
[14:52:53.128](0.108s) ok 19 - get error for negative timeout
[14:52:53.185](0.056s) ok 20 - get error for unknown parameter
[14:52:53.225](0.040s) ok 21 - get error for duplicate TIMEOUT parameter
[14:52:53.326](0.101s) ok 22 - get error for duplicate NO_THROW parameter
[14:52:53.401](0.075s) ok 23 - get syntax error when options specified without WITH keyword
[14:52:53.448](0.048s) ok 24 - get syntax error for missing LSN
[14:52:53.546](0.097s) ok 25 - get error for invalid LSN format
[14:52:53.579](0.034s) ok 26 - get error for invalid timeout format
[14:52:53.622](0.043s) ok 27 - WAIT FOR WITH clause syntax works correctly
[14:52:53.776](0.153s) ok 28 - WAIT FOR WITH clause returns correct timeout status
[14:52:53.804](0.028s) ok 29 - get error for invalid WITH clause option
[14:52:53.832](0.028s) ok 30 - get error for invalid MODE value
[14:52:53.873](0.041s) ok 31 - get error for duplicate MODE parameter
[14:52:54.156](0.284s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:54.294](0.137s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:54.454](0.160s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:54.640](0.187s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:54.792](0.151s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:55.710](0.918s) ok 32 - multiple standby_replay waiters reported consistent data
[14:52:56.613](0.903s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:56.701](0.088s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:56.802](0.101s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:56.893](0.091s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:56.947](0.054s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:57.462](0.515s) ok 33 - multiple standby_write waiters: standby wrote WAL up to target LSN
[14:52:59.059](1.597s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:59.178](0.119s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:59.307](0.129s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:59.338](0.030s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:59.593](0.255s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:52:59.942](0.349s) ok 34 - multiple standby_flush waiters: standby flushed WAL up to target LSN
[14:53:00.619](0.677s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:00.905](0.286s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:01.012](0.107s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:01.201](0.189s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:01.245](0.044s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:01.303](0.058s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:01.906](0.603s) ok 35 - mixed mode waiters: all modes completed and reached target LSN
[14:53:03.045](1.139s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.143](0.098s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.260](0.117s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.310](0.050s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.369](0.059s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.509](0.139s) ok 36 - multiple primary_flush waiters: primary flushed WAL up to target LSN
[14:53:03.671](0.162s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.730](0.059s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:03.762](0.032s) # 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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --log /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_standby.log promote
waiting for server to promote.... done
server promoted
[14:53:04.655](0.893s) ok 37 - promotion interrupted all wait modes
[14:53:04.729](0.074s) ok 38 - wait for already replayed LSN exits immediately even after promotion
[14:53:04.945](0.215s) ok 39 - WAIT FOR returns correct status after standby promotion
### Stopping node "standby" using mode fast
# Running: pg_ctl --pgdata /Users/admin/pgsql/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 /Users/admin/pgsql/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 17278
# Found port 17278
Name: arc_primary
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/archives
Connection string: port=17278 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log
[14:53:05.234](0.289s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata --log /Users/admin/pgsql/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 29226
# Taking pg_basebackup arc_backup from node "arc_primary"
# Running: pg_basebackup --no-sync --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/backup/arc_backup --host /var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz --port 17278 --checkpoint fast
# Backup finished
# Checking port 17279
# Found port 17279
Name: arc_standby
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/archives
Connection string: port=17279 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata --log /Users/admin/pgsql/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 29572
[14:53:16.477](11.244s) ok 40 - arc_standby has no walreceiver
[14:53:16.601](0.123s) ok 41 - standby_write succeeds on archive-only standby (getter fallback)
[14:53:16.664](0.063s) ok 42 - standby_flush succeeds on archive-only standby (getter fallback)
[14:53:17.655](0.991s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:17.837](0.182s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:18.392](0.555s) ok 43 - standby_write waiter woken by replay on archive-only standby
[14:53:18.392](0.000s) ok 44 - standby_flush waiter woken by replay on archive-only standby
### Stopping node "arc_standby" using mode fast
# Running: pg_ctl --pgdata /Users/admin/pgsql/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 /Users/admin/pgsql/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 17280
# Found port 17280
Name: rcv_primary
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/archives
Connection string: port=17280 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log
[14:53:18.756](0.363s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --log /Users/admin/pgsql/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 29970
# Taking pg_basebackup rcv_backup from node "rcv_primary"
# Running: pg_basebackup --no-sync --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/backup/rcv_backup --host /var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz --port 17280 --checkpoint fast
# Backup finished
# Checking port 17281
# Found port 17281
Name: rcv_standby
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/archives
Connection string: port=17281 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --log /Users/admin/pgsql/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 30240
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 /Users/admin/pgsql/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 /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --log /Users/admin/pgsql/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 30337
[14:53:32.579](13.824s) ok 45 - replay sits ahead of initial walreceiver flush position
[14:53:32.656](0.077s) ok 46 - initial walreceiver flush position is segment-aligned
[14:53:32.719](0.063s) ok 47 - standby_write succeeds for already-replayed LSN after standby restart
[14:53:32.773](0.053s) ok 48 - standby_flush succeeds for already-replayed LSN after standby restart
### Starting node "rcv_primary"
# Running: pg_ctl --wait --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --log /Users/admin/pgsql/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 30393
Waiting for replication conn rcv_standby's replay_lsn to pass 0/04002E58 on rcv_primary
done
[14:53:34.140](1.368s) ok 49 - standby_replay: target == current succeeds
[14:53:34.234](0.093s) ok 50 - standby_replay: target == current - 1 succeeds
[14:53:34.769](0.536s) ok 51 - standby_replay: target == current + 1 times out
[14:53:34.870](0.101s) ok 52 - standby_flush boundary is not masked by replay floor
[14:53:35.086](0.216s) ok 53 - standby_flush: target == current succeeds
[14:53:35.131](0.045s) ok 54 - standby_flush: target == current - 1 succeeds
[14:53:35.679](0.548s) ok 55 - standby_flush: target == current + 1 times out
[14:53:36.071](0.392s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[14:53:36.521](0.450s) ok 56 - standby_replay: waiter at current + 1 wakes when replay advances
### Stopping node "rcv_standby" using mode fast
# Running: pg_ctl --pgdata /Users/admin/pgsql/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 /Users/admin/pgsql/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 17282
# Found port 17282
Name: tl_primary
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/archives
Connection string: port=17282 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log
[14:53:36.917](0.396s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata --log /Users/admin/pgsql/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 30756
# Taking pg_basebackup tl_backup from node "tl_primary"
# Running: pg_basebackup --no-sync --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/backup/tl_backup --host /var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz --port 17282 --checkpoint fast
# Backup finished
# Checking port 17283
# Found port 17283
Name: tl_standby1
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/archives
Connection string: port=17283 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --log /Users/admin/pgsql/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 30920
# Taking pg_basebackup tl_backup2 from node "tl_standby1"
# Running: pg_basebackup --no-sync --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/backup/tl_backup2 --host /var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz --port 17283 --checkpoint fast
# Backup finished
# Checking port 17284
# Found port 17284
Name: tl_standby2
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/archives
Connection string: port=17284 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/JLrQIOfkLz
Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata --log /Users/admin/pgsql/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 31125
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:53:44.928](8.011s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
### Promoting node "tl_standby1"
# Running: pg_ctl --pgdata /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --log /Users/admin/pgsql/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:53:45.254](0.326s) 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 /Users/admin/pgsql/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 /Users/admin/pgsql/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 /Users/admin/pgsql/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:53:45.627](0.373s) 1..57