# Checking port 15865 # Found port 15865 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=15865 host=/tmp/JTUUSVVY45 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_primary.log [14:51:26.440](0.049s) # 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 60260 # 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/JTUUSVVY45 --port 15865 --checkpoint fast # Backup finished # Checking port 15866 # Found port 15866 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=15866 host=/tmp/JTUUSVVY45 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 60396 [14:51:29.632](3.192s) ok 1 - standby reached the same LSN as primary after WAIT FOR [14:51:30.700](1.068s) ok 2 - standby reached the same LSN as primary [14:51:30.776](0.076s) ok 3 - standby wrote WAL up to target LSN after WAIT FOR with MODE 'standby_write' [14:51:30.893](0.117s) ok 4 - standby flushed WAL up to target LSN after WAIT FOR with MODE 'standby_flush' [14:51:30.962](0.069s) ok 5 - primary flushed WAL up to target LSN after WAIT FOR with MODE 'primary_flush' [14:51:32.034](1.072s) ok 6 - get timeout on waiting for unreachable LSN [14:51:32.045](0.010s) ok 7 - WAIT FOR returns correct status after successful waiting [14:51:32.081](0.036s) ok 8 - WAIT FOR returns correct status after timeout [14:51:32.117](0.036s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:32.153](0.036s) ok 9 - canceled WAIT FOR LSN in subtransaction [14:51:32.166](0.013s) ok 10 - query cancel interrupted WAIT FOR LSN in subtransaction [14:51:32.167](0.001s) ok 11 - second WAIT FOR LSN timed out after savepoint rollback [14:51:32.167](0.000s) ok 12 - WAIT FOR LSN after savepoint rollback did not disconnect [14:51:32.180](0.012s) ok 13 - get an error when running standby_flush on the primary [14:51:32.197](0.017s) ok 14 - get an error when running primary_flush on the standby 1 [14:51:32.220](0.024s) 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 [14:51:33.260](1.040s) ok 16 - get an error when running within a function [14:51:33.275](0.015s) ok 17 - get an error when running within a procedure [14:51:33.308](0.033s) ok 18 - get an error when running within a DO block [14:51:33.343](0.035s) ok 19 - get error for negative timeout [14:51:33.364](0.021s) ok 20 - get error for unknown parameter [14:51:33.377](0.013s) ok 21 - get error for duplicate TIMEOUT parameter [14:51:33.392](0.016s) ok 22 - get error for duplicate NO_THROW parameter [14:51:33.405](0.013s) ok 23 - get syntax error when options specified without WITH keyword [14:51:33.423](0.017s) ok 24 - get syntax error for missing LSN [14:51:33.464](0.041s) ok 25 - get error for invalid LSN format [14:51:33.478](0.014s) ok 26 - get error for invalid timeout format [14:51:33.495](0.017s) ok 27 - WAIT FOR WITH clause syntax works correctly [14:51:33.612](0.117s) ok 28 - WAIT FOR WITH clause returns correct timeout status [14:51:33.654](0.042s) ok 29 - get error for invalid WITH clause option [14:51:33.668](0.014s) ok 30 - get error for invalid MODE value [14:51:33.678](0.011s) ok 31 - get error for duplicate MODE parameter [14:51:33.769](0.091s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:33.821](0.052s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:33.937](0.116s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:34.008](0.071s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:34.068](0.060s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:35.043](0.975s) ok 32 - multiple standby_replay waiters reported consistent data [14:51:35.473](0.430s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:35.486](0.013s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:35.504](0.018s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:35.528](0.023s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:35.541](0.013s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:35.692](0.151s) ok 33 - multiple standby_write waiters: standby wrote WAL up to target LSN [14:51:36.885](1.193s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:36.923](0.038s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:36.959](0.037s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:37.000](0.040s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:37.039](0.039s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:37.212](0.173s) ok 34 - multiple standby_flush waiters: standby flushed WAL up to target LSN [14:51:37.959](0.747s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:37.982](0.023s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:38.008](0.026s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:38.033](0.025s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:38.052](0.019s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:38.067](0.014s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:38.990](0.923s) ok 35 - mixed mode waiters: all modes completed and reached target LSN [14:51:39.494](0.505s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.527](0.032s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.591](0.064s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.608](0.018s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.639](0.030s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.681](0.042s) ok 36 - multiple primary_flush waiters: primary flushed WAL up to target LSN [14:51:39.778](0.097s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.795](0.017s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:39.820](0.024s) # 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 [14:51:40.409](0.589s) ok 37 - promotion interrupted all wait modes [14:51:40.423](0.014s) ok 38 - wait for already replayed LSN exits immediately even after promotion [14:51:40.452](0.029s) 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 15867 # Found port 15867 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=15867 host=/tmp/JTUUSVVY45 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_arc_primary.log [14:51:40.627](0.175s) # 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 66270 # 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/JTUUSVVY45 --port 15867 --checkpoint fast # Backup finished # Checking port 15868 # Found port 15868 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=15868 host=/tmp/JTUUSVVY45 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 66535 [14:51:43.349](2.722s) ok 40 - arc_standby has no walreceiver [14:51:43.364](0.014s) ok 41 - standby_write succeeds on archive-only standby (getter fallback) [14:51:43.401](0.038s) ok 42 - standby_flush succeeds on archive-only standby (getter fallback) [14:51:43.628](0.226s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:43.645](0.017s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:44.038](0.394s) ok 43 - standby_write waiter woken by replay on archive-only standby [14:51:44.039](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 /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 15869 # Found port 15869 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=15869 host=/tmp/JTUUSVVY45 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_rcv_primary.log [14:51:44.281](0.242s) # 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 67403 # 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/JTUUSVVY45 --port 15869 --checkpoint fast # Backup finished # Checking port 15870 # Found port 15870 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=15870 host=/tmp/JTUUSVVY45 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 67565 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 67796 [14:51:47.437](3.156s) ok 45 - replay sits ahead of initial walreceiver flush position [14:51:47.516](0.079s) ok 46 - initial walreceiver flush position is segment-aligned [14:51:47.555](0.039s) ok 47 - standby_write succeeds for already-replayed LSN after standby restart [14:51:47.572](0.016s) 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 68008 Waiting for replication conn rcv_standby's replay_lsn to pass 0/04002E58 on rcv_primary done [14:51:48.375](0.803s) ok 49 - standby_replay: target == current succeeds [14:51:48.413](0.038s) ok 50 - standby_replay: target == current - 1 succeeds [14:51:48.960](0.547s) ok 51 - standby_replay: target == current + 1 times out [14:51:49.107](0.146s) ok 52 - standby_flush boundary is not masked by replay floor [14:51:49.195](0.088s) ok 53 - standby_flush: target == current succeeds [14:51:49.207](0.012s) ok 54 - standby_flush: target == current - 1 succeeds [14:51:49.733](0.525s) ok 55 - standby_flush: target == current + 1 times out [14:51:49.826](0.094s) # connect output: # { # 'stderr' => 'background_psql: ready # ', # 'stdout' => 'background_psql: ready # ' # } [14:51:50.196](0.370s) 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 15871 # Found port 15871 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=15871 host=/tmp/JTUUSVVY45 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/049_wait_for_lsn/log/049_wait_for_lsn_tl_primary.log [14:51:50.429](0.232s) # 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 69431 # 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/JTUUSVVY45 --port 15871 --checkpoint fast # Backup finished # Checking port 15872 # Found port 15872 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=15872 host=/tmp/JTUUSVVY45 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 69555 # 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/JTUUSVVY45 --port 15872 --checkpoint fast # Backup finished # Checking port 15873 # Found port 15873 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=15873 host=/tmp/JTUUSVVY45 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 69653 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:51:53.516](3.087s) # 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 [14:51:53.829](0.314s) 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" [14:51:54.186](0.357s) 1..57