031_recovery_conflict_primary.log

2023-08-04 12:20:36.343 UTC [1920003] LOG:  starting PostgreSQL 17devel (Ubuntu 17~~devel-1) on s390x-ibm-linux-gnu, compiled by gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, 64-bit
2023-08-04 12:20:36.343 UTC [1920003] LOG:  listening on Unix socket "/tmp/GoFBiPMX9Y/.s.PGSQL.55090"
2023-08-04 12:20:36.345 UTC [1920006] LOG:  database system was shut down at 2023-08-04 12:20:36 UTC
2023-08-04 12:20:36.351 UTC [1920003] LOG:  database system is ready to accept connections
2023-08-04 12:20:36.445 UTC [1920011] 031_recovery_conflict.pl LOG:  statement: CREATE TABLESPACE test_recovery_conflict_tblspc LOCATION ''
2023-08-04 12:20:36.475 UTC [1920013] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-04 12:20:36.475 UTC [1920013] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-04 12:20:36.480 UTC [1920013] 031_recovery_conflict.pl LOG:  received replication command: SHOW wal_segment_size
2023-08-04 12:20:36.480 UTC [1920013] 031_recovery_conflict.pl STATEMENT:  SHOW wal_segment_size
2023-08-04 12:20:36.485 UTC [1920013] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-04 12:20:36.485 UTC [1920013] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-04 12:20:36.491 UTC [1920013] 031_recovery_conflict.pl LOG:  received replication command: BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-04 12:20:36.491 UTC [1920013] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-04 12:20:36.498 UTC [1920004] LOG:  checkpoint starting: immediate force wait
2023-08-04 12:20:36.505 UTC [1920004] LOG:  checkpoint complete: wrote 7 buffers (5.5%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.001 s, sync=0.001 s, total=0.008 s; sync files=0, longest=0.000 s, average=0.000 s; distance=11360 kB, estimate=11360 kB; lsn=0/2000060, redo lsn=0/2000028
2023-08-04 12:20:36.519 UTC [1920014] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-04 12:20:36.519 UTC [1920014] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-04 12:20:36.520 UTC [1920014] 031_recovery_conflict.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_1920014" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-04 12:20:36.520 UTC [1920014] 031_recovery_conflict.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_1920014" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-04 12:20:36.530 UTC [1920014] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-04 12:20:36.530 UTC [1920014] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-04 12:20:36.536 UTC [1920014] 031_recovery_conflict.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_1920014" 0/2000000 TIMELINE 1
2023-08-04 12:20:36.536 UTC [1920014] 031_recovery_conflict.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_1920014" 0/2000000 TIMELINE 1
2023-08-04 12:20:36.746 UTC [1920013] 031_recovery_conflict.pl LOG:  temporary file: path "base/pgsql_tmp/pgsql_tmp1920013.0", size 137324
2023-08-04 12:20:36.746 UTC [1920013] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-04 12:20:36.899 UTC [1920025] standby LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-04 12:20:36.899 UTC [1920025] standby STATEMENT:  IDENTIFY_SYSTEM
2023-08-04 12:20:36.935 UTC [1920025] standby LOG:  received replication command: START_REPLICATION 0/3000000 TIMELINE 1
2023-08-04 12:20:36.935 UTC [1920025] standby STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2023-08-04 12:20:36.990 UTC [1920027] 031_recovery_conflict.pl LOG:  statement: CREATE DATABASE test_db
2023-08-04 12:20:37.036 UTC [1920029] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table1(a int, b int);
2023-08-04 12:20:37.037 UTC [1920029] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 SELECT i % 3, 0 FROM generate_series(1,20) i;
2023-08-04 12:20:37.037 UTC [1920029] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table2(a int, b int);
2023-08-04 12:20:37.223 UTC [1920031] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-04 12:20:37.241 UTC [1920033] 031_recovery_conflict.pl LOG:  statement: SELECT '0/3429B90' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:20:37.283 UTC [1920037] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-04 12:20:37.305 UTC [1920037] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 VALUES (1,0);
2023-08-04 12:20:37.310 UTC [1920037] 031_recovery_conflict.pl LOG:  statement: ROLLBACK;
2023-08-04 12:20:37.314 UTC [1920037] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-04 12:20:37.314 UTC [1920037] 031_recovery_conflict.pl LOG:  statement: LOCK test_recovery_conflict_table1;
2023-08-04 12:20:37.314 UTC [1920037] 031_recovery_conflict.pl LOG:  statement: COMMIT;
2023-08-04 12:20:37.324 UTC [1920039] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-04 12:20:37.335 UTC [1920041] 031_recovery_conflict.pl LOG:  statement: SELECT '0/3429C50' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:20:37.369 UTC [1920043] 031_recovery_conflict.pl LOG:  statement: VACUUM test_recovery_conflict_table1;
2023-08-04 12:20:37.389 UTC [1920045] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-04 12:20:37.400 UTC [1920047] 031_recovery_conflict.pl LOG:  statement: SELECT '0/3430000' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:20:37.549 UTC [1920049] 031_recovery_conflict.pl LOG:  statement: SELECT '0/3430000' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:20:37.624 UTC [1920057] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 SELECT i, 0 FROM generate_series(1,20) i
2023-08-04 12:20:37.654 UTC [1920059] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-04 12:20:37.678 UTC [1920061] 031_recovery_conflict.pl LOG:  statement: SELECT '0/3432470' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:20:37.716 UTC [1920063] 031_recovery_conflict.pl LOG:  statement: UPDATE test_recovery_conflict_table1 SET a = a + 1 WHERE a > 2;
2023-08-04 12:20:37.734 UTC [1920065] 031_recovery_conflict.pl LOG:  statement: VACUUM test_recovery_conflict_table1;
2023-08-04 12:20:37.751 UTC [1920067] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-04 12:20:37.766 UTC [1920069] 031_recovery_conflict.pl LOG:  statement: SELECT '0/34329A8' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:20:37.980 UTC [1920077] 031_recovery_conflict.pl LOG:  statement: DROP TABLE test_recovery_conflict_table1;
2023-08-04 12:20:38.061 UTC [1920079] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-04 12:20:38.165 UTC [1920081] 031_recovery_conflict.pl LOG:  statement: SELECT '0/34330C0' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-04 12:23:41.116 UTC [1920003] LOG:  received immediate shutdown request
2023-08-04 12:23:41.123 UTC [1920003] LOG:  database system is shut down