# Checking port 57438
# Found port 57438
Name: publisher
Data directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata
Backup directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/backup
Archive directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/archives
Connection string: port=57438 host=C:/Windows/TEMP/4fjEeHtaR1
Log file: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_publisher.log
[23:08:52.613](0.136s) # 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/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata --create-role repl_role
### Starting node "publisher"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata -l C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_publisher.log -o --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 7712
# Checking port 57439
# Found port 57439
Name: subscriber1
Data directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata
Backup directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/backup
Archive directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/archives
Connection string: port=57439 host=C:/Windows/TEMP/4fjEeHtaR1
Log file: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_subscriber1.log
[23:08:54.213](1.601s) # 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/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata
### Starting node "subscriber1"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata -l C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_subscriber1.log -o --cluster-name=subscriber1 start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber1" is 2328
[23:08:56.136](1.923s) ok 1 - logical slot has failover false on the publisher
[23:08:57.076](0.939s) ok 2 - logical slot has failover true on the publisher
[23:08:57.763](0.688s) ok 3 - logical slot has failover false on the publisher
[23:08:58.039](0.275s) ok 4 - logical slot has failover true on the publisher
[23:08:58.436](0.398s) ok 5 - altering failover is not allowed for enabled subscription
[23:08:58.584](0.148s) ok 6 - cannot sync slots on a non-standby server
# Taking pg_basebackup backup from node "publisher"
# Running: pg_basebackup -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/backup/backup -h C:/Windows/TEMP/4fjEeHtaR1 -p 57438 --checkpoint fast --no-sync
# Backup finished
# Checking port 57440
# Found port 57440
Name: standby1
Data directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata
Backup directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/backup
Archive directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/archives
Connection string: port=57440 host=C:/Windows/TEMP/4fjEeHtaR1
Log file: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_standby1.log
# Initializing node "standby1" from backup "backup" of node "publisher"
### Enabling streaming replication for node "standby1"
### Enabling WAL restore for node "standby1"
### Reloading node "publisher"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata reload
server signaled
(lsub2_slot,0/3000098)
(sb1_slot,)
### Starting node "standby1"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata -l C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_standby1.log -o --cluster-name=standby1 start
waiting for server to start.... done
server started
# Postmaster PID for node "standby1" is 6652
Waiting for replication conn regress_mysub1's replay_lsn to pass 0/3000098 on publisher
done
Waiting for replication conn standby1's replay_lsn to pass 0/3000098 on publisher
done
[23:09:12.871](14.287s) ok 7 - logical slots have synced as true on standby

[23:09:13.418](0.547s) ok 8 - synchronized slot has been dropped
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
Waiting for replication conn standby1's replay_lsn to pass 0/40000F8 on publisher
done
[23:09:14.453](1.036s) ok 9 - synchronized slot has been invalidated
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
#### Begin standard error
psql:<stdin>:2: NOTICE:  dropped replication slot "lsub1_slot" on publisher
psql:<stdin>:3: NOTICE:  created replication slot "lsub1_slot" on publisher
#### End standard error
Waiting for replication conn regress_mysub1's replay_lsn to pass 0/4000130 on publisher
done
Waiting for replication conn standby1's replay_lsn to pass 0/4000130 on publisher
done
[23:09:19.300](4.847s) ok 10 - logical slot is re-synced
### Reloading node "publisher"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata reload
server signaled
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
[23:09:19.925](0.625s) ok 11 - logical decoding is not allowed on synced slot
[23:09:20.016](0.091s) ok 12 - synced slot on standby cannot be altered
[23:09:20.166](0.150s) ok 13 - synced slot on standby cannot be dropped
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
[23:09:20.520](0.354s) ok 14 - cannot sync slots if dbname is not specified in primary_conninfo
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
# Taking pg_basebackup backup2 from node "standby1"
# Running: pg_basebackup -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/backup/backup2 -h C:/Windows/TEMP/4fjEeHtaR1 -p 57440 --checkpoint fast --no-sync
# Backup finished
# Checking port 57441
# Found port 57441
Name: cascading_standby
Data directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_cascading_standby_data/pgdata
Backup directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_cascading_standby_data/backup
Archive directory: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_cascading_standby_data/archives
Connection string: port=57441 host=C:/Windows/TEMP/4fjEeHtaR1
Log file: C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_cascading_standby.log
# Initializing node "cascading_standby" from backup "backup2" of node "standby1"
### Enabling streaming replication for node "cascading_standby"
### Enabling WAL restore for node "cascading_standby"
(cascading_sb_slot,)
### Starting node "cascading_standby"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_cascading_standby_data/pgdata -l C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_cascading_standby.log -o --cluster-name=cascading_standby start
waiting for server to start.... done
server started
# Postmaster PID for node "cascading_standby" is 4524
[23:09:32.255](11.736s) ok 15 - cannot sync slots to a cascading standby server
### Stopping node "cascading_standby" using mode fast
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_cascading_standby_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "cascading_standby"
Waiting for replication conn standby1's replay_lsn to pass 0/443A638 on publisher
done
### Stopping node "standby1" using mode fast
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby1"
### Starting node "standby1"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata -l C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\log/040_standby_failover_slots_sync_standby1.log -o --cluster-name=standby1 start
waiting for server to start..... done
server started
# Postmaster PID for node "standby1" is 5312
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
### Reloading node "standby1"
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload
server signaled
Waiting for all subscriptions in "subscriber1" to synchronize data
[23:17:43.241](490.985s) # poll_query_until timed out executing this query:
# SELECT count(1) = 0 FROM pg_subscription_rel WHERE srsubstate NOT IN ('r', 's');
# expecting this output:
# t
# last actual query output:
# f
# with stderr:
timed out waiting for subscriber to synchronize data at C:/cirrus/src/test/recovery/t/040_standby_failover_slots_sync.pl line 480.
# Postmaster PID for node "publisher" is 7712
### Stopping node "publisher" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_publisher_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "publisher"
# Postmaster PID for node "subscriber1" is 2328
### Stopping node "subscriber1" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "subscriber1"
# Postmaster PID for node "standby1" is 5312
### Stopping node "standby1" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/recovery/040_standby_failover_slots_sync\data/t_040_standby_failover_slots_sync_standby1_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby1"
# No postmaster PID for node "cascading_standby"
[23:17:43.620](0.379s) # Tests were run but no plan was declared and done_testing() was not seen.
[23:17:43.620](0.000s) # Looks like your test exited with 255 just after 15.