# Running: pg_createsubscriber --help [22:54:33.440](0.019s) ok 1 - pg_createsubscriber --help exit code 0 [22:54:33.441](0.001s) ok 2 - pg_createsubscriber --help goes to stdout [22:54:33.441](0.000s) ok 3 - pg_createsubscriber --help nothing to stderr [22:54:33.442](0.000s) ok 4 - pg_createsubscriber --help maximum line length # Running: pg_createsubscriber --version [22:54:33.447](0.005s) ok 5 - pg_createsubscriber --version exit code 0 [22:54:33.448](0.001s) ok 6 - pg_createsubscriber --version goes to stdout [22:54:33.448](0.000s) ok 7 - pg_createsubscriber --version nothing to stderr # Running: pg_createsubscriber --not-a-valid-option [22:54:33.453](0.005s) ok 8 - pg_createsubscriber with invalid option nonzero exit code [22:54:33.454](0.000s) ok 9 - pg_createsubscriber with invalid option prints error message # Running: pg_createsubscriber pg_createsubscriber: error: no subscriber data directory specified pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information. [22:54:33.468](0.014s) ok 10 - no subscriber data directory specified # Running: pg_createsubscriber --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb pg_createsubscriber: error: no publisher connection string specified pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information. [22:54:33.483](0.015s) ok 11 - no publisher connection string specified # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb --publisher-server port=5432 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: no database was specified pg_createsubscriber: error: no database name specified pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information. [22:54:33.498](0.015s) ok 12 - no database name specified # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb --publisher-server port=5432 --database pg1 --database pg1 pg_createsubscriber: error: duplicate database "pg1" [22:54:33.513](0.015s) ok 13 - duplicate database name # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb --publisher-server port=5432 --publication foo1 --publication foo1 --database pg1 --database pg2 pg_createsubscriber: error: duplicate publication "foo1" [22:54:33.528](0.015s) ok 14 - duplicate publication name # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb --publisher-server port=5432 --publication foo1 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: error: wrong number of publication names pg_createsubscriber: hint: Number of publication names (1) must match number of database names (2). [22:54:33.542](0.015s) ok 15 - wrong number of publication names # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb --publisher-server port=5432 --publication foo1 --publication foo2 --subscription bar1 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: error: wrong number of subscription names pg_createsubscriber: hint: Number of subscription names (1) must match number of database names (2). [22:54:33.556](0.014s) ok 16 - wrong number of subscription names # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_6jdb --publisher-server port=5432 --publication foo1 --publication foo2 --subscription bar1 --subscription bar2 --replication-slot baz1 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: error: wrong number of replication slot names pg_createsubscriber: hint: Number of replication slot names (1) must match number of database names (2). [22:54:33.572](0.016s) ok 17 - wrong number of replication slot names # Checking port 51180 # Found port 51180 Name: node_p Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/archives Connection string: port=51180 host=/tmp/KNgqluPMhm Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_p.log [22:54:33.577](0.005s) # 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/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata ### Starting node "node_p" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_p.log -o --cluster-name=node_p start waiting for server to start.... done server started # Postmaster PID for node "node_p" is 39362 # Checking port 51181 # Found port 51181 Name: node_f Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/archives Connection string: port=51181 host=/tmp/KNgqluPMhm Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_f.log [22:54:33.738](0.161s) # initializing database system by running initdb # Running: initdb -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata -A trust -N 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: en_US.UTF-8 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 disabled. creating directory /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_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 ... 2024-03-27 22:54:34.122 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.391 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.395 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.396 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.398 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.400 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.400 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.401 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.402 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.402 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.403 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-27 22:54:34.519 UTC [39412] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 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/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata -l logfile start # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata #### Begin standard error psql::2: WARNING: databases created by regression test cases should have names including "regression" psql::3: WARNING: databases created by regression test cases should have names including "regression" #### End standard error # Taking pg_basebackup backup_1 from node "node_p" # Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/backup/backup_1 -h /tmp/KNgqluPMhm -p 51180 --checkpoint fast --no-sync # Backup finished # Checking port 51182 # Found port 51182 Name: node_s Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/archives Connection string: port=51182 host=/tmp/KNgqluPMhm Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_s.log # Initializing node "node_s" from backup "backup_1" of node "node_p" ### Enabling streaming replication for node "node_s" ### Starting node "node_s" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start waiting for server to start.... done server started # Postmaster PID for node "node_s" is 39570 # Checking port 51183 # Found port 51183 Name: node_t Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/archives Connection string: port=51183 host=/tmp/KNgqluPMhm Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_t.log # Initializing node "node_t" from backup "backup_1" of node "node_p" ### Enabling streaming replication for node "node_t" ### Starting node "node_t" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_t.log -o --cluster-name=node_t start waiting for server to start.... done server started # Postmaster PID for node "node_t" is 39603 ### Promoting node "node_t" # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_t.log promote waiting for server to promote.... done server promoted ### Stopping node "node_t" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "node_t" # Running: pg_createsubscriber --verbose --dry-run --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata --publisher-server port=51180 host=/tmp/KNgqluPMhm dbname='pg1' --socket-directory /tmp/KNgqluPMhm --subscriber-port 51183 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: checking if directory "/tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata" is a cluster data directory pg_createsubscriber: getting system identifier from publisher WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree pg_createsubscriber: system identifier is 7351175905916808405 on publisher pg_createsubscriber: getting system identifier from subscriber pg_createsubscriber: system identifier is 7351175905916808405 on subscriber pg_createsubscriber: starting the standby with command-line options 2024-03-27 22:54:35.646 UTC [39663][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-27 22:54:35.646 UTC [39663][postmaster] LOG: listening on Unix socket "/tmp/KNgqluPMhm/.s.PGSQL.51183" 2024-03-27 22:54:35.647 UTC [39668][startup] LOG: database system was shut down at 2024-03-27 22:54:35 UTC 2024-03-27 22:54:35.649 UTC [39663][postmaster] LOG: database system is ready to accept connections pg_createsubscriber: server was started pg_createsubscriber: checking settings on subscriber 2024-03-27 22:54:35.747 UTC [39682][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-27 22:54:35.747 UTC [39682][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/pg_hba.conf:117) 2024-03-27 22:54:35.747 UTC [39682][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl 2024-03-27 22:54:35.752 UTC [39682][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG: statement: SELECT pg_catalog.set_config('search_path', '', false); 2024-03-27 22:54:35.753 UTC [39682][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG: statement: SELECT pg_catalog.pg_is_in_recovery() pg_createsubscriber: error: target server must be a standby 2024-03-27 22:54:35.753 UTC [39682][client backend] [040_pg_createsubscriber.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=pg1 host=[local] 2024-03-27 22:54:35.755 UTC [39663][postmaster] LOG: received fast shutdown request 2024-03-27 22:54:35.755 UTC [39663][postmaster] LOG: aborting any active transactions 2024-03-27 22:54:35.757 UTC [39663][postmaster] LOG: background worker "logical replication launcher" (PID 39671) exited with exit code 1 2024-03-27 22:54:35.757 UTC [39666][checkpointer] LOG: shutting down 2024-03-27 22:54:35.757 UTC [39666][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-27 22:54:35.757 UTC [39666][checkpointer] LOG: checkpoint complete: wrote 6 buffers (4.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=17 kB, estimate=17 kB; lsn=0/30046A0, redo lsn=0/30046A0 2024-03-27 22:54:35.759 UTC [39663][postmaster] LOG: database system is shut down pg_createsubscriber: server was stopped [22:54:35.954](2.216s) ok 18 - target server is not in recovery # Running: pg_createsubscriber --verbose --dry-run --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=51180 host=/tmp/KNgqluPMhm dbname='pg1' --socket-directory /tmp/KNgqluPMhm --subscriber-port 51182 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: checking if directory "/tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory pg_createsubscriber: getting system identifier from publisher WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree pg_createsubscriber: system identifier is 7351175905916808405 on publisher pg_createsubscriber: getting system identifier from subscriber pg_createsubscriber: system identifier is 7351175905916808405 on subscriber pg_createsubscriber: error: standby is up and running pg_createsubscriber: hint: Stop the standby and try again. [22:54:35.969](0.015s) ok 19 - standby is up and running # Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata --publisher-server port=51180 host=/tmp/KNgqluPMhm dbname='pg1' --socket-directory /tmp/KNgqluPMhm --subscriber-port 51181 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: checking if directory "/tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata" is a cluster data directory pg_createsubscriber: getting system identifier from publisher WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree pg_createsubscriber: system identifier is 7351175905916808405 on publisher pg_createsubscriber: getting system identifier from subscriber pg_createsubscriber: system identifier is 7351180441365301736 on subscriber pg_createsubscriber: error: subscriber data directory is not a copy of the source database cluster [22:54:35.984](0.015s) ok 20 - subscriber data directory is not a copy of the source database cluster # Taking pg_basebackup backup_2 from node "node_s" # Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/backup/backup_2 -h /tmp/KNgqluPMhm -p 51182 --checkpoint fast --no-sync # Backup finished # Checking port 51184 # Found port 51184 Name: node_c Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/archives Connection string: port=51184 host=/tmp/KNgqluPMhm Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_c.log # Initializing node "node_c" from backup "backup_2" of node "node_s" ### Enabling streaming replication for node "node_c" # Running: pg_createsubscriber --verbose --dry-run --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata --publisher-server port=51182 host=/tmp/KNgqluPMhm dbname='pg1' --socket-directory /tmp/KNgqluPMhm --subscriber-port 51184 --database pg1 --database pg2 pg_createsubscriber: validating connection string on publisher pg_createsubscriber: validating connection string on subscriber pg_createsubscriber: checking if directory "/tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata" is a cluster data directory pg_createsubscriber: getting system identifier from publisher WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree pg_createsubscriber: system identifier is 7351175905916808405 on publisher pg_createsubscriber: getting system identifier from subscriber pg_createsubscriber: system identifier is 7351175905916808405 on subscriber pg_createsubscriber: starting the standby with command-line options 2024-03-27 22:54:36.241 UTC [39763][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-27 22:54:36.241 UTC [39763][postmaster] LOG: listening on Unix socket "/tmp/KNgqluPMhm/.s.PGSQL.51184" 2024-03-27 22:54:36.242 UTC [39768][startup] LOG: database system was interrupted while in recovery at log time 2024-03-27 22:54:34 UTC 2024-03-27 22:54:36.242 UTC [39768][startup] HINT: If this has occurred more than once some data might be corrupted and you might need to choose an earlier recovery target. 2024-03-27 22:54:36.243 UTC [39768][startup] LOG: starting backup recovery with redo LSN 0/2000028, checkpoint LSN 0/2000080, on timeline ID 1 2024-03-27 22:54:36.243 UTC [39768][startup] LOG: entering standby mode 2024-03-27 22:54:36.243 UTC [39768][startup] LOG: redo starts at 0/2000028 2024-03-27 22:54:36.243 UTC [39768][startup] LOG: completed backup recovery with redo LSN 0/2000028 and end LSN 0/2000120 2024-03-27 22:54:36.243 UTC [39768][startup] LOG: consistent recovery state reached at 0/3000000 2024-03-27 22:54:36.243 UTC [39763][postmaster] LOG: database system is ready to accept read-only connections 2024-03-27 22:54:36.247 UTC [39769][walreceiver] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 pg_createsubscriber: server was started pg_createsubscriber: checking settings on subscriber 2024-03-27 22:54:36.338 UTC [39780][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-27 22:54:36.339 UTC [39780][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/pg_hba.conf:117) 2024-03-27 22:54:36.339 UTC [39780][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl 2024-03-27 22:54:36.344 UTC [39780][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG: statement: SELECT pg_catalog.set_config('search_path', '', false); 2024-03-27 22:54:36.344 UTC [39780][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG: statement: SELECT pg_catalog.pg_is_in_recovery() 2024-03-27 22:54:36.345 UTC [39780][client backend] [040_pg_createsubscriber.pl][0/4:0] LOG: statement: SELECT setting FROM pg_catalog.pg_settings WHERE name IN ('max_logical_replication_workers', 'max_replication_slots', 'max_worker_processes', 'primary_slot_name') ORDER BY name 2024-03-27 22:54:36.346 UTC [39780][client backend] [040_pg_createsubscriber.pl][0/4:0] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree pg_createsubscriber: checking settings on publisher 2024-03-27 22:54:36.348 UTC [39780][client backend] [040_pg_createsubscriber.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=postgres database=pg1 host=[local] pg_createsubscriber: error: primary server cannot be in recovery 2024-03-27 22:54:36.353 UTC [39763][postmaster] LOG: received fast shutdown request 2024-03-27 22:54:36.353 UTC [39763][postmaster] LOG: aborting any active transactions 2024-03-27 22:54:36.353 UTC [39769][walreceiver] FATAL: terminating walreceiver process due to administrator command 2024-03-27 22:54:36.355 UTC [39766][checkpointer] LOG: shutting down 2024-03-27 22:54:36.356 UTC [39763][postmaster] LOG: database system is shut down pg_createsubscriber: server was stopped [22:54:36.539](0.555s) ok 21 - primary server is in recovery Waiting for replication conn node_s's replay_lsn to pass 0/3000110 on node_p [22:58:14.046](217.507s) # poll_query_until timed out executing this query: # SELECT '0/3000110' <= replay_lsn AND state = 'streaming' # FROM pg_catalog.pg_stat_replication # WHERE application_name IN ('node_s', 'walreceiver') # expecting this output: # t # last actual query output: # t # with stderr: # WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree timed out waiting for catchup at /tmp/cirrus-ci-build/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 201. # Postmaster PID for node "node_p" is 39362 ### Stopping node "node_p" using mode immediate # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata -m immediate stop waiting for server to shut down.... done server stopped # No postmaster PID for node "node_p" # No postmaster PID for node "node_f" # Postmaster PID for node "node_s" is 39570 ### Stopping node "node_s" using mode immediate # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata -m immediate stop waiting for server to shut down.... done server stopped # No postmaster PID for node "node_s" # No postmaster PID for node "node_t" # No postmaster PID for node "node_c" [22:58:14.269](0.223s) # Tests were run but no plan was declared and done_testing() was not seen. [22:58:14.269](0.000s) # Looks like your test exited with 29 just after 21.