# Running: pg_createsubscriber --help
[01:30:49.750](0.091s) ok 1 - pg_createsubscriber --help exit code 0
[01:30:49.750](0.000s) ok 2 - pg_createsubscriber --help goes to stdout
[01:30:49.750](0.000s) ok 3 - pg_createsubscriber --help nothing to stderr
[01:30:49.751](0.000s) ok 4 - pg_createsubscriber --help maximum line length
# Running: pg_createsubscriber --version
[01:30:49.780](0.029s) ok 5 - pg_createsubscriber --version exit code 0
[01:30:49.780](0.000s) ok 6 - pg_createsubscriber --version goes to stdout
[01:30:49.781](0.000s) ok 7 - pg_createsubscriber --version nothing to stderr
# Running: pg_createsubscriber --not-a-valid-option
[01:30:49.842](0.062s) ok 8 - pg_createsubscriber with invalid option nonzero exit code
[01:30:49.843](0.001s) 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.
[01:30:49.904](0.061s) ok 10 - no subscriber data directory specified
# Running: pg_createsubscriber --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO
pg_createsubscriber: error: no publisher connection string specified
pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information.
[01:30:49.964](0.060s) ok 11 - no publisher connection string specified
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO --publisher-server port=5432
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: no database was specified
pg_createsubscriber: error: no database name specified
pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information.
[01:30:50.011](0.047s) ok 12 - no database name specified
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO --publisher-server port=5432 --database pg1 --database pg1
pg_createsubscriber: error: duplicate database "pg1"
[01:30:50.059](0.048s) ok 13 - duplicate database name
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO --publisher-server port=5432 --publication foo1 --publication foo1 --database pg1 --database pg2
pg_createsubscriber: error: duplicate publication "foo1"
[01:30:50.120](0.061s) ok 14 - duplicate publication name
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO --publisher-server port=5432 --publication foo1 --database pg1 --database pg2
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: error: wrong number of publication names
pg_createsubscriber: hint: Number of publication names (1) must match number of database names (2).
[01:30:50.189](0.069s) ok 15 - wrong number of publication names
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO --publisher-server port=5432 --publication foo1 --publication foo2 --subscription bar1 --database pg1 --database pg2
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: error: wrong number of subscription names
pg_createsubscriber: hint: Number of subscription names (1) must match number of database names (2).
[01:30:50.262](0.073s) ok 16 - wrong number of subscription names
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build\testrun\pg_basebackup_15\040_pg_createsubscriber\data\tmp_test_t9PO --publisher-server port=5432 --publication foo1 --publication foo2 --subscription bar1 --subscription bar2 --replication-slot baz1 --database pg1 --database pg2
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
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).
[01:30:50.309](0.047s) ok 17 - wrong number of replication slot names
# Checking port 15396
# Found port 15396
Name: node_p
Data directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata
Backup directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/backup
Archive directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/archives
Connection string: port=15396 host=127.0.0.1
Log file: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log
[01:30:50.329](0.020s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP T:/pgtest/postgresql/build/tmp_install/initdb-template T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata

# Running: T:\pgtest\postgresql\build\src/test\regress\pg_regress.exe --config-auth T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata
### Starting node "node_p"
# Running: pg_ctl -w -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log -o --cluster-name=node_p start
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
.!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
 done
server started
# Postmaster PID for node "node_p" is 4552
# Checking port 15397
# Found port 15397
Name: node_f
Data directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata
Backup directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/backup
Archive directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/archives
Connection string: port=15397 host=127.0.0.1
Log file: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_f.log
[01:30:54.453](4.125s) # initializing database system by running initdb
# Running: initdb -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/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 "1".
This user must also own the server process.

The database cluster will be initialized with locale "English_United States.1252".
The default database encoding has accordingly been set to "WIN1252".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... windows
selecting default "max_connections" ... 100
selecting default "shared_buffers" ... 128MB
selecting default time zone ... America/Los_Angeles
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 ^"T^:^\pgtest^\postgresql^\build^/testrun^/pg^_basebackup^_15^/040^_pg^_createsubscriber^\data^/t^_040^_pg^_createsubscriber^_node^_f^_data^/pgdata^" -l logfile start

# Running: T:\pgtest\postgresql\build\src/test\regress\pg_regress.exe --config-auth T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata
# Running: createdb regression	 !#$%&'()*+,-
[01:31:00.235](5.782s) ok 18 - created database with ASCII characters from 1 to 45
# Running: createdb regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
[01:31:01.453](1.218s) ok 19 - created database with ASCII characters from 46 to 90
# Taking pg_basebackup backup_1 from node "node_p"
# Running: pg_basebackup -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/backup/backup_1 -h 127.0.0.1 -p 15396 --checkpoint fast --no-sync
# Backup finished
# Checking port 15398
# Found port 15398
Name: node_s
Data directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata
Backup directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/backup
Archive directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/archives
Connection string: port=15398 host=127.0.0.1
Log file: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/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 T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
. done
server started
# Postmaster PID for node "node_s" is 3484
# Checking port 15399
# Found port 15399
Name: node_t
Data directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/pgdata
Backup directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/backup
Archive directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/archives
Connection string: port=15399 host=127.0.0.1
Log file: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/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 T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_t.log -o --cluster-name=node_t start
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
.!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
 done
server started
# Postmaster PID for node "node_t" is 6628
### Promoting node "node_t"
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_t.log promote
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to promote...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
 done
server promoted
### Stopping node "node_t" using mode fast
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/pgdata -m fast stop
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
# No postmaster PID for node "node_t"
# Running: pg_createsubscriber --verbose --dry-run --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_t_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15399 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
pg_createsubscriber: starting the standby with command-line options
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:23.129 PDT [7852] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:23.131 PDT [7852] LOG:  listening on IPv4 address "127.0.0.1", port 15399
2024-08-21 01:31:23.178 PDT [7920] LOG:  database system was shut down at 2024-08-21 01:31:22 PDT
2024-08-21 01:31:23.191 PDT [7852] LOG:  database system is ready to accept connections
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:23.320 PDT [5456] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:23.321 PDT [5456] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
pg_createsubscriber: error: target server must be a standby
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
2024-08-21 01:31:23.353 PDT [7852] LOG:  received fast shutdown request
waiting for server to shut down...2024-08-21 01:31:23.353 PDT [7852] LOG:  aborting any active transactions
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: 3, errno: 22
.2024-08-21 01:31:23.357 PDT [7852] LOG:  background worker "logical replication launcher" (PID 7696) exited with exit code 1
2024-08-21 01:31:23.360 PDT [7764] LOG:  shutting down
2024-08-21 01:31:23.360 PDT [7764] LOG:  checkpoint starting: shutdown immediate
2024-08-21 01:31:23.362 PDT [7764] 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.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=17 kB, estimate=17 kB; lsn=0/30046F0, redo lsn=0/30046F0
2024-08-21 01:31:23.370 PDT [7852] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_t_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
[01:31:23.894](22.440s) ok 20 - target server is not in recovery
# Running: pg_createsubscriber --verbose --dry-run --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15398 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
pg_createsubscriber: error: standby is up and running
pg_createsubscriber: hint: Stop the standby and try again.
[01:31:24.281](0.388s) ok 21 - standby is up and running
# Running: pg_createsubscriber --verbose --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15397 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405507400995901740 on subscriber
pg_createsubscriber: error: subscriber data directory is not a copy of the source database cluster
[01:31:24.658](0.377s) ok 22 - 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 T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/backup/backup_2 -h 127.0.0.1 -p 15398 --checkpoint fast --no-sync
# Backup finished
# Checking port 15400
# Found port 15400
Name: node_c
Data directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/pgdata
Backup directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/backup
Archive directory: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/archives
Connection string: port=15400 host=127.0.0.1
Log file: T:\pgtest\postgresql\build/testrun/pg_basebackup_15/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 T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/pgdata --publisher-server port=15398 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15400 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
pg_createsubscriber: starting the standby with command-line options
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:39.066 PDT [8732] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:39.068 PDT [8732] LOG:  listening on IPv4 address "127.0.0.1", port 15400
2024-08-21 01:31:39.090 PDT [1216] LOG:  database system was interrupted while in recovery at log time 2024-08-21 01:31:02 PDT
2024-08-21 01:31:39.090 PDT [1216] HINT:  If this has occurred more than once some data might be corrupted and you might need to choose an earlier recovery target.
2024-08-21 01:31:39.091 PDT [1216] LOG:  starting backup recovery with redo LSN 0/2000028, checkpoint LSN 0/2000080, on timeline ID 1
2024-08-21 01:31:39.091 PDT [1216] LOG:  entering standby mode
2024-08-21 01:31:39.094 PDT [1216] LOG:  redo starts at 0/2000028
2024-08-21 01:31:39.094 PDT [1216] LOG:  completed backup recovery with redo LSN 0/2000028 and end LSN 0/2000120
2024-08-21 01:31:39.094 PDT [1216] LOG:  consistent recovery state reached at 0/3000060
2024-08-21 01:31:39.094 PDT [8732] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:39.094 PDT [1216] LOG:  invalid record length at 0/3000060: expected at least 24, got 0
2024-08-21 01:31:39.138 PDT [3316] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:39.231 PDT [9044] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:39.232 PDT [9044] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-08-21 01:31:39.232 PDT [9044] 040_pg_createsubscriber.pl 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
pg_createsubscriber: error: primary server cannot be in recovery
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...2024-08-21 01:31:39.328 PDT [8732] LOG:  received fast shutdown request
2024-08-21 01:31:39.329 PDT [8732] LOG:  aborting any active transactions
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/postmaster.pid, fd: 3, errno: 22
.2024-08-21 01:31:39.330 PDT [3316] FATAL:  terminating walreceiver process due to administrator command
2024-08-21 01:31:39.339 PDT [460] LOG:  shutting down
2024-08-21 01:31:39.343 PDT [8732] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
[01:31:39.720](15.062s) ok 23 - primary server is in recovery
### Restarting node "node_p"
# Running: pg_ctl -w -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log restart
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
. done
server started
# Postmaster PID for node "node_p" is 7608
### Stopping node "node_s" using mode fast
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -m fast stop
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
# No postmaster PID for node "node_s"
# Running: pg_createsubscriber --verbose --dry-run --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15398 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
pg_createsubscriber: starting the standby with command-line options
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:40.630 PDT [13856] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:40.631 PDT [13856] LOG:  listening on IPv4 address "127.0.0.1", port 15398
2024-08-21 01:31:40.660 PDT [8208] LOG:  database system was shut down in recovery at 2024-08-21 01:31:40 PDT
2024-08-21 01:31:40.661 PDT [8208] LOG:  entering standby mode
2024-08-21 01:31:40.663 PDT [8208] LOG:  consistent recovery state reached at 0/30000D8
2024-08-21 01:31:40.663 PDT [13856] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:40.663 PDT [8208] LOG:  invalid record length at 0/30000D8: expected at least 24, got 0
2024-08-21 01:31:40.711 PDT [13500] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-08-21 01:31:40.712 PDT [8208] LOG:  redo starts at 0/30000D8
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:40.796 PDT [1072] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:40.796 PDT [1072] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-08-21 01:31:40.797 PDT [1072] 040_pg_createsubscriber.pl 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
pg_createsubscriber: error: publisher requires wal_level >= "logical"
pg_createsubscriber: error: publisher requires 2 replication slots, but only 0 remain
pg_createsubscriber: hint: Increase the configuration parameter "max_replication_slots" to at least 3.
pg_createsubscriber: error: publisher requires 2 wal sender processes, but only 0 remain
pg_createsubscriber: hint: Increase the configuration parameter "max_wal_senders" to at least 3.
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...2024-08-21 01:31:40.881 PDT [13856] LOG:  received fast shutdown request
2024-08-21 01:31:40.881 PDT [13856] LOG:  aborting any active transactions
2024-08-21 01:31:40.881 PDT [13500] FATAL:  terminating walreceiver process due to administrator command
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.2024-08-21 01:31:40.886 PDT [9952] LOG:  shutting down
2024-08-21 01:31:40.890 PDT [13856] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
[01:31:41.453](1.733s) ok 24 - primary contains unmet conditions on node P
# Running: pg_createsubscriber --verbose --dry-run --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15398 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
pg_createsubscriber: starting the standby with command-line options
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:41.776 PDT [9716] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:41.777 PDT [9716] LOG:  listening on IPv4 address "127.0.0.1", port 15398
2024-08-21 01:31:41.823 PDT [14284] LOG:  database system was shut down in recovery at 2024-08-21 01:31:40 PDT
2024-08-21 01:31:41.824 PDT [14284] LOG:  entering standby mode
2024-08-21 01:31:41.826 PDT [14284] LOG:  redo starts at 0/30000D8
2024-08-21 01:31:41.827 PDT [14284] LOG:  consistent recovery state reached at 0/3004620
2024-08-21 01:31:41.827 PDT [9716] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:41.827 PDT [14284] LOG:  invalid record length at 0/3004620: expected at least 24, got 0
2024-08-21 01:31:41.889 PDT [11704] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:41.981 PDT [11548] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:41.982 PDT [11548] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-08-21 01:31:41.982 PDT [11548] 040_pg_createsubscriber.pl 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: error: subscriber requires 2 replication slots, but only 1 remain
pg_createsubscriber: hint: Increase the configuration parameter "max_replication_slots" to at least 2.
pg_createsubscriber: error: subscriber requires 2 logical replication workers, but only 1 remain
pg_createsubscriber: hint: Increase the configuration parameter "max_logical_replication_workers" to at least 2.
pg_createsubscriber: error: subscriber requires 3 worker processes, but only 2 remain
pg_createsubscriber: hint: Increase the configuration parameter "max_worker_processes" to at least 3.
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...2024-08-21 01:31:42.038 PDT [9716] LOG:  received fast shutdown request
2024-08-21 01:31:42.038 PDT [9716] LOG:  aborting any active transactions
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.2024-08-21 01:31:42.038 PDT [11704] FATAL:  terminating walreceiver process due to administrator command
2024-08-21 01:31:42.076 PDT [14016] LOG:  shutting down
2024-08-21 01:31:42.086 PDT [9716] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
[01:31:42.672](1.219s) ok 25 - standby contains unmet conditions on node S
### Restarting node "node_p"
# Running: pg_ctl -w -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log restart
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
.!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
 done
server started
# Postmaster PID for node "node_p" is 9856
### Starting node "node_s"
# Running: pg_ctl -w -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
. done
server started
# Postmaster PID for node "node_s" is 9208
Waiting for replication conn node_s's replay_lsn to pass 0/3004708 on node_p
done
[01:31:44.047](1.375s) ok 26 - failover slot is synced
Waiting for replication conn node_s's replay_lsn to pass 0/30047F0 on node_p
done
#### Begin standard error
psql:<stdin>:1: WARNING:  subscription was created, but is not connected
HINT:  To initiate replication, you must manually create the replication slot, enable the subscription, and refresh the subscription.
#### End standard error
Waiting for replication conn node_s's replay_lsn to pass 0/3004D58 on node_p
done
### Stopping node "node_s" using mode fast
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -m fast stop
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
# No postmaster PID for node "node_s"
# Running: pg_createsubscriber --verbose --recovery-timeout 180 --dry-run --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15398 --publication pub1 --publication pub2 --subscription sub1 --subscription sub2 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
pg_createsubscriber: starting the standby with command-line options
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:45.449 PDT [1288] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:45.450 PDT [1288] LOG:  listening on IPv4 address "127.0.0.1", port 15398
2024-08-21 01:31:45.474 PDT [10024] LOG:  database system was shut down in recovery at 2024-08-21 01:31:44 PDT
2024-08-21 01:31:45.475 PDT [10024] LOG:  entering standby mode
2024-08-21 01:31:45.478 PDT [10024] LOG:  redo starts at 0/3004698
2024-08-21 01:31:45.478 PDT [10024] LOG:  consistent recovery state reached at 0/3004D58
2024-08-21 01:31:45.479 PDT [1288] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:45.479 PDT [10024] LOG:  invalid record length at 0/3004D58: expected at least 24, got 0
2024-08-21 01:31:45.549 PDT [836] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:45.607 PDT [6836] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:45.608 PDT [6836] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-08-21 01:31:45.608 PDT [6836] 040_pg_createsubscriber.pl 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
pg_createsubscriber: stopping the subscriber
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
2024-08-21 01:31:45.684 PDT [1288] LOG:  received fast shutdown request
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
2024-08-21 01:31:45.685 PDT [1288] LOG:  aborting any active transactions
.2024-08-21 01:31:45.685 PDT [836] FATAL:  terminating walreceiver process due to administrator command
2024-08-21 01:31:45.697 PDT [6316] LOG:  shutting down
2024-08-21 01:31:45.708 PDT [1288] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
pg_createsubscriber: creating publication "pub1" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: creating the replication slot "sub1" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: create replication slot "sub1" on publisher
pg_createsubscriber: creating publication "pub2" in database "regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ"
pg_createsubscriber: creating the replication slot "sub2" in database "regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ"
pg_createsubscriber: create replication slot "sub2" on publisher
pg_createsubscriber: starting the subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:46.149 PDT [14080] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:46.151 PDT [14080] LOG:  listening on IPv4 address "127.0.0.1", port 15398
2024-08-21 01:31:46.177 PDT [9064] LOG:  database system was shut down in recovery at 2024-08-21 01:31:45 PDT
2024-08-21 01:31:46.177 PDT [9064] LOG:  entering standby mode
2024-08-21 01:31:46.180 PDT [9064] LOG:  redo starts at 0/3004698
2024-08-21 01:31:46.181 PDT [9064] LOG:  consistent recovery state reached at 0/3004D58
2024-08-21 01:31:46.181 PDT [14080] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:46.181 PDT [9064] LOG:  invalid record length at 0/3004D58: expected at least 24, got 0
pg_createsubscriber: server was started
pg_createsubscriber: waiting for the target server to reach the consistent state
2024-08-21 01:31:46.330 PDT [11636] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-08-21 01:31:46.365 PDT [7640] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:46.366 PDT [7640] 040_pg_createsubscriber.pl 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-08-21 01:31:46.394 PDT [13540] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:46.394 PDT [13540] 040_pg_createsubscriber.pl LOG:  statement: SELECT s.subname FROM pg_catalog.pg_subscription s INNER JOIN pg_catalog.pg_database d ON (s.subdbid = d.oid) WHERE d.datname = 'regression	 !#$%&''()*+,-'
pg_createsubscriber: dropping subscription "regress_sub_dummy" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: dropping publication "pub1" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: creating subscription "sub1" in database "regression	 !#$%&'()*+,-"
2024-08-21 01:31:46.398 PDT [13540] 040_pg_createsubscriber.pl LOG:  statement: SELECT s.oid FROM pg_catalog.pg_subscription s INNER JOIN pg_catalog.pg_database d ON (s.subdbid = d.oid) WHERE s.subname = 'sub1' AND d.datname = 'regression	 !#$%&''()*+,-'
pg_createsubscriber: setting the replication progress (node name "pg_0" ; LSN 0/0) in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: enabling subscription "sub1" in database "regression	 !#$%&'()*+,-"
2024-08-21 01:31:46.430 PDT [11380] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:46.430 PDT [11380] 040_pg_createsubscriber.pl LOG:  statement: SELECT s.subname FROM pg_catalog.pg_subscription s INNER JOIN pg_catalog.pg_database d ON (s.subdbid = d.oid) WHERE d.datname = 'regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ'
pg_createsubscriber: dropping publication "pub2" in database "regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ"
pg_createsubscriber: creating subscription "sub2" in database "regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ"
2024-08-21 01:31:46.433 PDT [11380] 040_pg_createsubscriber.pl LOG:  statement: SELECT s.oid FROM pg_catalog.pg_subscription s INNER JOIN pg_catalog.pg_database d ON (s.subdbid = d.oid) WHERE s.subname = 'sub2' AND d.datname = 'regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ'
pg_createsubscriber: setting the replication progress (node name "pg_0" ; LSN 0/0) in database "regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ"
pg_createsubscriber: enabling subscription "sub2" in database "regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ"
pg_createsubscriber: dropping the replication slot "physical_slot" in database "regression	 !#$%&'()*+,-"
2024-08-21 01:31:46.507 PDT [13268] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:46.509 PDT [13268] 040_pg_createsubscriber.pl LOG:  statement: SELECT slot_name FROM pg_catalog.pg_replication_slots WHERE failover
pg_createsubscriber: dropping the replication slot "failover_slot" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: stopping the subscriber
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
2024-08-21 01:31:46.540 PDT [14080] LOG:  received fast shutdown request
2024-08-21 01:31:46.540 PDT [14080] LOG:  aborting any active transactions
.2024-08-21 01:31:46.546 PDT [11636] FATAL:  terminating walreceiver process due to administrator command
2024-08-21 01:31:46.573 PDT [4784] LOG:  shutting down
2024-08-21 01:31:46.581 PDT [14080] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
pg_createsubscriber: modifying system identifier of subscriber
pg_createsubscriber: system identifier is 7405507623748371624 on subscriber
pg_createsubscriber: running pg_resetwal on the subscriber
pg_createsubscriber: Done!
[01:31:46.845](2.797s) ok 27 - run pg_createsubscriber --dry-run on node S
### Starting node "node_s"
# Running: pg_ctl -w -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
. done
server started
# Postmaster PID for node "node_s" is 4584
[01:31:47.113](0.268s) ok 28 - standby is in recovery
### Stopping node "node_s" using mode fast
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -m fast stop
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
# No postmaster PID for node "node_s"
# Running: pg_createsubscriber --verbose --dry-run --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15398 --replication-slot replslot1
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: no database was specified
pg_createsubscriber: database "regression	 !#$%&'()*+,-" was extracted from the publisher connection string
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/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 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
pg_createsubscriber: starting the standby with command-line options
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:47.615 PDT [9436] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:47.616 PDT [9436] LOG:  listening on IPv4 address "127.0.0.1", port 15398
2024-08-21 01:31:47.642 PDT [6240] LOG:  database system was shut down in recovery at 2024-08-21 01:31:47 PDT
2024-08-21 01:31:47.643 PDT [6240] LOG:  entering standby mode
2024-08-21 01:31:47.645 PDT [6240] LOG:  redo starts at 0/3004698
2024-08-21 01:31:47.646 PDT [6240] LOG:  consistent recovery state reached at 0/3009268
2024-08-21 01:31:47.647 PDT [9436] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:47.647 PDT [6240] LOG:  invalid record length at 0/3009268: expected at least 24, got 0
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:47.709 PDT [4472] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-08-21 01:31:47.729 PDT [11196] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:47.730 PDT [11196] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-08-21 01:31:47.730 PDT [11196] 040_pg_createsubscriber.pl 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
pg_createsubscriber: stopping the subscriber
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...2024-08-21 01:31:47.804 PDT [9436] LOG:  received fast shutdown request
2024-08-21 01:31:47.805 PDT [9436] LOG:  aborting any active transactions
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.2024-08-21 01:31:47.805 PDT [4472] FATAL:  terminating walreceiver process due to administrator command
2024-08-21 01:31:47.828 PDT [11392] LOG:  shutting down
2024-08-21 01:31:47.835 PDT [9436] LOG:  database system is shut down
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
pg_createsubscriber: creating publication "pg_createsubscriber_16384_fee3863f" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: creating the replication slot "replslot1" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: create replication slot "replslot1" on publisher
pg_createsubscriber: starting the subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
2024-08-21 01:31:48.131 PDT [9440] LOG:  starting PostgreSQL 18devel on x86_64-windows, compiled by msvc-19.39.33523, 64-bit
2024-08-21 01:31:48.133 PDT [9440] LOG:  listening on IPv4 address "127.0.0.1", port 15398
2024-08-21 01:31:48.154 PDT [9916] LOG:  database system was shut down in recovery at 2024-08-21 01:31:47 PDT
2024-08-21 01:31:48.155 PDT [9916] LOG:  entering standby mode
2024-08-21 01:31:48.158 PDT [9916] LOG:  redo starts at 0/3004698
2024-08-21 01:31:48.159 PDT [9916] LOG:  consistent recovery state reached at 0/3009268
2024-08-21 01:31:48.159 PDT [9440] LOG:  database system is ready to accept read-only connections
2024-08-21 01:31:48.159 PDT [9916] LOG:  invalid record length at 0/3009268: expected at least 24, got 0
2024-08-21 01:31:48.216 PDT [2520] 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-08-21 01:31:48.398 PDT [4844] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:48.399 PDT [4844] 040_pg_createsubscriber.pl 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-08-21 01:31:48.436 PDT [11820] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:48.436 PDT [11820] 040_pg_createsubscriber.pl LOG:  statement: SELECT s.subname FROM pg_catalog.pg_subscription s INNER JOIN pg_catalog.pg_database d ON (s.subdbid = d.oid) WHERE d.datname = 'regression	 !#$%&''()*+,-'
pg_createsubscriber: dropping subscription "regress_sub_dummy" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: dropping publication "pg_createsubscriber_16384_fee3863f" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: creating subscription "pg_createsubscriber_16384_fee3863f" in database "regression	 !#$%&'()*+,-"
2024-08-21 01:31:48.440 PDT [11820] 040_pg_createsubscriber.pl LOG:  statement: SELECT s.oid FROM pg_catalog.pg_subscription s INNER JOIN pg_catalog.pg_database d ON (s.subdbid = d.oid) WHERE s.subname = 'pg_createsubscriber_16384_fee3863f' AND d.datname = 'regression	 !#$%&''()*+,-'
pg_createsubscriber: setting the replication progress (node name "pg_0" ; LSN 0/0) in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: enabling subscription "pg_createsubscriber_16384_fee3863f" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: dropping the replication slot "physical_slot" in database "regression	 !#$%&'()*+,-"
2024-08-21 01:31:48.502 PDT [6300] 040_pg_createsubscriber.pl LOG:  statement: SELECT pg_catalog.set_config('search_path', '', false);
2024-08-21 01:31:48.503 PDT [6300] 040_pg_createsubscriber.pl LOG:  statement: SELECT slot_name FROM pg_catalog.pg_replication_slots WHERE failover
pg_createsubscriber: dropping the replication slot "failover_slot" in database "regression	 !#$%&'()*+,-"
pg_createsubscriber: stopping the subscriber
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...2024-08-21 01:31:48.543 PDT [9440] LOG:  received fast shutdown request
2024-08-21 01:31:48.543 PDT [9440] LOG:  aborting any active transactions
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.2024-08-21 01:31:48.543 PDT [2520] FATAL:  terminating walreceiver process due to administrator command
2024-08-21 01:31:48.552 PDT [13088] LOG:  shutting down
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
pg_createsubscriber: server was stopped
pg_createsubscriber: modifying system identifier of subscriber
pg_createsubscriber: system identifier is 7405507632376257708 on subscriber
pg_createsubscriber: running pg_resetwal on the subscriber
pg_createsubscriber: Done!
[01:31:48.954](1.841s) ok 29 - run pg_createsubscriber without --databases
# Running: pg_createsubscriber --verbose --recovery-timeout 180 --verbose --pgdata T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=15396 host=127.0.0.1 dbname='regression	 !#$%&\'()*+,-' --socketdir 127.0.0.1 --subscriber-port 15398 --publication pub1 --publication Pub2 --replication-slot replslot1 --replication-slot replslot2 --database regression	 !#$%&'()*+,- --database regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ
pg_createsubscriber: validating publisher connection string
pg_createsubscriber: validating subscriber connection string
pg_createsubscriber: pg_ctl path is:  T:/pgtest/postgresql/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe
pg_createsubscriber: pg_resetwal path is:  T:/pgtest/postgresql/build/tmp_install/usr/local/pgsql/bin/pg_resetwal.exe
pg_createsubscriber: checking if directory "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory
pg_createsubscriber: publisher(0): publication: pub1 ; replication slot: replslot1 ; connection string: host=127.0.0.1 port=15396 dbname='regression	 !#$%&\'()*+,-'
pg_createsubscriber: subscriber(0): subscription: (auto) ; connection string: port=15398 fallback_application_name=pg_createsubscriber dbname='regression	 !#$%&\'()*+,-'
pg_createsubscriber: publisher(1): publication: Pub2 ; replication slot: replslot2 ; connection string: host=127.0.0.1 port=15396 dbname='regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ'
pg_createsubscriber: subscriber(1): subscription: (auto) ; connection string: port=15398 fallback_application_name=pg_createsubscriber dbname='regression./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ'
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7405504253965176896 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7405504253965176896 on subscriber
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
pg_createsubscriber: starting the standby with command-line options
pg_createsubscriber: pg_ctl command is: "T:/pgtest/postgresql/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe" start -D T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata -s -o "-c sync_replication_slots=off" -o "-p 15398"
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!pgwin32_open_handle| fileFlags: 8000, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 0, err: 5, ntstatus: C0000056
!!!pgwin32_open_handle| fileFlags: 502, err: 5, ntstatus: C0000056
2024-08-21 01:31:49.116 PDT [5540] FATAL:  could not create lock file (ntries: 11) "postmaster.pid": File exists
!!!pgwin32_open_handle| fileFlags: 8000, err: 5, ntstatus: C0000056
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
2024-08-21 01:31:49.335 PDT [9440] LOG:  database system is shut down
pg_createsubscriber: error: connection to database failed: connection to server at "127.0.0.1", port 15398 failed: Connection refused (0x0000274D/10061)
	Is the server running on that host and accepting TCP/IP connections?
pg_createsubscriber: pg_ctl command is: "T:/pgtest/postgresql/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe" stop -D "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" 
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
pg_ctl: PID file "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid" does not exist
Is server running?
pg_createsubscriber: error: pg_ctl failed with exit code 1
pg_createsubscriber: detail: The failed command was: "T:/pgtest/postgresql/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe" stop -D "T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" 
[01:31:51.688](2.734s) not ok 30 - run pg_createsubscriber on node S
[01:31:51.688](0.000s) #   Failed test 'run pg_createsubscriber on node S'
#   at T:/pgtest/postgresql/src/bin/pg_basebackup_15/t/040_pg_createsubscriber.pl line 360.
[01:31:51.755](0.066s) not ok 31 - the physical replication slot used as primary_slot_name has been removed
[01:31:51.755](0.000s) #   Failed test 'the physical replication slot used as primary_slot_name has been removed'
#   at T:/pgtest/postgresql/src/bin/pg_basebackup_15/t/040_pg_createsubscriber.pl line 382.
[01:31:51.755](0.000s) #          got: '1'
#     expected: '0'
### Starting node "node_s"
# Running: pg_ctl -w -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
waiting for server to start...!!!pgwin32_open_handle| fileFlags: 8000, err: 2, ntstatus: C0000034
. done
server started
# Postmaster PID for node "node_s" is 13632
[01:31:52.313](0.558s) not ok 32 - pre-existing subscription was dropped
[01:31:52.313](0.000s) #   Failed test 'pre-existing subscription was dropped'
#   at T:/pgtest/postgresql/src/bin/pg_basebackup_15/t/040_pg_createsubscriber.pl line 398.
[01:31:52.313](0.000s) #          got: '1'
#     expected: '0'
Waiting for all subscriptions in "node_s" to synchronize data
subscription name must be specified at T:/pgtest/postgresql/src/bin/pg_basebackup_15/t/040_pg_createsubscriber.pl line 408.
# Postmaster PID for node "node_p" is 9856
### Stopping node "node_p" using mode immediate
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -m immediate stop
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_p_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
# No postmaster PID for node "node_p"
# No postmaster PID for node "node_f"
# Postmaster PID for node "node_s" is 13632
### Stopping node "node_s" using mode immediate
# Running: pg_ctl -D T:\pgtest\postgresql\build/testrun/pg_basebackup_15/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -m immediate stop
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
waiting for server to shut down...!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
.!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: 3, errno: 22
!!!pgwin32_open_handle| fileFlags: 0, err: 2, ntstatus: C0000034
!!!pgwin32_fopen| fileName: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, fd: -1, errno: 2
!!!get_pgpid| pid_file: T:/pgtest/postgresql/build/testrun/pg_basebackup_15/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/postmaster.pid, pidf: 0000000000000000, errno: 2
!!!wait_for_postmaster_stop| pid: 0
 done
server stopped
# No postmaster PID for node "node_s"
# No postmaster PID for node "node_t"
# No postmaster PID for node "node_c"
[01:31:53.501](1.188s) # Tests were run but no plan was declared and done_testing() was not seen.
[01:31:53.501](0.000s) # Looks like your test exited with 25 just after 32.
