# Checking port 59524 # Found port 59524 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=59524 host=/tmp/dkl8yaKE1e Log file: /tmp/cirrus-ci-build/build/testrun/recovery/031_recovery_conflict/log/031_recovery_conflict_primary.log [20:00:57.542](0.027s) # 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 13345 #### 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/dkl8yaKE1e -p 59524 --checkpoint fast --no-sync # Backup finished # Checking port 59525 # Found port 59525 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=59525 host=/tmp/dkl8yaKE1e 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 13444 #### 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/3444678 on primary done Waiting for replication conn standby's replay_lsn to pass 0/3444738 on primary done [20:00:58.239](0.697s) # 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; [20:00:58.242](0.003s) ok 1 - buffer pin conflict: cursor with conflicting pin established Waiting for replication conn standby's replay_lsn to pass 0/344C000 on primary done [20:00:58.424](0.183s) ok 2 - buffer pin conflict: logfile contains terminated connection due to recovery conflict [20:00:58.628](0.204s) ok 3 - buffer pin conflict: stats show conflict on standby Waiting for replication conn standby's replay_lsn to pass 0/344CF78 on primary done [20:00:58.766](0.138s) # 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; # [20:00:58.783](0.017s) ok 4 - snapshot conflict: cursor with conflicting snapshot established Waiting for replication conn standby's replay_lsn to pass 0/344D4B0 on primary done [20:00:58.995](0.213s) ok 5 - snapshot conflict: logfile contains terminated connection due to recovery conflict [20:00:59.065](0.070s) not ok 6 - snapshot conflict: stats show conflict on standby [20:00:59.066](0.001s) # Failed test 'snapshot conflict: stats show conflict on standby' # at /tmp/cirrus-ci-build/src/test/recovery/t/031_recovery_conflict.pl line 332. [20:00:59.066](0.000s) # got: '2' # expected: '1' [20:00:59.067](0.000s) # issuing query via background psql: # BEGIN; # LOCK TABLE test_recovery_conflict_table1 IN ACCESS SHARE MODE; # SELECT 1; # [20:00:59.091](0.025s) ok 7 - lock conflict: conflicting lock acquired Waiting for replication conn standby's replay_lsn to pass 0/344DC40 on primary done [20:00:59.207](0.115s) ok 8 - lock conflict: logfile contains terminated connection due to recovery conflict [20:00:59.249](0.042s) ok 9 - lock conflict: stats show conflict on standby [20:00:59.249](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; # [20:00:59.253](0.004s) ok 10 - tablespace conflict: cursor with conflicting temp file established Waiting for replication conn standby's replay_lsn to pass 0/344DCE0 on primary done [20:00:59.436](0.184s) ok 11 - tablespace conflict: logfile contains terminated connection due to recovery conflict [20:00:59.488](0.051s) 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 13789 Waiting for replication conn standby's replay_lsn to pass 0/3450A48 on primary done [20:00:59.804](0.317s) ok 13 - startup deadlock: cursor holding conflicting pin, also waiting for lock, established [20:00:59.824](0.019s) ok 14 - startup deadlock: lock acquisition is waiting Waiting for replication conn standby's replay_lsn to pass 0/3450A48 on primary done [20:00:59.987](0.163s) ok 15 - startup deadlock: logfile contains terminated connection due to recovery conflict [20:01:00.029](0.043s) ok 16 - startup deadlock: 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 13900 [20:01:00.319](0.290s) not ok 17 - 5 recovery conflicts shown in pg_stat_database [20:01:00.320](0.001s) # Failed test '5 recovery conflicts shown in pg_stat_database' # at /tmp/cirrus-ci-build/src/test/recovery/t/031_recovery_conflict.pl line 286. [20:01:00.321](0.000s) # got: '6' # expected: '5' Waiting for replication conn standby's replay_lsn to pass 0/34571B8 on primary done [20:01:00.402](0.081s) 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" [20:01:00.617](0.216s) 1..18 [20:01:00.620](0.003s) # Looks like you failed 2 tests of 18.