# Running: pg_createsubscriber --help
[04:08:51.785](0.025s) ok 1 - pg_createsubscriber --help exit code 0
[04:08:51.786](0.000s) ok 2 - pg_createsubscriber --help goes to stdout
[04:08:51.786](0.000s) ok 3 - pg_createsubscriber --help nothing to stderr
[04:08:51.786](0.000s) ok 4 - pg_createsubscriber --help maximum line length
# Running: pg_createsubscriber --version
[04:08:51.804](0.017s) ok 5 - pg_createsubscriber --version exit code 0
[04:08:51.804](0.000s) ok 6 - pg_createsubscriber --version goes to stdout
[04:08:51.804](0.000s) ok 7 - pg_createsubscriber --version nothing to stderr
# Running: pg_createsubscriber --not-a-valid-option
[04:08:51.829](0.025s) ok 8 - pg_createsubscriber with invalid option nonzero exit code
[04:08:51.830](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.
[04:08:51.850](0.020s) ok 10 - no subscriber data directory specified
# Running: pg_createsubscriber --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_reyy
pg_createsubscriber: error: no publisher connection string specified
pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information.
[04:08:51.873](0.023s) ok 11 - no publisher connection string specified
# Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/tmp_test_reyy --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.
[04:08:51.892](0.019s) ok 12 - no database name specified
# Checking port 53079
# Found port 53079
Name: node_p
Data directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/backup
Archive directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/archives
Connection string: port=53079 host=/tmp/9NjUoWdH00
Log file: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_p.log
[04:08:51.904](0.012s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/build-32/tmp_install/initdb-template /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata
# Running: /tmp/cirrus-ci-build/build-32/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build-32/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-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata -l /tmp/cirrus-ci-build/build-32/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 67181
# Checking port 53080
# Found port 53080
Name: node_f
Data directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/backup
Archive directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/archives
Connection string: port=53080 host=/tmp/9NjUoWdH00
Log file: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_f.log
[04:08:52.219](0.315s) # initializing database system by running initdb
# Running: initdb -D /tmp/cirrus-ci-build/build-32/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 locale "C".
The default database encoding has accordingly been set to "SQL_ASCII".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory /tmp/cirrus-ci-build/build-32/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 ... Etc/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-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata -l logfile start

# Running: /tmp/cirrus-ci-build/build-32/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata
### Starting node "node_f"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_f.log -o --cluster-name=node_f start
waiting for server to start.... done
server started
# Postmaster PID for node "node_f" is 67366
# Taking pg_basebackup backup_1 from node "node_p"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/backup/backup_1 -h /tmp/9NjUoWdH00 -p 53079 --checkpoint fast --no-sync
# Backup finished
# Checking port 53081
# Found port 53081
Name: node_s
Data directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/backup
Archive directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/archives
Connection string: port=53081 host=/tmp/9NjUoWdH00
Log file: /tmp/cirrus-ci-build/build-32/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-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata -l /tmp/cirrus-ci-build/build-32/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 67482
# Running: pg_createsubscriber --verbose --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata --publisher-server port=53079 host=/tmp/9NjUoWdH00 dbname='pg1' --socket-directory /tmp/9NjUoWdH00 --subscriber-port 53080 --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-32/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
pg_createsubscriber: system identifier is 7344952595601407952 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7344952990375716489 on subscriber
pg_createsubscriber: error: subscriber data directory is not a copy of the source database cluster
[04:08:54.705](2.487s) ok 13 - 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-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/backup/backup_2 -h /tmp/9NjUoWdH00 -p 53081 --checkpoint fast --no-sync
# Backup finished
# Checking port 53082
# Found port 53082
Name: node_c
Data directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/backup
Archive directory: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/archives
Connection string: port=53082 host=/tmp/9NjUoWdH00
Log file: /tmp/cirrus-ci-build/build-32/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"
### Starting node "node_c"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_c.log -o --cluster-name=node_c start
waiting for server to start.... done
server started
# Postmaster PID for node "node_c" is 67657
# Running: pg_createsubscriber --verbose --dry-run --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata --publisher-server port=53081 host=/tmp/9NjUoWdH00 dbname='pg1' --socket-directory /tmp/9NjUoWdH00 --subscriber-port 53082 --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-32/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
pg_createsubscriber: system identifier is 7344952595601407952 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7344952595601407952 on subscriber
pg_createsubscriber: standby is up and running
pg_createsubscriber: stopping the server to start the transformation steps
pg_createsubscriber: server was stopped
pg_createsubscriber: starting the standby with command-line options
2024-03-11 04:08:55.522 UTC [67754][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:55.522 UTC [67754][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53082"
2024-03-11 04:08:55.525 UTC [67759][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:55 UTC
2024-03-11 04:08:55.525 UTC [67759][startup] LOG:  entering standby mode
2024-03-11 04:08:55.526 UTC [67759][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:55.526 UTC [67759][startup] LOG:  consistent recovery state reached at 0/3000000
2024-03-11 04:08:55.526 UTC [67754][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:55.530 UTC [67760][walreceiver] FATAL:  could not start WAL streaming: ERROR:  replication slot "physical_slot" does not exist
2024-03-11 04:08:55.534 UTC [67766][walreceiver] FATAL:  could not start WAL streaming: ERROR:  replication slot "physical_slot" does not exist
2024-03-11 04:08:55.535 UTC [67759][startup] LOG:  waiting for WAL to become available at 0/3000018
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-03-11 04:08:55.682 UTC [67794][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.683 UTC [67794][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:55.683 UTC [67794][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:55.695 UTC [67794][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:55.696 UTC [67794][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-03-11 04:08:55.698 UTC [67794][client backend] [040_pg_createsubscriber.pl][0/4:0] LOG:  statement: SELECT pg_catalog.pg_has_role(current_user, 6304, 'MEMBER'), pg_catalog.has_database_privilege(current_user, 'pg1', 'CREATE'), pg_catalog.has_function_privilege(current_user, 'pg_catalog.pg_replication_origin_advance(text, pg_lsn)', 'EXECUTE')
2024-03-11 04:08:55.699 UTC [67794][client backend] [040_pg_createsubscriber.pl][0/5: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
pg_createsubscriber: checking settings on publisher
2024-03-11 04:08:55.704 UTC [67794][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=postgres database=pg1 host=[local]
pg_createsubscriber: error: primary server cannot be in recovery
[04:08:55.939](1.234s) ok 14 - primary server is in recovery
### Stopping node "node_c" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata -m immediate stop
waiting for server to shut down....2024-03-11 04:08:55.962 UTC [67754][postmaster] LOG:  received immediate shutdown request
2024-03-11 04:08:55.963 UTC [67754][postmaster] LOG:  database system is shut down
 done
server stopped
# No postmaster PID for node "node_c"
Waiting for replication conn node_s's replay_lsn to pass 0/3000104 on node_p
done
# Running: pg_createsubscriber --verbose --dry-run --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=53079 host=/tmp/9NjUoWdH00 dbname='pg1' --socket-directory /tmp/9NjUoWdH00 --subscriber-port 53081 --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-32/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
pg_createsubscriber: system identifier is 7344952595601407952 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7344952595601407952 on subscriber
pg_createsubscriber: standby is up and running
pg_createsubscriber: stopping the server to start the transformation steps
pg_createsubscriber: server was stopped
pg_createsubscriber: starting the standby with command-line options
2024-03-11 04:08:56.494 UTC [67979][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:56.494 UTC [67979][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:56.497 UTC [67984][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:56 UTC
2024-03-11 04:08:56.497 UTC [67984][startup] LOG:  entering standby mode
2024-03-11 04:08:56.498 UTC [67984][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:56.498 UTC [67984][startup] LOG:  consistent recovery state reached at 0/3000104
2024-03-11 04:08:56.498 UTC [67979][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:56.498 UTC [67984][startup] LOG:  invalid record length at 0/3000104: expected at least 24, got 0
2024-03-11 04:08:56.513 UTC [67985][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-11 04:08:56.574 UTC [68006][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:56.575 UTC [68006][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:56.575 UTC [68006][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:56.581 UTC [68006][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:56.581 UTC [68006][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-03-11 04:08:56.581 UTC [68006][client backend] [040_pg_createsubscriber.pl][0/4:0] LOG:  statement: SELECT pg_catalog.pg_has_role(current_user, 6304, 'MEMBER'), pg_catalog.has_database_privilege(current_user, 'pg1', 'CREATE'), pg_catalog.has_function_privilege(current_user, 'pg_catalog.pg_replication_origin_advance(text, pg_lsn)', 'EXECUTE')
2024-03-11 04:08:56.582 UTC [68006][client backend] [040_pg_createsubscriber.pl][0/5: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
pg_createsubscriber: checking settings on publisher
2024-03-11 04:08:56.585 UTC [68006][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=pg1 host=[local]
pg_createsubscriber: primary has replication slot "physical_slot"
pg_createsubscriber: creating publication "pg_createsubscriber_16384" on database "pg1"
pg_createsubscriber: creating the replication slot "pg_createsubscriber_16384_67943" on database "pg1"
pg_createsubscriber: create replication slot "pg_createsubscriber_16384_67943" on publisher
pg_createsubscriber: creating publication "pg_createsubscriber_16385" on database "pg2"
pg_createsubscriber: creating the replication slot "pg_createsubscriber_16385_67943" on database "pg2"
pg_createsubscriber: create replication slot "pg_createsubscriber_16385_67943" on publisher
pg_createsubscriber: stopping and starting the subscriber
2024-03-11 04:08:56.619 UTC [67979][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:56.619 UTC [67979][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:56.621 UTC [67985][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:56.622 UTC [67982][checkpointer] LOG:  shutting down
2024-03-11 04:08:56.624 UTC [67979][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
2024-03-11 04:08:56.755 UTC [68054][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:56.755 UTC [68054][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:56.766 UTC [68057][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:56 UTC
2024-03-11 04:08:56.766 UTC [68057][startup] LOG:  entering standby mode
2024-03-11 04:08:56.769 UTC [68057][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:56.769 UTC [68057][startup] LOG:  consistent recovery state reached at 0/3000104
2024-03-11 04:08:56.769 UTC [68054][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:56.769 UTC [68057][startup] LOG:  invalid record length at 0/3000104: expected at least 24, got 0
2024-03-11 04:08:56.806 UTC [68064][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: waiting for the target server to reach the consistent state
2024-03-11 04:08:56.851 UTC [68086][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:56.852 UTC [68086][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:56.852 UTC [68086][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:56.859 UTC [68086][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:56.859 UTC [68086][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
pg_createsubscriber: target server reached the consistent state
pg_createsubscriber: hint: If pg_createsubscriber fails after this point, you must recreate the physical replica before continuing.
2024-03-11 04:08:56.860 UTC [68086][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=pg1 host=[local]
2024-03-11 04:08:56.861 UTC [68092][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:56.862 UTC [68092][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:56.862 UTC [68092][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:56.863 UTC [68092][client backend] [040_pg_createsubscriber.pl][1/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
pg_createsubscriber: dropping publication "pg_createsubscriber_16384" on database "pg1"
pg_createsubscriber: creating subscription "pg_createsubscriber_16384_67943" on database "pg1"
2024-03-11 04:08:56.863 UTC [68092][client backend] [040_pg_createsubscriber.pl][1/3:0] LOG:  statement: SELECT oid FROM pg_catalog.pg_subscription WHERE subname = 'pg_createsubscriber_16384_67943'
pg_createsubscriber: setting the replication progress (node name "pg_0" ; LSN 0/0) on database "pg1"
pg_createsubscriber: enabling subscription "pg_createsubscriber_16384_67943" on database "pg1"
2024-03-11 04:08:56.864 UTC [68092][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=pg1 host=[local]
2024-03-11 04:08:56.865 UTC [68093][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:56.865 UTC [68093][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:56.865 UTC [68093][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=pg2 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:56.876 UTC [68093][client backend] [040_pg_createsubscriber.pl][2/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
pg_createsubscriber: dropping publication "pg_createsubscriber_16385" on database "pg2"
pg_createsubscriber: creating subscription "pg_createsubscriber_16385_67943" on database "pg2"
2024-03-11 04:08:56.877 UTC [68093][client backend] [040_pg_createsubscriber.pl][2/3:0] LOG:  statement: SELECT oid FROM pg_catalog.pg_subscription WHERE subname = 'pg_createsubscriber_16385_67943'
pg_createsubscriber: setting the replication progress (node name "pg_0" ; LSN 0/0) on database "pg2"
pg_createsubscriber: enabling subscription "pg_createsubscriber_16385_67943" on database "pg2"
2024-03-11 04:08:56.880 UTC [68093][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=postgres database=pg2 host=[local]
pg_createsubscriber: dropping the replication slot "physical_slot" on database "pg1"
pg_createsubscriber: stopping the subscriber
2024-03-11 04:08:56.902 UTC [68054][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:56.902 UTC [68054][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:56.902 UTC [68064][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:56.904 UTC [68055][checkpointer] LOG:  shutting down
2024-03-11 04:08:56.906 UTC [68054][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
pg_createsubscriber: modifying system identifier from subscriber
pg_createsubscriber: system identifier is 7344953010332068199 on subscriber
pg_createsubscriber: running pg_resetwal on the subscriber
2024-03-11 04:08:57.066 UTC [68142][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:57.066 UTC [68142][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:57.090 UTC [68155][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:56 UTC
2024-03-11 04:08:57.090 UTC [68155][startup] LOG:  entering standby mode
2024-03-11 04:08:57.091 UTC [68155][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:57.091 UTC [68155][startup] LOG:  consistent recovery state reached at 0/3000104
2024-03-11 04:08:57.091 UTC [68155][startup] LOG:  invalid record length at 0/3000104: expected at least 24, got 0
2024-03-11 04:08:57.091 UTC [68142][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:57.099 UTC [68159][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: Done!
[04:08:57.322](1.382s) ok 15 - run pg_createsubscriber --dry-run on node S
2024-03-11 04:08:57.359 UTC [68190][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:57.359 UTC [68190][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:57.359 UTC [68190][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:57.377 UTC [68190][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
[04:08:57.379](0.058s) ok 16 - standby is in recovery
# Running: pg_createsubscriber --verbose --dry-run --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=53079 host=/tmp/9NjUoWdH00 dbname='pg1' --socket-directory /tmp/9NjUoWdH00 --subscriber-port 53081
2024-03-11 04:08:57.380 UTC [68190][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=postgres database=postgres host=[local]
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: no database was specified
pg_createsubscriber: database "pg1" was extracted from the publisher connection string
pg_createsubscriber: checking if directory "/tmp/cirrus-ci-build/build-32/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
pg_createsubscriber: system identifier is 7344952595601407952 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7344952595601407952 on subscriber
pg_createsubscriber: standby is up and running
pg_createsubscriber: stopping the server to start the transformation steps
2024-03-11 04:08:57.414 UTC [68142][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:57.414 UTC [68142][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:57.414 UTC [68159][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:57.417 UTC [68153][checkpointer] LOG:  shutting down
2024-03-11 04:08:57.418 UTC [68142][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
pg_createsubscriber: starting the standby with command-line options
2024-03-11 04:08:57.577 UTC [68251][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:57.577 UTC [68251][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:57.580 UTC [68260][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:57 UTC
2024-03-11 04:08:57.580 UTC [68260][startup] LOG:  entering standby mode
2024-03-11 04:08:57.582 UTC [68260][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:57.582 UTC [68260][startup] LOG:  consistent recovery state reached at 0/3000104
2024-03-11 04:08:57.582 UTC [68251][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:57.582 UTC [68260][startup] LOG:  invalid record length at 0/3000104: expected at least 24, got 0
2024-03-11 04:08:57.603 UTC [68261][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-11 04:08:57.662 UTC [68277][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:57.662 UTC [68277][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:57.663 UTC [68277][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:57.674 UTC [68277][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:57.674 UTC [68277][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-03-11 04:08:57.675 UTC [68277][client backend] [040_pg_createsubscriber.pl][0/4:0] LOG:  statement: SELECT pg_catalog.pg_has_role(current_user, 6304, 'MEMBER'), pg_catalog.has_database_privilege(current_user, 'pg1', 'CREATE'), pg_catalog.has_function_privilege(current_user, 'pg_catalog.pg_replication_origin_advance(text, pg_lsn)', 'EXECUTE')
2024-03-11 04:08:57.675 UTC [68277][client backend] [040_pg_createsubscriber.pl][0/5: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
pg_createsubscriber: checking settings on publisher
2024-03-11 04:08:57.682 UTC [68277][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=postgres database=pg1 host=[local]
pg_createsubscriber: primary has replication slot "physical_slot"
pg_createsubscriber: creating publication "pg_createsubscriber_16384" on database "pg1"
pg_createsubscriber: creating the replication slot "pg_createsubscriber_16384_68192" on database "pg1"
pg_createsubscriber: create replication slot "pg_createsubscriber_16384_68192" on publisher
pg_createsubscriber: stopping and starting the subscriber
2024-03-11 04:08:57.759 UTC [68251][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:57.760 UTC [68251][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:57.760 UTC [68261][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:57.761 UTC [68258][checkpointer] LOG:  shutting down
2024-03-11 04:08:57.763 UTC [68251][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
2024-03-11 04:08:57.945 UTC [68305][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:57.945 UTC [68305][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:57.959 UTC [68315][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:57 UTC
2024-03-11 04:08:57.959 UTC [68315][startup] LOG:  entering standby mode
2024-03-11 04:08:57.960 UTC [68315][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:57.960 UTC [68315][startup] LOG:  consistent recovery state reached at 0/3000104
2024-03-11 04:08:57.960 UTC [68315][startup] LOG:  invalid record length at 0/3000104: expected at least 24, got 0
2024-03-11 04:08:57.960 UTC [68305][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:57.973 UTC [68322][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: waiting for the target server to reach the consistent state
2024-03-11 04:08:58.028 UTC [68349][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:58.029 UTC [68349][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:58.029 UTC [68349][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:58.035 UTC [68349][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:58.036 UTC [68349][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
pg_createsubscriber: target server reached the consistent state
pg_createsubscriber: hint: If pg_createsubscriber fails after this point, you must recreate the physical replica before continuing.
2024-03-11 04:08:58.037 UTC [68349][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=postgres database=pg1 host=[local]
2024-03-11 04:08:58.043 UTC [68351][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:58.043 UTC [68351][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:58.043 UTC [68351][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:58.047 UTC [68351][client backend] [040_pg_createsubscriber.pl][1/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
pg_createsubscriber: dropping publication "pg_createsubscriber_16384" on database "pg1"
pg_createsubscriber: creating subscription "pg_createsubscriber_16384_68192" on database "pg1"
2024-03-11 04:08:58.047 UTC [68351][client backend] [040_pg_createsubscriber.pl][1/3:0] LOG:  statement: SELECT oid FROM pg_catalog.pg_subscription WHERE subname = 'pg_createsubscriber_16384_68192'
pg_createsubscriber: setting the replication progress (node name "pg_0" ; LSN 0/0) on database "pg1"
pg_createsubscriber: enabling subscription "pg_createsubscriber_16384_68192" on database "pg1"
2024-03-11 04:08:58.048 UTC [68351][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=pg1 host=[local]
pg_createsubscriber: dropping the replication slot "physical_slot" on database "pg1"
pg_createsubscriber: stopping the subscriber
2024-03-11 04:08:58.077 UTC [68305][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:58.077 UTC [68305][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:58.077 UTC [68322][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:58.078 UTC [68313][checkpointer] LOG:  shutting down
2024-03-11 04:08:58.080 UTC [68305][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
pg_createsubscriber: modifying system identifier from subscriber
pg_createsubscriber: system identifier is 7344953015346661984 on subscriber
pg_createsubscriber: running pg_resetwal on the subscriber
2024-03-11 04:08:58.271 UTC [68402][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:58.271 UTC [68402][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:58.289 UTC [68409][startup] LOG:  database system was shut down in recovery at 2024-03-11 04:08:58 UTC
2024-03-11 04:08:58.290 UTC [68409][startup] LOG:  entering standby mode
2024-03-11 04:08:58.291 UTC [68409][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:58.291 UTC [68409][startup] LOG:  consistent recovery state reached at 0/3000104
2024-03-11 04:08:58.291 UTC [68402][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:58.291 UTC [68409][startup] LOG:  invalid record length at 0/3000104: expected at least 24, got 0
2024-03-11 04:08:58.309 UTC [68411][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: Done!
[04:08:58.523](1.143s) ok 17 - run pg_createsubscriber without --databases
# Running: pg_createsubscriber --verbose --verbose --pgdata /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=53079 host=/tmp/9NjUoWdH00 dbname='pg1' --socket-directory /tmp/9NjUoWdH00 --subscriber-port 53081 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: pg_ctl path is:  /tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_ctl
pg_createsubscriber: pg_resetwal path is:  /tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_resetwal
pg_createsubscriber: checking if directory "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory
pg_createsubscriber: publisher(0): connection string: host=/tmp/9NjUoWdH00 port=53079 dbname=pg1
pg_createsubscriber: subscriber(0): connection string: host=/tmp/9NjUoWdH00 port=53081 user=postgres fallback_application_name=pg_createsubscriber dbname=pg1
pg_createsubscriber: publisher(1): connection string: host=/tmp/9NjUoWdH00 port=53079 dbname=pg2
pg_createsubscriber: subscriber(1): connection string: host=/tmp/9NjUoWdH00 port=53081 user=postgres fallback_application_name=pg_createsubscriber dbname=pg2
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7344952595601407952 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7344952595601407952 on subscriber
pg_createsubscriber: log file is: /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_createsubscriber_output.d/server_start_20240311T040858.565.log
pg_createsubscriber: standby is up and running
pg_createsubscriber: stopping the server to start the transformation steps
pg_createsubscriber: pg_ctl command is: "/tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_ctl" stop -D "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s
2024-03-11 04:08:58.574 UTC [68402][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:58.575 UTC [68402][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:58.577 UTC [68411][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:58.579 UTC [68407][checkpointer] LOG:  shutting down
2024-03-11 04:08:58.580 UTC [68402][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
pg_createsubscriber: starting the standby with command-line options
pg_createsubscriber: pg_ctl command is: "/tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_ctl" start -D "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s -l "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_createsubscriber_output.d/server_start_20240311T040858.565.log" -o "-p 53081 -c listen_addresses='' -c unix_socket_permissions=0700 -c unix_socket_directories='/tmp/9NjUoWdH00'"
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: command is: SELECT pg_catalog.pg_has_role(current_user, 6304, 'MEMBER'), pg_catalog.has_database_privilege(current_user, 'pg1', 'CREATE'), pg_catalog.has_function_privilege(current_user, 'pg_catalog.pg_replication_origin_advance(text, pg_lsn)', 'EXECUTE')
pg_createsubscriber: subscriber: max_logical_replication_workers: 4
pg_createsubscriber: subscriber: max_replication_slots: 10
pg_createsubscriber: subscriber: max_worker_processes: 8
pg_createsubscriber: subscriber: primary_slot_name: physical_slot
pg_createsubscriber: checking settings on publisher
pg_createsubscriber: publisher: wal_level: logical
pg_createsubscriber: publisher: max_replication_slots: 10
pg_createsubscriber: publisher: current replication slots: 1
pg_createsubscriber: publisher: max_wal_senders: 10
pg_createsubscriber: publisher: current wal senders: 1
pg_createsubscriber: command is: SELECT 1 FROM pg_catalog.pg_replication_slots WHERE active AND slot_name = 'physical_slot'
pg_createsubscriber: primary has replication slot "physical_slot"
pg_createsubscriber: creating publication "pg_createsubscriber_16384" on database "pg1"
pg_createsubscriber: command is: CREATE PUBLICATION pg_createsubscriber_16384 FOR ALL TABLES
pg_createsubscriber: creating the replication slot "pg_createsubscriber_16384_68442" on database "pg1"
pg_createsubscriber: command is: SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('pg_createsubscriber_16384_68442', 'pgoutput', false, false, false)
pg_createsubscriber: create replication slot "pg_createsubscriber_16384_68442" on publisher
pg_createsubscriber: creating publication "pg_createsubscriber_16385" on database "pg2"
pg_createsubscriber: command is: CREATE PUBLICATION pg_createsubscriber_16385 FOR ALL TABLES
pg_createsubscriber: creating the replication slot "pg_createsubscriber_16385_68442" on database "pg2"
pg_createsubscriber: command is: SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('pg_createsubscriber_16385_68442', 'pgoutput', false, false, false)
pg_createsubscriber: create replication slot "pg_createsubscriber_16385_68442" on publisher
pg_createsubscriber: recovery parameters:
primary_conninfo = 'user=postgres passfile=''/home/postgres/.pgpass'' channel_binding=prefer host=''/tmp/9NjUoWdH00'' port=53079 application_name=040_pg_createsubscriber.pl sslmode=prefer sslcompression=0 sslcertmode=allow sslsni=1 ssl_min_protocol_version=TLSv1.2 gssencmode=prefer krbsrvname=postgres gssdelegation=0 target_session_attrs=any load_balance_hosts=disable'
recovery_target = ''
recovery_target_timeline = 'latest'
recovery_target_inclusive = true
recovery_target_action = promote
recovery_target_name = ''
recovery_target_time = ''
recovery_target_xid = ''
recovery_target_lsn = '0/3001394'
pg_createsubscriber: stopping and starting the subscriber
pg_createsubscriber: pg_ctl command is: "/tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_ctl" stop -D "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s
pg_createsubscriber: server was stopped
pg_createsubscriber: pg_ctl command is: "/tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_ctl" start -D "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s -l "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_createsubscriber_output.d/server_start_20240311T040858.565.log" -o "-p 53081 -c listen_addresses='' -c unix_socket_permissions=0700 -c unix_socket_directories='/tmp/9NjUoWdH00'"
pg_ctl: could not start server
Examine the log output.
pg_createsubscriber: error: pg_ctl failed with exit code 1
pg_createsubscriber: detail: The failed command was: "/tmp/cirrus-ci-build/build-32/tmp_install/usr/local/pgsql/bin/pg_ctl" start -D "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s -l "/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_createsubscriber_output.d/server_start_20240311T040858.565.log" -o "-p 53081 -c listen_addresses='' -c unix_socket_permissions=0700 -c unix_socket_directories='/tmp/9NjUoWdH00'"
pg_createsubscriber: dropping publication "pg_createsubscriber_16384" on database "pg1"
pg_createsubscriber: command is: DROP PUBLICATION pg_createsubscriber_16384
pg_createsubscriber: dropping the replication slot "pg_createsubscriber_16384_68442" on database "pg1"
pg_createsubscriber: command is: SELECT pg_catalog.pg_drop_replication_slot('pg_createsubscriber_16384_68442')
pg_createsubscriber: dropping publication "pg_createsubscriber_16385" on database "pg2"
pg_createsubscriber: command is: DROP PUBLICATION pg_createsubscriber_16385
pg_createsubscriber: dropping the replication slot "pg_createsubscriber_16385_68442" on database "pg2"
pg_createsubscriber: command is: SELECT pg_catalog.pg_drop_replication_slot('pg_createsubscriber_16385_68442')
[04:08:59.666](1.143s) not ok 18 - run pg_createsubscriber on node S
[04:08:59.667](0.000s) #   Failed test 'run pg_createsubscriber on node S'
#   at /tmp/cirrus-ci-build/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 146.
[04:08:59.667](0.000s) ok 19 - pg_createsubscriber_output.d/ removed after pg_createsubscriber success
[04:08:59.711](0.044s) not ok 20 - the physical replication slot used as primary_slot_name has been removed
[04:08:59.711](0.000s) #   Failed test 'the physical replication slot used as primary_slot_name has been removed'
#   at /tmp/cirrus-ci-build/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 167.
[04:08:59.712](0.000s) #          got: '1'
#     expected: '0'
### Starting node "node_s"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata -l /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl start failed; logfile:
2024-03-11 04:08:54.589 UTC [67482][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:54.589 UTC [67482][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:54.602 UTC [67499][startup] LOG:  database system was interrupted; last known up at 2024-03-11 04:08:54 UTC
2024-03-11 04:08:54.602 UTC [67499][startup] LOG:  starting backup recovery with redo LSN 0/2000024, checkpoint LSN 0/2000078, on timeline ID 1
2024-03-11 04:08:54.602 UTC [67499][startup] LOG:  entering standby mode
2024-03-11 04:08:54.603 UTC [67499][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:54.603 UTC [67499][startup] LOG:  completed backup recovery with redo LSN 0/2000024 and end LSN 0/2000104
2024-03-11 04:08:54.603 UTC [67499][startup] LOG:  consistent recovery state reached at 0/2000104
2024-03-11 04:08:54.603 UTC [67482][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-11 04:08:54.618 UTC [67500][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-03-11 04:08:54.714 UTC [67536][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:54.715 UTC [67536][walsender] [[unknown]][22/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:124)
2024-03-11 04:08:54.715 UTC [67536][walsender] [[unknown]][22/1:0] LOG:  replication connection authorized: user=postgres application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] LOG:  received replication command: SHOW data_directory_mode
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] STATEMENT:  SHOW data_directory_mode
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] LOG:  received replication command: SHOW wal_segment_size
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] STATEMENT:  SHOW wal_segment_size
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] LOG:  received replication command: IDENTIFY_SYSTEM
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] STATEMENT:  IDENTIFY_SYSTEM
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] LOG:  received replication command: BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2024-03-11 04:08:54.715 UTC [67536][walsender] [040_pg_createsubscriber.pl][22/0:0] STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2024-03-11 04:08:54.726 UTC [67541][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:54.727 UTC [67541][walsender] [[unknown]][23/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:124)
2024-03-11 04:08:54.727 UTC [67541][walsender] [[unknown]][23/1:0] LOG:  replication connection authorized: user=postgres application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:54.727 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  received replication command: SHOW data_directory_mode
2024-03-11 04:08:54.727 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  SHOW data_directory_mode
2024-03-11 04:08:54.727 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_67541" TEMPORARY PHYSICAL ( RESERVE_WAL)
2024-03-11 04:08:54.727 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_67541" TEMPORARY PHYSICAL ( RESERVE_WAL)
2024-03-11 04:08:54.728 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  released physical replication slot "pg_basebackup_67541"
2024-03-11 04:08:54.728 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_67541" TEMPORARY PHYSICAL ( RESERVE_WAL)
2024-03-11 04:08:54.732 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  received replication command: IDENTIFY_SYSTEM
2024-03-11 04:08:54.732 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  IDENTIFY_SYSTEM
2024-03-11 04:08:54.732 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_67541" 0/2000000 TIMELINE 1
2024-03-11 04:08:54.732 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  START_REPLICATION SLOT "pg_basebackup_67541" 0/2000000 TIMELINE 1
2024-03-11 04:08:54.732 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  acquired physical replication slot "pg_basebackup_67541"
2024-03-11 04:08:54.732 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  START_REPLICATION SLOT "pg_basebackup_67541" 0/2000000 TIMELINE 1
2024-03-11 04:08:54.908 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] LOG:  released physical replication slot "pg_basebackup_67541"
2024-03-11 04:08:54.908 UTC [67541][walsender] [040_pg_createsubscriber.pl][23/0:0] STATEMENT:  START_REPLICATION SLOT "pg_basebackup_67541" 0/2000000 TIMELINE 1
2024-03-11 04:08:54.909 UTC [67541][walsender] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.182 user=postgres database= host=[local]
2024-03-11 04:08:54.910 UTC [67536][walsender] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.196 user=postgres database= host=[local]
2024-03-11 04:08:55.232 UTC [67670][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.234 UTC [67670][walsender] [[unknown]][24/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:124)
2024-03-11 04:08:55.234 UTC [67670][walsender] [[unknown]][24/1:0] LOG:  replication connection authorized: user=postgres application_name=node_c
2024-03-11 04:08:55.235 UTC [67670][walsender] [node_c][24/0:0] LOG:  received replication command: IDENTIFY_SYSTEM
2024-03-11 04:08:55.235 UTC [67670][walsender] [node_c][24/0:0] STATEMENT:  IDENTIFY_SYSTEM
2024-03-11 04:08:55.235 UTC [67670][walsender] [node_c][24/0:0] LOG:  received replication command: START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.235 UTC [67670][walsender] [node_c][24/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.235 UTC [67670][walsender] [node_c][24/0:0] ERROR:  replication slot "physical_slot" does not exist
2024-03-11 04:08:55.235 UTC [67670][walsender] [node_c][24/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.236 UTC [67670][walsender] [node_c][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database= host=[local]
2024-03-11 04:08:55.257 UTC [67676][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.257 UTC [67676][walsender] [[unknown]][25/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:124)
2024-03-11 04:08:55.257 UTC [67676][walsender] [[unknown]][25/1:0] LOG:  replication connection authorized: user=postgres application_name=node_c
2024-03-11 04:08:55.258 UTC [67676][walsender] [node_c][25/0:0] LOG:  received replication command: IDENTIFY_SYSTEM
2024-03-11 04:08:55.258 UTC [67676][walsender] [node_c][25/0:0] STATEMENT:  IDENTIFY_SYSTEM
2024-03-11 04:08:55.262 UTC [67676][walsender] [node_c][25/0:0] LOG:  received replication command: START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.262 UTC [67676][walsender] [node_c][25/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.262 UTC [67676][walsender] [node_c][25/0:0] ERROR:  replication slot "physical_slot" does not exist
2024-03-11 04:08:55.262 UTC [67676][walsender] [node_c][25/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.263 UTC [67676][walsender] [node_c][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database= host=[local]
2024-03-11 04:08:55.374 UTC [67712][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.375 UTC [67712][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:55.375 UTC [67712][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:55.381 UTC [67712][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:55.382 UTC [67712][client backend] [040_pg_createsubscriber.pl][0/3:0] LOG:  statement: SELECT system_identifier FROM pg_catalog.pg_control_system()
2024-03-11 04:08:55.382 UTC [67712][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=pg1 host=[local]
2024-03-11 04:08:55.528 UTC [67761][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.529 UTC [67761][walsender] [[unknown]][26/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:124)
2024-03-11 04:08:55.529 UTC [67761][walsender] [[unknown]][26/1:0] LOG:  replication connection authorized: user=postgres application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:55.529 UTC [67761][walsender] [040_pg_createsubscriber.pl][26/0:0] LOG:  received replication command: IDENTIFY_SYSTEM
2024-03-11 04:08:55.529 UTC [67761][walsender] [040_pg_createsubscriber.pl][26/0:0] STATEMENT:  IDENTIFY_SYSTEM
2024-03-11 04:08:55.530 UTC [67761][walsender] [040_pg_createsubscriber.pl][26/0:0] LOG:  received replication command: START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.530 UTC [67761][walsender] [040_pg_createsubscriber.pl][26/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.530 UTC [67761][walsender] [040_pg_createsubscriber.pl][26/0:0] ERROR:  replication slot "physical_slot" does not exist
2024-03-11 04:08:55.530 UTC [67761][walsender] [040_pg_createsubscriber.pl][26/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.531 UTC [67761][walsender] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database= host=[local]
2024-03-11 04:08:55.533 UTC [67768][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.534 UTC [67768][walsender] [[unknown]][27/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:124)
2024-03-11 04:08:55.534 UTC [67768][walsender] [[unknown]][27/1:0] LOG:  replication connection authorized: user=postgres application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][27/0:0] LOG:  received replication command: IDENTIFY_SYSTEM
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][27/0:0] STATEMENT:  IDENTIFY_SYSTEM
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][27/0:0] LOG:  received replication command: START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][27/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][27/0:0] ERROR:  replication slot "physical_slot" does not exist
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][27/0:0] STATEMENT:  START_REPLICATION SLOT "physical_slot" 0/3000000 TIMELINE 1
2024-03-11 04:08:55.534 UTC [67768][walsender] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database= host=[local]
2024-03-11 04:08:55.709 UTC [67810][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-11 04:08:55.710 UTC [67810][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-11 04:08:55.711 UTC [67810][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=pg1 application_name=040_pg_createsubscriber.pl
2024-03-11 04:08:55.712 UTC [67810][client backend] [040_pg_createsubscriber.pl][1/2:0] LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-03-11 04:08:55.713 UTC [67810][client backend] [040_pg_createsubscriber.pl][1/3:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-03-11 04:08:55.713 UTC [67810][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=pg1 host=[local]
2024-03-11 04:08:56.274 UTC [67482][postmaster] LOG:  received fast shutdown request
2024-03-11 04:08:56.274 UTC [67482][postmaster] LOG:  aborting any active transactions
2024-03-11 04:08:56.275 UTC [67500][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-11 04:08:56.279 UTC [67490][checkpointer] LOG:  shutting down
2024-03-11 04:08:56.281 UTC [67482][postmaster] LOG:  database system is shut down
2024-03-11 04:08:59.815 UTC [68672][postmaster] LOG:  starting PostgreSQL 17devel on x86-linux, compiled by gcc-10.2.1, 32-bit
2024-03-11 04:08:59.815 UTC [68672][postmaster] LOG:  listening on Unix socket "/tmp/9NjUoWdH00/.s.PGSQL.53081"
2024-03-11 04:08:59.844 UTC [68677][startup] LOG:  database system was interrupted while in recovery at log time 2024-03-11 04:08:54 UTC
2024-03-11 04:08:59.844 UTC [68677][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-11 04:08:59.844 UTC [68677][startup] LOG:  entering standby mode
2024-03-11 04:08:59.845 UTC [68677][startup] LOG:  redo starts at 0/2000024
2024-03-11 04:08:59.845 UTC [68677][startup] LOG:  invalid magic number 0000 in WAL segment 000000010000000000000003, LSN 0/3006000, offset 24576
2024-03-11 04:08:59.845 UTC [68677][startup] LOG:  recovery stopping after WAL location (LSN) "0/3001394"
2024-03-11 04:08:59.845 UTC [68677][startup] FATAL:  requested recovery stop point is before consistent recovery point
2024-03-11 04:08:59.849 UTC [68672][postmaster] LOG:  startup process (PID 68677) exited with exit code 1
2024-03-11 04:08:59.850 UTC [68672][postmaster] LOG:  terminating any other active server processes
2024-03-11 04:08:59.850 UTC [68672][postmaster] LOG:  shutting down due to startup process failure
2024-03-11 04:08:59.851 UTC [68672][postmaster] LOG:  database system is shut down
# No postmaster PID for node "node_s"
[04:08:59.892](0.181s) Bail out!  pg_ctl start failed
# Postmaster PID for node "node_p" is 67181
### Stopping node "node_p" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build-32/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"
# Postmaster PID for node "node_f" is 67366
### Stopping node "node_f" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build-32/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node_f"
# No postmaster PID for node "node_s"
# No postmaster PID for node "node_c"