# Checking port 50347 # Found port 50347 Name: publisher Data directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/backup Archive directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/archives Connection string: port=50347 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/duDwW2fXpP Log file: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/log/040_standby_failover_slots_sync_publisher.log [18:44:34.028](0.026s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/pgdata -l /Users/admin/pgsql/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 15086 # Checking port 50348 # Found port 50348 Name: subscriber1 Data directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_subscriber1_data/backup Archive directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_subscriber1_data/archives Connection string: port=50348 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/duDwW2fXpP Log file: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/log/040_standby_failover_slots_sync_subscriber1.log [18:44:34.598](0.570s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_subscriber1_data/pgdata -l /Users/admin/pgsql/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 15171 [18:44:35.176](0.578s) ok 1 - logical slot has failover false on the publisher [18:44:35.235](0.059s) ok 2 - logical slot has failover true on the publisher [18:44:35.283](0.048s) ok 3 - logical slot has failover false on the publisher [18:44:35.323](0.040s) ok 4 - logical slot has failover true on the publisher [18:44:35.355](0.032s) ok 5 - altering failover is not allowed for enabled subscription [18:44:35.374](0.020s) ok 6 - cannot sync slots on a non-standby server # Taking pg_basebackup backup from node "publisher" # Running: pg_basebackup -D /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/backup/backup -h /var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/duDwW2fXpP -p 50347 --checkpoint fast --no-sync # Backup finished # Checking port 50349 # Found port 50349 Name: standby1 Data directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/backup Archive directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/archives Connection string: port=50349 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/duDwW2fXpP Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_publisher_data/pgdata reload server signaled (lsub2_slot,0/3000060) (sb1_slot,) ### Starting node "standby1" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/pgdata -l /Users/admin/pgsql/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 15364 Waiting for replication conn regress_mysub1's replay_lsn to pass 0/3000060 on publisher done Waiting for replication conn standby1's replay_lsn to pass 0/3000060 on publisher done [18:44:36.463](1.089s) ok 7 - logical slots have synced as true on standby [18:44:36.564](0.101s) ok 8 - synchronized slot has been dropped ### Reloading node "standby1" # Running: pg_ctl -D /Users/admin/pgsql/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 [18:44:36.924](0.359s) ok 9 - synchronized slot has been invalidated ### Reloading node "standby1" # Running: pg_ctl -D /Users/admin/pgsql/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::2: NOTICE: dropped replication slot "lsub1_slot" on publisher psql::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 [18:44:37.265](0.341s) ok 10 - logical slot is re-synced ### Reloading node "publisher" # Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload server signaled [18:44:37.295](0.030s) ok 11 - logical decoding is not allowed on synced slot [18:44:37.310](0.015s) ok 12 - synced slot on standby cannot be altered [18:44:37.325](0.015s) ok 13 - synced slot on standby cannot be dropped ### Reloading node "standby1" # Running: pg_ctl -D /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/pgdata reload server signaled [18:44:37.356](0.031s) ok 14 - cannot sync slots if dbname is not specified in primary_conninfo ### Reloading node "standby1" # Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/backup/backup2 -h /var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/duDwW2fXpP -p 50349 --checkpoint fast --no-sync # Backup finished # Checking port 50350 # Found port 50350 Name: cascading_standby Data directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_cascading_standby_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_cascading_standby_data/backup Archive directory: /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_cascading_standby_data/archives Connection string: port=50350 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/duDwW2fXpP Log file: /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_cascading_standby_data/pgdata -l /Users/admin/pgsql/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 15834 [18:44:38.285](0.930s) ok 15 - cannot sync slots to a cascading standby server ### Stopping node "cascading_standby" using mode fast # Running: pg_ctl -D /Users/admin/pgsql/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/4000238 on publisher done [18:44:38.647](0.362s) ok 16 - confirmed_flush_lsn of slot snap_test_slot synced to standby Waiting for replication conn standby1's replay_lsn to pass 0/4434538 on publisher done ### Stopping node "standby1" using mode fast # Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/040_standby_failover_slots_sync/data/t_040_standby_failover_slots_sync_standby1_data/pgdata -l /Users/admin/pgsql/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 16100 ### Reloading node "standby1" # Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/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 /Users/admin/pgsql/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 /Users/admin/pgsql/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 [18:48:56.663](258.016s) # 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 /Users/admin/pgsql/src/test/recovery/t/040_standby_failover_slots_sync.pl line 542. # Postmaster PID for node "publisher" is 15086 ### Stopping node "publisher" using mode immediate # Running: pg_ctl -D /Users/admin/pgsql/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 15171 ### Stopping node "subscriber1" using mode immediate # Running: pg_ctl -D /Users/admin/pgsql/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 16100 ### Stopping node "standby1" using mode immediate # Running: pg_ctl -D /Users/admin/pgsql/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" [18:48:57.041](0.378s) # Tests were run but no plan was declared and done_testing() was not seen. [18:48:57.041](0.000s) # Looks like your test exited with 60 just after 16.