# Running: pg_createsubscriber --help
[06:40:41.205](0.115s) ok 1 - pg_createsubscriber --help exit code 0
[06:40:41.206](0.000s) ok 2 - pg_createsubscriber --help goes to stdout
[06:40:41.206](0.000s) ok 3 - pg_createsubscriber --help nothing to stderr
[06:40:41.207](0.001s) ok 4 - pg_createsubscriber --help maximum line length
# Running: pg_createsubscriber --version
[06:40:41.265](0.058s) ok 5 - pg_createsubscriber --version exit code 0
[06:40:41.271](0.006s) ok 6 - pg_createsubscriber --version goes to stdout
[06:40:41.271](0.001s) ok 7 - pg_createsubscriber --version nothing to stderr
# Running: pg_createsubscriber --not-a-valid-option
[06:40:41.390](0.118s) ok 8 - pg_createsubscriber with invalid option nonzero exit code
[06:40:41.390](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.
[06:40:41.469](0.079s) ok 10 - no subscriber data directory specified
# Running: pg_createsubscriber --pgdata C:\cirrus\build\testrun\pg_basebackup\040_pg_createsubscriber\data\tmp_test_xMjq
pg_createsubscriber: error: no publisher connection string specified
pg_createsubscriber: hint: Try "pg_createsubscriber --help" for more information.
[06:40:41.546](0.077s) ok 11 - no publisher connection string specified
# Running: pg_createsubscriber --verbose --pgdata C:\cirrus\build\testrun\pg_basebackup\040_pg_createsubscriber\data\tmp_test_xMjq --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.
[06:40:41.606](0.060s) ok 12 - no database name specified
# Checking port 61621
# Found port 61621
Name: node_p
Data directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata
Backup directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/backup
Archive directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/archives
Connection string: port=61621 host=C:/Windows/TEMP/0dwXlyUQRY
Log file: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log
[06:40:41.643](0.037s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata
### Starting node "node_p"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log -o --cluster-name=node_p start
waiting for server to start.... done
server started
# Postmaster PID for node "node_p" is 5780
# Checking port 61622
# Found port 61622
Name: node_f
Data directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata
Backup directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/backup
Archive directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/archives
Connection string: port=61622 host=C:/Windows/TEMP/0dwXlyUQRY
Log file: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_f.log
[06:40:42.984](1.341s) # initializing database system by running initdb
# Running: initdb -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata -A trust -N
The files belonging to this database system will be owned by user "SYSTEM".
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 C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... windows
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... Atlantic/Reykjavik
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 ^"C^:^\cirrus^\build^/testrun^/pg^_basebackup^/040^_pg^_createsubscriber^\data^/t^_040^_pg^_createsubscriber^_node^_f^_data^/pgdata^" -l logfile start

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata
### Starting node "node_f"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata -l C:\cirrus\build/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 7844
# Taking pg_basebackup backup_1 from node "node_p"
# Running: pg_basebackup -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/backup/backup_1 -h C:/Windows/TEMP/0dwXlyUQRY -p 61621 --checkpoint fast --no-sync
# Backup finished
# Checking port 61623
# Found port 61623
Name: node_s
Data directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata
Backup directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/backup
Archive directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/archives
Connection string: port=61623 host=C:/Windows/TEMP/0dwXlyUQRY
Log file: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log
# Initializing node "node_s" from backup "backup_1" of node "node_p"
### Enabling streaming replication for node "node_s"
### Starting node "node_s"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
waiting for server to start.... done
server started
# Postmaster PID for node "node_s" is 5592
# Running: pg_createsubscriber --verbose --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_f_data/pgdata --publisher-server port=61621 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61622 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: checking if directory "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_f_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345734292283246236 on subscriber
pg_createsubscriber: error: subscriber data directory is not a copy of the source database cluster
[06:40:51.369](8.384s) 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 C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/backup/backup_2 -h C:/Windows/TEMP/0dwXlyUQRY -p 61623 --checkpoint fast --no-sync
# Backup finished
# Checking port 61624
# Found port 61624
Name: node_c
Data directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/pgdata
Backup directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/backup
Archive directory: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/archives
Connection string: port=61624 host=C:/Windows/TEMP/0dwXlyUQRY
Log file: C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_c.log
# Initializing node "node_c" from backup "backup_2" of node "node_s"
### Enabling streaming replication for node "node_c"
### Starting node "node_c"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/pgdata -l C:\cirrus\build/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 7016
# Running: pg_createsubscriber --verbose --dry-run --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/pgdata --publisher-server port=61623 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61624 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: checking if directory "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_c_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345732929255240240 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-13 06:41:04.401 GMT [5984][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-13 06:41:04.403 GMT [5984][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61624"
2024-03-13 06:41:04.427 GMT [4900][startup] LOG:  database system was shut down in recovery at 2024-03-13 06:41:04 GMT
2024-03-13 06:41:04.427 GMT [4900][startup] LOG:  entering standby mode
2024-03-13 06:41:04.431 GMT [4900][startup] LOG:  redo starts at 0/2000028
2024-03-13 06:41:04.431 GMT [4900][startup] LOG:  consistent recovery state reached at 0/3000000
2024-03-13 06:41:04.431 GMT [5984][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-13 06:41:04.469 GMT [7992][walreceiver] FATAL:  could not start WAL streaming: ERROR:  replication slot "physical_slot" does not exist
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: error: connection to database failed: invalid port number: "1165657104"
2024-03-13 06:41:04.535 GMT [5868][walreceiver] FATAL:  could not start WAL streaming: ERROR:  replication slot "physical_slot" does not exist
2024-03-13 06:41:04.536 GMT [4900][startup] LOG:  waiting for WAL to become available at 0/3000018
[06:41:04.764](13.395s) ok 14 - primary server is in recovery
### Stopping node "node_c" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_c_data/pgdata -m immediate stop
2024-03-13 06:41:04.778 GMT [5984][postmaster] LOG:  received immediate shutdown request
waiting for server to shut down....2024-03-13 06:41:04.786 GMT [5984][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/3000148 on node_p
done
### Restarting node "node_p"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "node_p" is 7340
# Running: pg_createsubscriber --verbose --dry-run --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=61621 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61623 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: checking if directory "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345732929255240240 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-13 06:41:05.925 GMT [1180][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-13 06:41:05.927 GMT [1180][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61623"
2024-03-13 06:41:05.963 GMT [3196][startup] LOG:  database system was shut down in recovery at 2024-03-13 06:41:05 GMT
2024-03-13 06:41:05.963 GMT [3196][startup] LOG:  entering standby mode
2024-03-13 06:41:05.968 GMT [3196][startup] LOG:  consistent recovery state reached at 0/30001C0
2024-03-13 06:41:05.968 GMT [3196][startup] LOG:  invalid record length at 0/30001C0: expected at least 24, got 0
2024-03-13 06:41:05.968 GMT [1180][postmaster] LOG:  database system is ready to accept read-only connections
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: error: connection to database failed: invalid port number: "1555083248"
2024-03-13 06:41:06.013 GMT [6844][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-03-13 06:41:06.014 GMT [3196][startup] LOG:  redo starts at 0/30001C0
[06:41:06.296](1.532s) ok 15 - primary contains unmet conditions on node P
### Restarting node "node_s"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log restart
2024-03-13 06:41:06.328 GMT [1180][postmaster] LOG:  received fast shutdown request
waiting for server to shut down...2024-03-13 06:41:06.328 GMT [1180][postmaster] LOG:  aborting any active transactions
.2024-03-13 06:41:06.328 GMT [6844][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-13 06:41:06.336 GMT [440][checkpointer] LOG:  shutting down
2024-03-13 06:41:06.341 GMT [1180][postmaster] LOG:  database system is shut down
 done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "node_s" is 6004
# Running: pg_createsubscriber --verbose --dry-run --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=61621 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61623 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: checking if directory "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345732929255240240 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-13 06:41:06.895 GMT [4100][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-13 06:41:06.897 GMT [4100][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61623"
2024-03-13 06:41:06.924 GMT [2028][startup] LOG:  database system was shut down in recovery at 2024-03-13 06:41:06 GMT
2024-03-13 06:41:06.925 GMT [2028][startup] LOG:  entering standby mode
2024-03-13 06:41:06.929 GMT [2028][startup] LOG:  redo starts at 0/30001C0
2024-03-13 06:41:06.929 GMT [2028][startup] LOG:  consistent recovery state reached at 0/3004708
2024-03-13 06:41:06.929 GMT [4100][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-13 06:41:06.930 GMT [2028][startup] LOG:  invalid record length at 0/3004708: expected at least 24, got 0
2024-03-13 06:41:06.971 GMT [7328][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: error: connection to database failed: invalid port number: "1029129600"
[06:41:07.265](0.969s) ok 16 - standby contains unmet conditions on node S
### Restarting node "node_s"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log restart
waiting for server to shut down...2024-03-13 06:41:07.285 GMT [4100][postmaster] LOG:  received fast shutdown request
2024-03-13 06:41:07.285 GMT [4100][postmaster] LOG:  aborting any active transactions
.2024-03-13 06:41:07.285 GMT [7328][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-13 06:41:07.290 GMT [2680][checkpointer] LOG:  shutting down
2024-03-13 06:41:07.294 GMT [4100][postmaster] LOG:  database system is shut down
 done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "node_s" is 6788
### Restarting node "node_p"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_p.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "node_p" is 2916
# Running: pg_createsubscriber --verbose --dry-run --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=61621 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61623 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: checking if directory "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345732929255240240 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-13 06:41:08.196 GMT [3948][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-13 06:41:08.198 GMT [3948][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61623"
2024-03-13 06:41:08.229 GMT [2320][startup] LOG:  database system was shut down in recovery at 2024-03-13 06:41:07 GMT
2024-03-13 06:41:08.230 GMT [2320][startup] LOG:  entering standby mode
2024-03-13 06:41:08.233 GMT [2320][startup] LOG:  consistent recovery state reached at 0/3004780
2024-03-13 06:41:08.233 GMT [3948][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-13 06:41:08.233 GMT [2320][startup] LOG:  invalid record length at 0/3004780: expected at least 24, got 0
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: error: connection to database failed: invalid integer value "3811522960" for connection option "port"
2024-03-13 06:41:08.272 GMT [7232][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-03-13 06:41:08.273 GMT [2320][startup] LOG:  redo starts at 0/3004780
[06:41:08.561](1.297s) not ok 17 - run pg_createsubscriber --dry-run on node S
[06:41:08.562](0.001s) #   Failed test 'run pg_createsubscriber --dry-run on node S'
#   at C:/cirrus/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 173.
2024-03-13 06:41:08.613 GMT [6032][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-13 06:41:08.615 GMT [6032][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-13 06:41:08.615 GMT [6032][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=040_pg_createsubscriber.pl
2024-03-13 06:41:08.628 GMT [6032][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-03-13 06:41:08.633 GMT [6032][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=SYSTEM database=postgres host=[local]
[06:41:08.678](0.116s) ok 18 - standby is in recovery
# Running: pg_createsubscriber --verbose --dry-run --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=61621 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61623
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 "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" is a cluster data directory
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345732929255240240 on subscriber
pg_createsubscriber: standby is up and running
pg_createsubscriber: stopping the server to start the transformation steps
2024-03-13 06:41:08.788 GMT [3948][postmaster] LOG:  received fast shutdown request
2024-03-13 06:41:08.789 GMT [3948][postmaster] LOG:  aborting any active transactions
2024-03-13 06:41:08.789 GMT [7232][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-13 06:41:08.796 GMT [7104][checkpointer] LOG:  shutting down
2024-03-13 06:41:08.802 GMT [3948][postmaster] LOG:  database system is shut down
pg_createsubscriber: server was stopped
pg_createsubscriber: starting the standby with command-line options
2024-03-13 06:41:09.029 GMT [2096][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-13 06:41:09.030 GMT [2096][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61623"
2024-03-13 06:41:09.054 GMT [5688][startup] LOG:  database system was shut down in recovery at 2024-03-13 06:41:08 GMT
2024-03-13 06:41:09.055 GMT [5688][startup] LOG:  entering standby mode
2024-03-13 06:41:09.071 GMT [5688][startup] LOG:  redo starts at 0/3004780
2024-03-13 06:41:09.071 GMT [5688][startup] LOG:  consistent recovery state reached at 0/30047B8
2024-03-13 06:41:09.071 GMT [2096][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-13 06:41:09.071 GMT [5688][startup] LOG:  invalid record length at 0/30047B8: expected at least 24, got 0
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: error: connection to database failed: invalid port number: "2140750720"
2024-03-13 06:41:09.117 GMT [3900][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
[06:41:09.378](0.700s) not ok 19 - run pg_createsubscriber without --databases
[06:41:09.378](0.000s) #   Failed test 'run pg_createsubscriber without --databases'
#   at C:/cirrus/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 191.
# Running: pg_createsubscriber --verbose --verbose --pgdata C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata --publisher-server port=61621 host=C:/Windows/TEMP/0dwXlyUQRY dbname='pg1' --socket-directory C:/Windows/TEMP/0dwXlyUQRY --subscriber-port 61623 --database pg1 --database pg2
pg_createsubscriber: validating connection string on publisher
pg_createsubscriber: validating connection string on subscriber
pg_createsubscriber: pg_ctl path is:  C:/cirrus/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe
pg_createsubscriber: pg_resetwal path is:  C:/cirrus/build/tmp_install/usr/local/pgsql/bin/pg_resetwal.exe
pg_createsubscriber: checking if directory "C:/cirrus/build/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=C:/Windows/TEMP/0dwXlyUQRY port=61621 dbname=pg1
pg_createsubscriber: subscriber(0): connection string: port=484004512 user=SYSTEM fallback_application_name=pg_createsubscriber dbname=pg1
pg_createsubscriber: publisher(1): connection string: host=C:/Windows/TEMP/0dwXlyUQRY port=61621 dbname=pg2
pg_createsubscriber: subscriber(1): connection string: port=484004512 user=SYSTEM fallback_application_name=pg_createsubscriber dbname=pg2
pg_createsubscriber: getting system identifier from publisher
pg_createsubscriber: system identifier is 7345732929255240240 on publisher
pg_createsubscriber: getting system identifier from subscriber
pg_createsubscriber: system identifier is 7345732929255240240 on subscriber
pg_createsubscriber: standby is up and running
pg_createsubscriber: stopping the server to start the transformation steps
pg_createsubscriber: pg_ctl command is: "C:/cirrus/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe" stop -D "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s
2024-03-13 06:41:09.485 GMT [2096][postmaster] LOG:  received fast shutdown request
2024-03-13 06:41:09.485 GMT [2096][postmaster] LOG:  aborting any active transactions
2024-03-13 06:41:09.485 GMT [3900][walreceiver] FATAL:  terminating walreceiver process due to administrator command
2024-03-13 06:41:09.495 GMT [668][checkpointer] LOG:  shutting down
2024-03-13 06:41:09.500 GMT [2096][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: "C:/cirrus/build/tmp_install/usr/local/pgsql/bin/pg_ctl.exe" start -D "C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata" -s -o "-p 61623"
2024-03-13 06:41:09.700 GMT [8152][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-13 06:41:09.701 GMT [8152][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61623"
2024-03-13 06:41:09.730 GMT [5276][startup] LOG:  database system was shut down in recovery at 2024-03-13 06:41:09 GMT
2024-03-13 06:41:09.730 GMT [5276][startup] LOG:  entering standby mode
2024-03-13 06:41:09.734 GMT [5276][startup] LOG:  redo starts at 0/3004780
2024-03-13 06:41:09.734 GMT [5276][startup] LOG:  consistent recovery state reached at 0/30047B8
2024-03-13 06:41:09.734 GMT [8152][postmaster] LOG:  database system is ready to accept read-only connections
2024-03-13 06:41:09.734 GMT [5276][startup] LOG:  invalid record length at 0/30047B8: expected at least 24, got 0
pg_createsubscriber: server was started
pg_createsubscriber: checking settings on subscriber
pg_createsubscriber: error: connection to database failed: invalid port number: "484004512"
2024-03-13 06:41:09.778 GMT [5148][walreceiver] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
[06:41:10.066](0.688s) not ok 20 - run pg_createsubscriber on node S
[06:41:10.067](0.000s) #   Failed test 'run pg_createsubscriber on node S'
#   at C:/cirrus/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 203.
[06:41:10.134](0.068s) not ok 21 - the physical replication slot used as primary_slot_name has been removed
[06:41:10.135](0.000s) #   Failed test 'the physical replication slot used as primary_slot_name has been removed'
#   at C:/cirrus/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 220.
[06:41:10.135](0.000s) #          got: '1'
#     expected: '0'
### Starting node "node_s"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -l C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\log/040_pg_createsubscriber_node_s.log -o --cluster-name=node_s start
pg_ctl: another server might be running; trying to start server anyway
waiting for server to start... done
server started
# Postmaster PID for node "node_s" is 8152
2024-03-13 06:41:10.623 GMT [6032][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-13 06:41:10.624 GMT [6032][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-13 06:41:10.624 GMT [6032][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=040_pg_createsubscriber.pl
2024-03-13 06:41:10.640 GMT [6032][client backend] [040_pg_createsubscriber.pl][0/2:0] LOG:  statement: SELECT subname FROM pg_subscription WHERE subname ~ '^pg_createsubscriber_'
2024-03-13 06:41:10.644 GMT [6032][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=SYSTEM database=postgres host=[local]
Waiting for all subscriptions in "node_s" to synchronize data
2024-03-13 06:41:10.748 GMT [4408][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-13 06:41:10.749 GMT [4408][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/pg_basebackup/040_pg_createsubscriber/data/t_040_pg_createsubscriber_node_s_data/pgdata/pg_hba.conf:117)
2024-03-13 06:41:10.749 GMT [4408][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=040_pg_createsubscriber.pl
2024-03-13 06:41:10.753 GMT [4408][client backend] [040_pg_createsubscriber.pl][1/2:0] LOG:  statement: SELECT count(1) = 0 FROM pg_subscription_rel WHERE srsubstate NOT IN ('r', 's');
2024-03-13 06:41:10.760 GMT [4408][client backend] [040_pg_createsubscriber.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=SYSTEM database=postgres host=[local]
subscription name must be specified at C:/cirrus/src/bin/pg_basebackup/t/040_pg_createsubscriber.pl line 241.
# Postmaster PID for node "node_p" is 2916
### Stopping node "node_p" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_p_data/pgdata -m immediate stop
waiting for server to shut down....2024-03-13 06:41:10.844 GMT [5148][walreceiver] FATAL:  could not receive data from WAL stream: server closed the connection unexpectedly
		This probably means the server terminated abnormally
		before or while processing the request.
2024-03-13 06:41:10.845 GMT [5276][startup] LOG:  invalid record length at 0/3008E50: expected at least 24, got 0
2024-03-13 06:41:10.879 GMT [3956][walreceiver] FATAL:  could not connect to the primary server: connection to server on socket "C:/Windows/TEMP/0dwXlyUQRY/.s.PGSQL.61621" failed: Connection refused (0x0000274D/10061)
		Is the server running locally and accepting connections on that socket?
2024-03-13 06:41:10.879 GMT [5276][startup] LOG:  waiting for WAL to become available at 0/3008E68
 done
server stopped
# No postmaster PID for node "node_p"
# Postmaster PID for node "node_f" is 7844
### Stopping node "node_f" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/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"
# Postmaster PID for node "node_s" is 8152
### Stopping node "node_s" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/pg_basebackup/040_pg_createsubscriber\data/t_040_pg_createsubscriber_node_s_data/pgdata -m immediate stop
2024-03-13 06:41:11.088 GMT [8152][postmaster] LOG:  received immediate shutdown request
waiting for server to shut down....2024-03-13 06:41:11.094 GMT [8152][postmaster] LOG:  database system is shut down
 done
server stopped
# No postmaster PID for node "node_s"
# No postmaster PID for node "node_c"
[06:41:11.195](1.060s) # Tests were run but no plan was declared and done_testing() was not seen.
[06:41:11.195](0.000s) # Looks like your test exited with 25 just after 21.