# Checking port 11147 # Found port 11147 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=11147 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log [13:51:58.399](0.050s) # 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 23300 # 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/Fn_0_5GIyw --port 11147 --checkpoint fast # Backup finished # Checking port 11148 # Found port 11148 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=11148 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw 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 23789 [13:52:04.573](6.174s) ok 1 - standby reached the same LSN as primary after WAIT FOR [13:52:05.660](1.088s) ok 2 - standby reached the same LSN as primary [13:52:05.784](0.123s) ok 3 - standby wrote WAL up to target LSN after WAIT FOR with MODE 'standby_write' [13:52:05.890](0.106s) ok 4 - standby flushed WAL up to target LSN after WAIT FOR with MODE 'standby_flush' [13:52:06.038](0.148s) ok 5 - primary flushed WAL up to target LSN after WAIT FOR with MODE 'primary_flush' [13:52:07.160](1.121s) ok 6 - get timeout on waiting for unreachable LSN [13:52:07.196](0.036s) ok 7 - WAIT FOR returns correct status after successful waiting [13:52:07.310](0.114s) ok 8 - WAIT FOR returns correct status after timeout [13:52:07.428](0.118s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:07.614](0.186s) ok 9 - canceled WAIT FOR LSN in subtransaction [13:52:07.628](0.014s) ok 10 - query cancel interrupted WAIT FOR LSN in subtransaction [13:52:07.628](0.000s) ok 11 - second WAIT FOR LSN timed out after savepoint rollback [13:52:07.628](0.000s) ok 12 - WAIT FOR LSN after savepoint rollback did not disconnect [13:52:07.651](0.022s) ok 13 - get an error when running standby_flush on the primary [13:52:07.689](0.038s) ok 14 - get an error when running primary_flush on the standby 1 [13:52:07.716](0.027s) 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 [13:52:08.803](1.088s) ok 16 - get an error when running within a function [13:52:08.835](0.031s) ok 17 - get an error when running within a procedure [13:52:09.021](0.186s) ok 18 - get an error when running within a DO block [13:52:09.164](0.143s) ok 19 - get error for negative timeout [13:52:09.282](0.118s) ok 20 - get error for unknown parameter [13:52:09.304](0.022s) ok 21 - get error for duplicate TIMEOUT parameter [13:52:09.319](0.015s) ok 22 - get error for duplicate NO_THROW parameter [13:52:09.333](0.014s) ok 23 - get syntax error when options specified without WITH keyword [13:52:09.433](0.099s) ok 24 - get syntax error for missing LSN [13:52:09.558](0.125s) ok 25 - get error for invalid LSN format [13:52:09.612](0.055s) ok 26 - get error for invalid timeout format [13:52:09.734](0.122s) ok 27 - WAIT FOR WITH clause syntax works correctly [13:52:09.879](0.146s) ok 28 - WAIT FOR WITH clause returns correct timeout status [13:52:10.060](0.180s) ok 29 - get error for invalid WITH clause option [13:52:10.177](0.117s) ok 30 - get error for invalid MODE value [13:52:10.244](0.067s) ok 31 - get error for duplicate MODE parameter [13:52:10.452](0.208s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:10.539](0.087s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:10.705](0.166s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:10.841](0.136s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:10.924](0.083s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:11.928](1.004s) ok 32 - multiple standby_replay waiters reported consistent data [13:52:12.323](0.394s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:12.347](0.024s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:12.375](0.028s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:12.402](0.027s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:12.427](0.025s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:12.551](0.124s) ok 33 - multiple standby_write waiters: standby wrote WAL up to target LSN [13:52:13.753](1.202s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:13.775](0.023s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:13.803](0.027s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:13.829](0.026s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:13.857](0.029s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:14.008](0.150s) ok 34 - multiple standby_flush waiters: standby flushed WAL up to target LSN [13:52:15.000](0.992s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:15.026](0.026s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:15.052](0.026s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:15.076](0.024s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:15.144](0.068s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:15.251](0.107s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:15.993](0.743s) ok 35 - mixed mode waiters: all modes completed and reached target LSN [13:52:16.582](0.589s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.612](0.030s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.643](0.032s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.668](0.025s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.698](0.030s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.761](0.063s) ok 36 - multiple primary_flush waiters: primary flushed WAL up to target LSN [13:52:16.842](0.082s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.869](0.026s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:16.914](0.045s) # 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 [13:52:17.505](0.591s) ok 37 - promotion interrupted all wait modes [13:52:17.531](0.026s) ok 38 - wait for already replayed LSN exits immediately even after promotion [13:52:17.555](0.024s) 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 11149 # Found port 11149 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=11149 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log [13:52:17.860](0.305s) # 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 25879 # 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/Fn_0_5GIyw --port 11149 --checkpoint fast # Backup finished # Checking port 11150 # Found port 11150 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=11150 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw 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 26158 [13:52:21.153](3.294s) ok 40 - arc_standby has no walreceiver [13:52:21.173](0.020s) ok 41 - standby_write succeeds on archive-only standby (getter fallback) [13:52:21.196](0.023s) ok 42 - standby_flush succeeds on archive-only standby (getter fallback) [13:52:21.389](0.193s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:21.411](0.022s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:21.785](0.374s) ok 43 - standby_write waiter woken by replay on archive-only standby [13:52:21.785](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 11151 # Found port 11151 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=11151 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log [13:52:22.039](0.254s) # 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 26468 # 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/Fn_0_5GIyw --port 11151 --checkpoint fast # Backup finished # Checking port 11152 # Found port 11152 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=11152 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw 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 26562 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 26674 [13:52:24.607](2.568s) ok 45 - replay sits ahead of initial walreceiver flush position [13:52:24.643](0.037s) ok 46 - initial walreceiver flush position is segment-aligned [13:52:24.676](0.033s) ok 47 - standby_write succeeds for already-replayed LSN after standby restart [13:52:24.783](0.106s) 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 26727 Waiting for replication conn rcv_standby's replay_lsn to pass 0/04002E58 on rcv_primary done [13:52:25.318](0.535s) ok 49 - standby_replay: target == current succeeds [13:52:25.342](0.024s) ok 50 - standby_replay: target == current - 1 succeeds [13:52:25.872](0.530s) ok 51 - standby_replay: target == current + 1 times out [13:52:25.940](0.067s) ok 52 - standby_flush boundary is not masked by replay floor [13:52:26.052](0.112s) ok 53 - standby_flush: target == current succeeds [13:52:26.073](0.021s) ok 54 - standby_flush: target == current - 1 succeeds [13:52:26.599](0.526s) ok 55 - standby_flush: target == current + 1 times out [13:52:26.697](0.098s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [13:52:26.847](0.150s) 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 11153 # Found port 11153 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=11153 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw Log file: /Users/admin/pgsql/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log [13:52:27.107](0.260s) # 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 27011 # 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/Fn_0_5GIyw --port 11153 --checkpoint fast # Backup finished # Checking port 11154 # Found port 11154 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=11154 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw 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 27087 # 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/Fn_0_5GIyw --port 11154 --checkpoint fast # Backup finished # Checking port 11155 # Found port 11155 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=11155 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/Fn_0_5GIyw 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 27179 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 [13:52:30.328](3.221s) # 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 [13:52:30.653](0.324s) 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" [13:52:31.013](0.360s) 1..57