# Checking port 49818 # Found port 49818 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/archives Connection string: port=49818 host=/tmp/M0faFMXotl Log file: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_primary.log [09:11:04.005](0.014s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 14946 #### Begin standard error psql::1: WARNING: tablespaces created by regression test cases should have names starting with "regress_" #### End standard error # Taking pg_basebackup my_backup from node "primary" # Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/backup/my_backup -h /tmp/M0faFMXotl -p 49818 --checkpoint fast --no-sync # Backup finished # Checking port 49819 # Found port 49819 Name: standby Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/archives Connection string: port=49819 host=/tmp/M0faFMXotl Log file: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_standby.log # Initializing node "standby" from backup "my_backup" of node "primary" ### Enabling streaming replication for node "standby" ### Starting node "standby" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_standby.log -o --cluster-name=standby start waiting for server to start.... done server started # Postmaster PID for node "standby" is 14980 #### Begin standard error psql::1: WARNING: databases created by regression test cases should have names including "regression" #### End standard error Waiting for replication conn standby's replay_lsn to pass 0/3444490 on primary done Waiting for replication conn standby's replay_lsn to pass 0/3444550 on primary done [09:11:05.830](1.825s) # issuing query via background psql: # BEGIN; # DECLARE test_recovery_conflict_cursor CURSOR FOR SELECT b FROM test_recovery_conflict_table1; # FETCH FORWARD FROM test_recovery_conflict_cursor; [09:11:05.832](0.002s) ok 1 - buffer pin conflict: cursor with conflicting pin established Waiting for replication conn standby's replay_lsn to pass 0/344C868 on primary done [09:11:05.995](0.163s) ok 2 - buffer pin conflict: logfile contains terminated connection due to recovery conflict [09:11:06.081](0.085s) ok 3 - buffer pin conflict: stats show conflict on standby Waiting for replication conn standby's replay_lsn to pass 0/344CD90 on primary done [09:11:06.151](0.071s) # issuing query via background psql: # BEGIN; # DECLARE test_recovery_conflict_cursor CURSOR FOR SELECT b FROM test_recovery_conflict_table1; # FETCH FORWARD FROM test_recovery_conflict_cursor; # [09:11:06.186](0.034s) ok 4 - snapshot conflict: cursor with conflicting snapshot established Waiting for replication conn standby's replay_lsn to pass 0/344D2C8 on primary done [09:11:06.360](0.175s) ok 5 - snapshot conflict: logfile contains terminated connection due to recovery conflict [09:11:06.403](0.043s) ok 6 - snapshot conflict: stats show conflict on standby [09:11:06.404](0.001s) # issuing query via background psql: # BEGIN; # LOCK TABLE test_recovery_conflict_table1 IN ACCESS SHARE MODE; # SELECT 1; # [09:11:06.407](0.002s) ok 7 - lock conflict: conflicting lock acquired Waiting for replication conn standby's replay_lsn to pass 0/344DA58 on primary done [09:11:06.582](0.176s) ok 8 - lock conflict: logfile contains terminated connection due to recovery conflict [09:11:06.617](0.034s) ok 9 - lock conflict: stats show conflict on standby [09:11:06.617](0.001s) # issuing query via background psql: # BEGIN; # SET work_mem = '64kB'; # DECLARE test_recovery_conflict_cursor CURSOR FOR # SELECT count(*) FROM generate_series(1,6000); # FETCH FORWARD FROM test_recovery_conflict_cursor; # [09:11:06.622](0.004s) ok 10 - tablespace conflict: cursor with conflicting temp file established Waiting for replication conn standby's replay_lsn to pass 0/344DAF8 on primary done [09:11:06.807](0.185s) ok 11 - tablespace conflict: logfile contains terminated connection due to recovery conflict [09:11:06.881](0.074s) ok 12 - tablespace conflict: stats show conflict on standby ### Restarting node "standby" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_standby.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "standby" is 15225 Waiting for replication conn standby's replay_lsn to pass 0/3450860 on primary done [09:11:07.151](0.270s) ok 13 - startup deadlock: cursor holding conflicting pin, also waiting for lock, established [09:11:07.172](0.021s) ok 14 - startup deadlock: lock acquisition is waiting Waiting for replication conn standby's replay_lsn to pass 0/3450860 on primary done [09:11:07.310](0.138s) ok 15 - startup deadlock: logfile contains terminated connection due to recovery conflict [09:11:07.336](0.025s) not ok 16 - startup deadlock: stats show conflict on standby [09:11:07.336](0.001s) # Failed test 'startup deadlock: stats show conflict on standby' # at /tmp/cirrus-ci-build/src/test/recovery/t/031_recovery_conflict.pl line 332. [09:11:07.338](0.001s) # got: '0' # expected: '1' ### Restarting node "standby" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_standby.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "standby" is 15275 [09:11:07.591](0.254s) ok 17 - 5 recovery conflicts shown in pg_stat_database Waiting for replication conn standby's replay_lsn to pass 0/3456FD0 on primary done [09:11:07.627](0.035s) ok 18 - database conflict: logfile contains terminated connection due to recovery conflict ### Stopping node "standby" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_standby_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "standby" ### Stopping node "primary" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/data/t_031_recovery_conflict_primary_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "primary" [09:11:07.849](0.222s) 1..18 [09:11:07.851](0.002s) # Looks like you failed 1 test of 18.