# Checking port 32109 # Found port 32109 Name: primary Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/archives Connection string: port=32109 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log [19:46:16.197](0.027s) # initializing database system by running initdb # Running: initdb --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata --auth trust -c debug_copy_parse_plan_trees=on -c debug_write_read_parse_plan_trees=on -c debug_raw_expression_coverage_test=on -c debug_parallel_query=regress The files belonging to this database system will be owned by user "postgres". This user must also own the server process. The database cluster will be initialized with this locale configuration: locale provider: libc LC_COLLATE: en_US.UTF-8 LC_CTYPE: en_US.UTF-8 LC_MESSAGES: C LC_MONETARY: en_US.UTF-8 LC_NUMERIC: C LC_TIME: en_US.UTF-8 The default database encoding has accordingly been set to "UTF8". The default text search configuration will be set to "english". Data page checksums are enabled. creating directory /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata ... ok creating subdirectories ... ok selecting dynamic shared memory implementation ... posix selecting default "max_connections" ... 100 selecting default "shared_buffers" ... 128MB selecting default time zone ... UTC creating configuration files ... ok running bootstrap script ... ok performing post-bootstrap initialization ... ok Sync to disk skipped. The data directory might become corrupt if the operating system crashes. Success. You can now start the database server using: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata -l logfile start # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/pgdata --log /tmp/cirrus-ci-build/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 59619 # Taking pg_basebackup my_backup from node "primary" # Running: pg_basebackup --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_primary_data/backup/my_backup --host /tmp/0Mo38G4hYg --port 32109 --checkpoint fast # Backup finished # Checking port 32110 # Found port 32110 Name: standby Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/archives Connection string: port=32110 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --log /tmp/cirrus-ci-build/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 59769 [19:46:19.909](3.712s) ok 1 - standby reached the same LSN as primary after WAIT FOR [19:46:20.969](1.060s) ok 2 - standby reached the same LSN as primary [19:46:21.020](0.051s) ok 3 - standby wrote WAL up to target LSN after WAIT FOR with MODE 'standby_write' [19:46:21.123](0.102s) ok 4 - standby flushed WAL up to target LSN after WAIT FOR with MODE 'standby_flush' [19:46:21.233](0.111s) ok 5 - primary flushed WAL up to target LSN after WAIT FOR with MODE 'primary_flush' [19:46:22.302](1.069s) ok 6 - get timeout on waiting for unreachable LSN [19:46:22.322](0.020s) ok 7 - WAIT FOR returns correct status after successful waiting [19:46:22.350](0.028s) ok 8 - WAIT FOR returns correct status after timeout [19:46:22.388](0.038s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:22.429](0.041s) ok 9 - canceled WAIT FOR LSN in subtransaction [19:46:22.449](0.019s) ok 10 - query cancel interrupted WAIT FOR LSN in subtransaction [19:46:22.449](0.001s) ok 11 - second WAIT FOR LSN timed out after savepoint rollback [19:46:22.452](0.003s) ok 12 - WAIT FOR LSN after savepoint rollback did not disconnect [19:46:22.465](0.013s) ok 13 - get an error when running standby_flush on the primary [19:46:22.484](0.018s) ok 14 - get an error when running primary_flush on the standby 1 [19:46:22.503](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/03011390 on primary done [19:46:23.549](1.045s) ok 16 - get an error when running within a function [19:46:23.575](0.026s) ok 17 - get an error when running within a procedure [19:46:23.605](0.030s) ok 18 - get an error when running within a DO block [19:46:23.656](0.051s) ok 19 - get error for negative timeout [19:46:23.673](0.017s) ok 20 - get error for unknown parameter [19:46:23.719](0.046s) ok 21 - get error for duplicate TIMEOUT parameter [19:46:23.738](0.018s) ok 22 - get error for duplicate NO_THROW parameter [19:46:23.761](0.024s) ok 23 - get syntax error when options specified without WITH keyword [19:46:23.776](0.014s) ok 24 - get syntax error for missing LSN [19:46:23.799](0.023s) ok 25 - get error for invalid LSN format [19:46:23.816](0.017s) ok 26 - get error for invalid timeout format [19:46:23.839](0.022s) ok 27 - WAIT FOR WITH clause syntax works correctly [19:46:23.963](0.125s) ok 28 - WAIT FOR WITH clause returns correct timeout status [19:46:23.983](0.019s) ok 29 - get error for invalid WITH clause option [19:46:24.000](0.018s) ok 30 - get error for invalid MODE value [19:46:24.196](0.196s) ok 31 - get error for duplicate MODE parameter [19:46:24.301](0.105s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:24.375](0.073s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:24.449](0.074s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:24.539](0.090s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:24.598](0.060s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:25.602](1.004s) ok 32 - multiple standby_replay waiters reported consistent data [19:46:26.135](0.533s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:26.155](0.020s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:26.186](0.031s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:26.211](0.024s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:26.231](0.021s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:26.592](0.361s) ok 33 - multiple standby_write waiters: standby wrote WAL up to target LSN [19:46:27.508](0.916s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:27.536](0.028s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:27.567](0.031s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:27.606](0.038s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:27.643](0.037s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:27.828](0.185s) ok 34 - multiple standby_flush waiters: standby flushed WAL up to target LSN [19:46:28.625](0.797s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:28.646](0.021s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:28.668](0.022s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:28.681](0.013s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:28.696](0.014s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:28.711](0.016s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:29.632](0.921s) ok 35 - mixed mode waiters: all modes completed and reached target LSN [19:46:30.098](0.466s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.128](0.030s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.164](0.036s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.284](0.121s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.336](0.052s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.421](0.085s) ok 36 - multiple primary_flush waiters: primary flushed WAL up to target LSN [19:46:30.567](0.146s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.611](0.044s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:30.660](0.049s) # 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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_standby_data/pgdata --log /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_standby.log promote waiting for server to promote.... done server promoted [19:46:31.180](0.520s) ok 37 - promotion interrupted all wait modes [19:46:31.219](0.038s) ok 38 - wait for already replayed LSN exits immediately even after promotion [19:46:31.243](0.024s) ok 39 - WAIT FOR returns correct status after standby promotion ### Stopping node "standby" using mode fast # Running: pg_ctl --pgdata /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/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 32111 # Found port 32111 Name: arc_primary Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/archives Connection string: port=32111 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log [19:46:31.479](0.236s) # initializing database system by running initdb # Running: initdb --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata --auth trust -c debug_copy_parse_plan_trees=on -c debug_write_read_parse_plan_trees=on -c debug_raw_expression_coverage_test=on -c debug_parallel_query=regress The files belonging to this database system will be owned by user "postgres". This user must also own the server process. The database cluster will be initialized with this locale configuration: locale provider: libc LC_COLLATE: en_US.UTF-8 LC_CTYPE: en_US.UTF-8 LC_MESSAGES: C LC_MONETARY: en_US.UTF-8 LC_NUMERIC: C LC_TIME: en_US.UTF-8 The default database encoding has accordingly been set to "UTF8". The default text search configuration will be set to "english". Data page checksums are enabled. creating directory /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata ... ok creating subdirectories ... ok selecting dynamic shared memory implementation ... posix selecting default "max_connections" ... 100 selecting default "shared_buffers" ... 128MB selecting default time zone ... UTC creating configuration files ... ok running bootstrap script ... ok performing post-bootstrap initialization ... ok Sync to disk skipped. The data directory might become corrupt if the operating system crashes. Success. You can now start the database server using: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata -l logfile start # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/pgdata --log /tmp/cirrus-ci-build/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 65231 # Taking pg_basebackup arc_backup from node "arc_primary" # Running: pg_basebackup --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_primary_data/backup/arc_backup --host /tmp/0Mo38G4hYg --port 32111 --checkpoint fast # Backup finished # Checking port 32112 # Found port 32112 Name: arc_standby Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/archives Connection string: port=32112 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_arc_standby_data/pgdata --log /tmp/cirrus-ci-build/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 65486 [19:46:34.311](2.832s) ok 40 - arc_standby has no walreceiver [19:46:34.327](0.016s) ok 41 - standby_write succeeds on archive-only standby (getter fallback) [19:46:34.348](0.021s) ok 42 - standby_flush succeeds on archive-only standby (getter fallback) [19:46:34.630](0.282s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:34.675](0.046s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:34.989](0.314s) ok 43 - standby_write waiter woken by replay on archive-only standby [19:46:34.990](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 /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/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 32113 # Found port 32113 Name: rcv_primary Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/archives Connection string: port=32113 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log [19:46:35.241](0.251s) # initializing database system by running initdb # Running: initdb --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --auth trust -c debug_copy_parse_plan_trees=on -c debug_write_read_parse_plan_trees=on -c debug_raw_expression_coverage_test=on -c debug_parallel_query=regress The files belonging to this database system will be owned by user "postgres". This user must also own the server process. The database cluster will be initialized with this locale configuration: locale provider: libc LC_COLLATE: en_US.UTF-8 LC_CTYPE: en_US.UTF-8 LC_MESSAGES: C LC_MONETARY: en_US.UTF-8 LC_NUMERIC: C LC_TIME: en_US.UTF-8 The default database encoding has accordingly been set to "UTF8". The default text search configuration will be set to "english". Data page checksums are enabled. creating directory /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata ... ok creating subdirectories ... ok selecting dynamic shared memory implementation ... posix selecting default "max_connections" ... 100 selecting default "shared_buffers" ... 128MB selecting default time zone ... UTC creating configuration files ... ok running bootstrap script ... ok performing post-bootstrap initialization ... ok Sync to disk skipped. The data directory might become corrupt if the operating system crashes. Success. You can now start the database server using: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata -l logfile start # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --log /tmp/cirrus-ci-build/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 66563 # Taking pg_basebackup rcv_backup from node "rcv_primary" # Running: pg_basebackup --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/backup/rcv_backup --host /tmp/0Mo38G4hYg --port 32113 --checkpoint fast # Backup finished # Checking port 32114 # Found port 32114 Name: rcv_standby Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/archives Connection string: port=32114 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --log /tmp/cirrus-ci-build/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 66715 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 /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_standby_data/pgdata --log /tmp/cirrus-ci-build/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 66965 [19:46:39.154](3.913s) ok 45 - replay sits ahead of initial walreceiver flush position [19:46:39.217](0.063s) ok 46 - initial walreceiver flush position is segment-aligned [19:46:39.233](0.016s) ok 47 - standby_write succeeds for already-replayed LSN after standby restart [19:46:39.284](0.052s) ok 48 - standby_flush succeeds for already-replayed LSN after standby restart ### Starting node "rcv_primary" # Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_rcv_primary_data/pgdata --log /tmp/cirrus-ci-build/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 67178 Waiting for replication conn rcv_standby's replay_lsn to pass 0/04002E58 on rcv_primary done [19:46:40.152](0.867s) ok 49 - standby_replay: target == current succeeds [19:46:40.187](0.035s) ok 50 - standby_replay: target == current - 1 succeeds [19:46:40.720](0.533s) ok 51 - standby_replay: target == current + 1 times out [19:46:40.832](0.112s) ok 52 - standby_flush boundary is not masked by replay floor [19:46:40.969](0.138s) ok 53 - standby_flush: target == current succeeds [19:46:41.029](0.060s) ok 54 - standby_flush: target == current - 1 succeeds [19:46:41.557](0.528s) ok 55 - standby_flush: target == current + 1 times out [19:46:41.643](0.087s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [19:46:41.805](0.161s) ok 56 - standby_replay: waiter at current + 1 wakes when replay advances ### Stopping node "rcv_standby" using mode fast # Running: pg_ctl --pgdata /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/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 32115 # Found port 32115 Name: tl_primary Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/archives Connection string: port=32115 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log [19:46:42.044](0.240s) # initializing database system by running initdb # Running: initdb --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata --auth trust -c debug_copy_parse_plan_trees=on -c debug_write_read_parse_plan_trees=on -c debug_raw_expression_coverage_test=on -c debug_parallel_query=regress The files belonging to this database system will be owned by user "postgres". This user must also own the server process. The database cluster will be initialized with this locale configuration: locale provider: libc LC_COLLATE: en_US.UTF-8 LC_CTYPE: en_US.UTF-8 LC_MESSAGES: C LC_MONETARY: en_US.UTF-8 LC_NUMERIC: C LC_TIME: en_US.UTF-8 The default database encoding has accordingly been set to "UTF8". The default text search configuration will be set to "english". Data page checksums are enabled. creating directory /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata ... ok creating subdirectories ... ok selecting dynamic shared memory implementation ... posix selecting default "max_connections" ... 100 selecting default "shared_buffers" ... 128MB selecting default time zone ... UTC creating configuration files ... ok running bootstrap script ... ok performing post-bootstrap initialization ... ok Sync to disk skipped. The data directory might become corrupt if the operating system crashes. Success. You can now start the database server using: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata -l logfile start # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/pgdata --log /tmp/cirrus-ci-build/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 68454 # Taking pg_basebackup tl_backup from node "tl_primary" # Running: pg_basebackup --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_primary_data/backup/tl_backup --host /tmp/0Mo38G4hYg --port 32115 --checkpoint fast # Backup finished # Checking port 32116 # Found port 32116 Name: tl_standby1 Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/archives Connection string: port=32116 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --log /tmp/cirrus-ci-build/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 68604 # Taking pg_basebackup tl_backup2 from node "tl_standby1" # Running: pg_basebackup --no-sync --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/backup/tl_backup2 --host /tmp/0Mo38G4hYg --port 32116 --checkpoint fast # Backup finished # Checking port 32117 # Found port 32117 Name: tl_standby2 Version: 19devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/archives Connection string: port=32117 host=/tmp/0Mo38G4hYg Log file: /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby2_data/pgdata --log /tmp/cirrus-ci-build/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 68675 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 [19:46:45.471](3.426s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } ### Promoting node "tl_standby1" # Running: pg_ctl --pgdata /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/data/t_049_wait_for_lsn_tl_standby1_data/pgdata --log /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_standby1.log promote waiting for server to promote.... done server promoted [19:46:45.766](0.296s) 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 /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/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 /tmp/cirrus-ci-build/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" [19:46:46.095](0.329s) 1..57