# Running: pg_createsubscriber --help
[23:54:46.003](0.017s) ok 1 - pg_createsubscriber --help exit code 0
[23:54:46.004](0.000s) ok 2 - pg_createsubscriber --help goes to stdout
[23:54:46.004](0.000s) ok 3 - pg_createsubscriber --help nothing to stderr
[23:54:46.004](0.000s) ok 4 - pg_createsubscriber --help maximum line length
# Running: pg_createsubscriber --version
[23:54:46.009](0.005s) ok 5 - pg_createsubscriber --version exit code 0
[23:54:46.009](0.000s) ok 6 - pg_createsubscriber --version goes to stdout
[23:54:46.010](0.000s) ok 7 - pg_createsubscriber --version nothing to stderr
# Running: pg_createsubscriber --not-a-valid-option
[23:54:46.014](0.005s) ok 8 - pg_createsubscriber with invalid option nonzero exit code
[23:54:46.015](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.
[23:54:46.028](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_1TPC
pg_createsubscriber: error: no publisher connection string specified
pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information.
[23:54:46.043](0.014s) 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_1TPC --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.
[23:54:46.057](0.014s) 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_1TPC --publisher-server port=5432 --database pg1 --database pg1
pg_createsubscriber: error: duplicate database "pg1"
[23:54:46.071](0.014s) ok 13 - duplicate database name
# Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_1TPC --publisher-server port=5432 --publication foo1 --publication foo1 --database pg1 --database pg2
pg_createsubscriber: error: duplicate publication "foo1"
[23:54:46.085](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_1TPC --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).
[23:54:46.099](0.014s) 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_1TPC --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).
[23:54:46.113](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_1TPC --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).
[23:54:46.127](0.014s) ok 17 - wrong number of replication slot names
# Checking port 61521
# Found port 61521
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=61521 host=/tmp/3wchaz7nKX
Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_p.log
[23:54:46.131](0.004s) # 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 38730
# Checking port 61522
# Found port 61522
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=61522 host=/tmp/3wchaz7nKX
Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_f.log
[23:54:46.286](0.156s) # 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-26 23:54:46.653 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.914 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.917 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.919 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.920 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.922 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.923 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.923 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.924 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.925 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:46.925 UTC [38777] WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
2024-03-26 23:54:47.043 UTC [38777] 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:<stdin>:2: WARNING:  databases created by regression test cases should have names including "regression"
psql:<stdin>: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/3wchaz7nKX -p 61521 --checkpoint fast --no-sync
# Backup finished
# Checking port 61523
# Found port 61523
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=61523 host=/tmp/3wchaz7nKX
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 38925
# Checking port 61524
# Found port 61524
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=61524 host=/tmp/3wchaz7nKX
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 38962
### 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=61521 host=/tmp/3wchaz7nKX dbname='pg1' --socket-directory /tmp/3wchaz7nKX --subscriber-port 61524 --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 7350820336669441229 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7350820336669441229 on subscriber
pg_createsubscriber: starting the standby with command-line options
2024-03-26 23:54:48.093 UTC [39021][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-26 23:54:48.093 UTC [39021][postmaster] LOG:  listening on Unix socket "/tmp/3wchaz7nKX/.s.PGSQL.61524"
2024-03-26 23:54:48.094 UTC [39024][startup] LOG:  database system was shut down at 2024-03-26 23:54:47 UTC
2024-03-26 23:54:48.096 UTC [39021][postmaster] LOG:  database system is ready to accept connections
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-03-26 23:54:48.194 UTC [39042][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 23:54:48.194 UTC [39042][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-26 23:54:48.194 UTC [39042][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-26 23:54:48.199 UTC [39042][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-26 23:54:48.199 UTC [39042][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-26 23:54:48.199 UTC [39042][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=pg1 host=[local]
2024-03-26 23:54:48.201 UTC [39021][postmaster] LOG:  received fast shutdown request
2024-03-26 23:54:48.201 UTC [39021][postmaster] LOG:  aborting any active transactions
2024-03-26 23:54:48.201 UTC [39021][postmaster] LOG:  background worker "logical replication launcher" (PID 39027) exited with exit code 1
2024-03-26 23:54:48.202 UTC [39022][checkpointer] LOG:  shutting down
2024-03-26 23:54:48.202 UTC [39022][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-26 23:54:48.202 UTC [39022][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-26 23:54:48.203 UTC [39021][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
[23:54:48.404](2.117s) 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=61521 host=/tmp/3wchaz7nKX dbname='pg1' --socket-directory /tmp/3wchaz7nKX --subscriber-port 61523 --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 7350820336669441229 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7350820336669441229 on subscriber
pg_createsubscriber: error: standby is up and running
pg_createsubscriber: hint: Stop the standby and try again.
[23:54:48.418](0.014s) 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=61521 host=/tmp/3wchaz7nKX dbname='pg1' --socket-directory /tmp/3wchaz7nKX --subscriber-port 61522 --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 7350820336669441229 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7350824872022820714 on subscriber
pg_createsubscriber: error: subscriber data directory is not a copy of the source database cluster
[23:54:48.433](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/3wchaz7nKX -p 61523 --checkpoint fast --no-sync
# Backup finished
# Checking port 61525
# Found port 61525
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=61525 host=/tmp/3wchaz7nKX
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=61523 host=/tmp/3wchaz7nKX dbname='pg1' --socket-directory /tmp/3wchaz7nKX --subscriber-port 61525 --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 7350820336669441229 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7350820336669441229 on subscriber
pg_createsubscriber: starting the standby with command-line options
2024-03-26 23:54:48.665 UTC [39119][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-26 23:54:48.665 UTC [39119][postmaster] LOG:  listening on Unix socket "/tmp/3wchaz7nKX/.s.PGSQL.61525"
2024-03-26 23:54:48.667 UTC [39122][startup] LOG:  database system was interrupted while in recovery at log time 2024-03-26 23:54:47 UTC
2024-03-26 23:54:48.667 UTC [39122][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-26 23:54:48.667 UTC [39122][startup] LOG:  starting backup recovery with redo LSN 0/2000028, checkpoint LSN 0/2000080, on timeline ID 1
2024-03-26 23:54:48.667 UTC [39122][startup] LOG:  entering standby mode
2024-03-26 23:54:48.667 UTC [39122][startup] LOG:  redo starts at 0/2000028
2024-03-26 23:54:48.667 UTC [39122][startup] LOG:  completed backup recovery with redo LSN 0/2000028 and end LSN 0/2000120
2024-03-26 23:54:48.667 UTC [39122][startup] LOG:  consistent recovery state reached at 0/3000000
2024-03-26 23:54:48.667 UTC [39119][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-26 23:54:48.670 UTC [39123][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-26 23:54:48.760 UTC [39138][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-26 23:54:48.761 UTC [39138][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-26 23:54:48.761 UTC [39138][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-26 23:54:48.766 UTC [39138][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-26 23:54:48.766 UTC [39138][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-03-26 23:54:48.766 UTC [39138][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-26 23:54:48.768 UTC [39138][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-26 23:54:48.769 UTC [39138][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-26 23:54:48.774 UTC [39119][postmaster] LOG:  received fast shutdown request
2024-03-26 23:54:48.774 UTC [39119][postmaster] LOG:  aborting any active transactions
2024-03-26 23:54:48.774 UTC [39123][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-26 23:54:48.774 UTC [39120][checkpointer] LOG:  shutting down
2024-03-26 23:54:48.775 UTC [39119][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
[23:54:48.969](0.537s) ok 21 - primary server is in recovery
Waiting for replication conn node_s's replay_lsn to pass 0/3000110 on node_p
[23:58:22.258](213.289s) # 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 38730
### 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 38925
### 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"
[23:58:22.482](0.224s) # Tests were run but no plan was declared and done_testing() was not seen.
[23:58:22.482](0.000s) # Looks like your test exited with 29 just after 21.