# Checking port 13034
# Found port 13034
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=13034 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log
[22:32:43.051](0.029s) # 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 24753
# 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/j2m0IxZpLT --port 13034 --checkpoint fast
# Backup finished
# Checking port 13035
# Found port 13035
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=13035 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
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 25223
[22:32:47.725](4.674s) ok 1 - standby reached the same LSN as primary after WAIT FOR
[22:32:48.764](1.039s) ok 2 - standby reached the same LSN as primary
[22:32:48.847](0.084s) ok 3 - standby wrote WAL up to target LSN after WAIT FOR with MODE 'standby_write'
[22:32:48.944](0.096s) ok 4 - standby flushed WAL up to target LSN after WAIT FOR with MODE 'standby_flush'
[22:32:49.012](0.068s) ok 5 - primary flushed WAL up to target LSN after WAIT FOR with MODE 'primary_flush'
[22:32:50.092](1.080s) ok 6 - get timeout on waiting for unreachable LSN
[22:32:50.122](0.031s) ok 7 - WAIT FOR returns correct status after successful waiting
[22:32:50.193](0.071s) ok 8 - WAIT FOR returns correct status after timeout
[22:32:50.293](0.100s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:50.388](0.095s) ok 9 - canceled WAIT FOR LSN in subtransaction
[22:32:50.405](0.017s) ok 10 - query cancel interrupted WAIT FOR LSN in subtransaction
[22:32:50.406](0.000s) ok 11 - second WAIT FOR LSN timed out after savepoint rollback
[22:32:50.406](0.000s) ok 12 - WAIT FOR LSN after savepoint rollback did not disconnect
[22:32:50.445](0.040s) ok 13 - get an error when running standby_flush on the primary
[22:32:50.609](0.163s) ok 14 - get an error when running primary_flush on the standby
1
[22:32:50.678](0.070s) 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
[22:32:51.768](1.090s) ok 16 - get an error when running within a function
[22:32:51.838](0.069s) ok 17 - get an error when running within a procedure
[22:32:51.873](0.035s) ok 18 - get an error when running within a DO block
[22:32:51.929](0.056s) ok 19 - get error for negative timeout
[22:32:51.955](0.026s) ok 20 - get error for unknown parameter
[22:32:51.990](0.035s) ok 21 - get error for duplicate TIMEOUT parameter
[22:32:52.018](0.029s) ok 22 - get error for duplicate NO_THROW parameter
[22:32:52.068](0.049s) ok 23 - get syntax error when options specified without WITH keyword
[22:32:52.106](0.038s) ok 24 - get syntax error for missing LSN
[22:32:52.132](0.026s) ok 25 - get error for invalid LSN format
[22:32:52.161](0.029s) ok 26 - get error for invalid timeout format
[22:32:52.184](0.024s) ok 27 - WAIT FOR WITH clause syntax works correctly
[22:32:52.336](0.152s) ok 28 - WAIT FOR WITH clause returns correct timeout status
[22:32:52.397](0.061s) ok 29 - get error for invalid WITH clause option
[22:32:52.435](0.039s) ok 30 - get error for invalid MODE value
[22:32:52.484](0.049s) ok 31 - get error for duplicate MODE parameter
[22:32:52.721](0.237s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:52.933](0.211s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:53.058](0.125s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:53.172](0.114s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:53.296](0.124s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:54.326](1.031s) ok 32 - multiple standby_replay waiters reported consistent data
[22:32:55.260](0.934s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:55.300](0.039s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:55.405](0.105s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:55.455](0.050s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:55.484](0.029s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:55.679](0.195s) ok 33 - multiple standby_write waiters: standby wrote WAL up to target LSN
[22:32:57.152](1.473s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:57.187](0.035s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:57.231](0.044s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:57.286](0.055s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:57.367](0.082s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:57.573](0.206s) ok 34 - multiple standby_flush waiters: standby flushed WAL up to target LSN
[22:32:58.324](0.751s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:58.350](0.026s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:58.377](0.027s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:58.413](0.035s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:58.443](0.031s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:58.469](0.026s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.356](0.886s) ok 35 - mixed mode waiters: all modes completed and reached target LSN
[22:32:59.662](0.307s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.683](0.021s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.709](0.026s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.730](0.022s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.755](0.025s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.790](0.035s) ok 36 - multiple primary_flush waiters: primary flushed WAL up to target LSN
[22:32:59.882](0.092s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.912](0.030s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:32:59.946](0.034s) # 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
[22:33:00.730](0.784s) ok 37 - promotion interrupted all wait modes
[22:33:00.760](0.030s) ok 38 - wait for already replayed LSN exits immediately even after promotion
[22:33:00.795](0.035s) 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 13036
# Found port 13036
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=13036 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log
[22:33:01.046](0.251s) # 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 27738
# 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/j2m0IxZpLT --port 13036 --checkpoint fast
# Backup finished
# Checking port 13037
# Found port 13037
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=13037 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
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 28040
[22:33:04.049](3.004s) ok 40 - arc_standby has no walreceiver
[22:33:04.080](0.030s) ok 41 - standby_write succeeds on archive-only standby (getter fallback)
[22:33:04.120](0.041s) ok 42 - standby_flush succeeds on archive-only standby (getter fallback)
[22:33:04.446](0.326s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:33:04.474](0.028s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:33:04.716](0.242s) ok 43 - standby_write waiter woken by replay on archive-only standby
[22:33:04.716](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 13038
# Found port 13038
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=13038 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log
[22:33:04.981](0.265s) # 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 28297
# 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/j2m0IxZpLT --port 13038 --checkpoint fast
# Backup finished
# Checking port 13039
# Found port 13039
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=13039 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
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 28374
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 28454
[22:33:08.175](3.194s) ok 45 - replay sits ahead of initial walreceiver flush position
[22:33:08.210](0.035s) ok 46 - initial walreceiver flush position is segment-aligned
[22:33:08.238](0.028s) ok 47 - standby_write succeeds for already-replayed LSN after standby restart
[22:33:08.271](0.033s) 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 28515
Waiting for replication conn rcv_standby's replay_lsn to pass 0/04002E58 on rcv_primary
done
[22:33:08.832](0.561s) ok 49 - standby_replay: target == current succeeds
[22:33:08.864](0.032s) ok 50 - standby_replay: target == current - 1 succeeds
[22:33:09.398](0.534s) ok 51 - standby_replay: target == current + 1 times out
[22:33:09.560](0.162s) ok 52 - standby_flush boundary is not masked by replay floor
[22:33:09.646](0.086s) ok 53 - standby_flush: target == current succeeds
[22:33:09.689](0.044s) ok 54 - standby_flush: target == current - 1 succeeds
[22:33:10.224](0.535s) ok 55 - standby_flush: target == current + 1 times out
[22:33:10.324](0.100s) # connect output:
# {
#   'stderr' => 'background_psql: ready
# ',
#   'stdout' => 'background_psql: ready
# '
# }
[22:33:10.496](0.172s) 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 13040
# Found port 13040
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=13040 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log
[22:33:10.748](0.252s) # 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 28912
# 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/j2m0IxZpLT --port 13040 --checkpoint fast
# Backup finished
# Checking port 13041
# Found port 13041
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=13041 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
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 29057
# 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/j2m0IxZpLT --port 13041 --checkpoint fast
# Backup finished
# Checking port 13042
# Found port 13042
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=13042 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/j2m0IxZpLT
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 29185
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
[22:33:14.588](3.840s) # 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
[22:33:14.896](0.309s) 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"
[22:33:15.299](0.403s) 1..57