# Checking port 60136 # Found port 60136 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/archives Connection string: port=60136 host=/tmp/TC8kTgpUaR Log file: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_primary.log # Checking port 60137 # Found port 60137 Name: standby Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_standby_data/archives Connection string: port=60137 host=/tmp/TC8kTgpUaR Log file: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_standby.log # Checking port 60138 # Found port 60138 Name: cascading_standby Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_cascading_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_cascading_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_cascading_standby_data/archives Connection string: port=60138 host=/tmp/TC8kTgpUaR Log file: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_cascading_standby.log # Checking port 60139 # Found port 60139 Name: subscriber Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/archives Connection string: port=60139 host=/tmp/TC8kTgpUaR Log file: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_subscriber.log [02:10:34.888](0.023s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/pgdata ### Enabling WAL archiving for node "primary" Name: primary Version: 17devel Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/archives Connection string: port=60136 host=/tmp/TC8kTgpUaR Log file: /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_primary.log ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 52276 [02:10:35.132](0.245s) ok 1 - Physical slot reports conflict_reason as NULL # Taking pg_basebackup b1 from node "primary" # Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/backup/b1 -h /tmp/TC8kTgpUaR -p 60136 --checkpoint fast --no-sync # Backup finished # Initializing node "standby" from backup "b1" of node "primary" ### Enabling streaming replication for node "standby" ### Enabling WAL restore for node "standby" ### Starting node "standby" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_standby.log -o --cluster-name=standby start waiting for server to start.... done server started # Postmaster PID for node "standby" is 52368 Waiting for replication conn standby's replay_lsn to pass 0/3019E10 on primary done [02:10:35.533](0.401s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/pgdata ### Starting node "subscriber" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/log/035_standby_logical_decoding_subscriber.log -o --cluster-name=subscriber start waiting for server to start.... done server started # Postmaster PID for node "subscriber" is 52424 [02:10:35.795](0.262s) ok 2 - behaves_ok_inactiveslot on standby created [02:10:35.994](0.199s) ok 3 - behaves_ok_activeslot on standby created Waiting for replication conn standby's replay_lsn to pass 0/3026718 on primary done [02:10:36.047](0.053s) ok 4 - Decoding produced 14 rows (2 BEGIN/COMMIT and 10 rows) Waiting for replication conn standby's replay_lsn to pass 0/3026848 on primary done [02:10:36.088](0.040s) ok 5 - got expected output from SQL decoding session Waiting for replication conn standby's replay_lsn to pass 0/30273F8 on primary done [02:10:36.140](0.052s) ok 6 - got same expected output from pg_recvlogical decoding session [02:10:36.160](0.020s) ok 7 - pg_recvlogical acknowledged changes Waiting for replication conn standby's replay_lsn to pass 0/34710A8 on primary done [02:10:36.225](0.065s) ok 8 - replaying logical slot from another database fails Waiting for replication conn standby's replay_lsn to pass 0/34988E0 on primary done Waiting for all subscriptions in "subscriber" to synchronize data Waiting for replication conn tap_sub's replay_lsn to pass 0/3498918 on standby done done Waiting for replication conn standby's replay_lsn to pass 0/3498EA8 on primary done Waiting for replication conn tap_sub's replay_lsn to pass 0/3498EA8 on standby done [02:10:36.718](0.493s) ok 9 - check replicated inserts after subscription on standby #### Begin standard error psql::1: NOTICE: dropped replication slot "tap_sub" on publisher #### End standard error ### Stopping node "subscriber" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_subscriber_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "subscriber" [02:10:36.995](0.277s) ok 10 - vacuum_full_inactiveslot on standby created [02:10:37.195](0.199s) ok 11 - vacuum_full_activeslot on standby created ### Reloading node "standby" # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_standby_data/pgdata reload server signaled #### Begin standard error psql::1: INFO: vacuuming "pg_catalog.pg_class" psql::1: INFO: "pg_catalog.pg_class": found 5 removable, 420 nonremovable row versions in 14 pages DETAIL: 0 dead row versions cannot be removed yet. CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s. #### End standard error Waiting for replication conn standby's replay_lsn to pass 0/34C9850 on primary [02:14:06.197](209.002s) # poll_query_until timed out executing this query: # SELECT '0/34C9850' <= replay_lsn AND state = 'streaming' # FROM pg_catalog.pg_stat_replication # WHERE application_name IN ('standby', 'walreceiver') # expecting this output: # t # last actual query output: # # with stderr: timed out waiting for catchup at /tmp/cirrus-ci-build/src/test/recovery/t/035_standby_logical_decoding.pl line 522. # Postmaster PID for node "primary" is 52276 ### Stopping node "primary" using mode immediate # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/035_standby_logical_decoding/data/t_035_standby_logical_decoding_primary_data/pgdata -m immediate stop waiting for server to shut down.... done server stopped # No postmaster PID for node "primary" # No postmaster PID for node "standby" # No postmaster PID for node "cascading_standby" # No postmaster PID for node "subscriber" [02:14:06.303](0.106s) # Tests were run but no plan was declared and done_testing() was not seen. [02:14:06.303](0.000s) # Looks like your test exited with 29 just after 11.