031_recovery_conflict_primary.log

2023-08-08 08:52:33.312 UTC [1734403] LOG:  starting PostgreSQL 17devel (Debian 17~~devel-1) on s390x-ibm-linux-gnu, compiled by gcc (Debian 13.2.0-1) 13.2.0, 64-bit
2023-08-08 08:52:33.312 UTC [1734403] LOG:  listening on Unix socket "/tmp/UNCayI_17k/.s.PGSQL.63827"
2023-08-08 08:52:33.318 UTC [1734406] LOG:  database system was shut down at 2023-08-08 08:52:33 UTC
2023-08-08 08:52:33.350 UTC [1734403] LOG:  database system is ready to accept connections
2023-08-08 08:52:33.477 UTC [1734411] 031_recovery_conflict.pl LOG:  statement: CREATE TABLESPACE test_recovery_conflict_tblspc LOCATION ''
2023-08-08 08:52:33.484 UTC [1734413] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-08 08:52:33.484 UTC [1734413] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-08 08:52:33.485 UTC [1734413] 031_recovery_conflict.pl LOG:  received replication command: SHOW wal_segment_size
2023-08-08 08:52:33.485 UTC [1734413] 031_recovery_conflict.pl STATEMENT:  SHOW wal_segment_size
2023-08-08 08:52:33.485 UTC [1734413] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-08 08:52:33.485 UTC [1734413] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-08 08:52:33.485 UTC [1734413] 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-08 08:52:33.485 UTC [1734413] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-08 08:52:33.490 UTC [1734404] LOG:  checkpoint starting: immediate force wait
2023-08-08 08:52:33.500 UTC [1734404] 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.011 s; sync files=0, longest=0.000 s, average=0.000 s; distance=11350 kB, estimate=11350 kB; lsn=0/2000060, redo lsn=0/2000028
2023-08-08 08:52:33.507 UTC [1734414] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-08 08:52:33.507 UTC [1734414] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-08 08:52:33.508 UTC [1734414] 031_recovery_conflict.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_1734414" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-08 08:52:33.508 UTC [1734414] 031_recovery_conflict.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_1734414" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-08 08:52:33.513 UTC [1734414] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-08 08:52:33.513 UTC [1734414] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-08 08:52:33.518 UTC [1734414] 031_recovery_conflict.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_1734414" 0/2000000 TIMELINE 1
2023-08-08 08:52:33.518 UTC [1734414] 031_recovery_conflict.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_1734414" 0/2000000 TIMELINE 1
2023-08-08 08:52:33.596 UTC [1734413] 031_recovery_conflict.pl LOG:  temporary file: path "base/pgsql_tmp/pgsql_tmp1734413.0", size 137324
2023-08-08 08:52:33.596 UTC [1734413] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-08 08:52:33.741 UTC [1734425] standby LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-08 08:52:33.741 UTC [1734425] standby STATEMENT:  IDENTIFY_SYSTEM
2023-08-08 08:52:33.766 UTC [1734425] standby LOG:  received replication command: START_REPLICATION 0/3000000 TIMELINE 1
2023-08-08 08:52:33.766 UTC [1734425] standby STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2023-08-08 08:52:33.823 UTC [1734427] 031_recovery_conflict.pl LOG:  statement: CREATE DATABASE test_db
2023-08-08 08:52:33.878 UTC [1734429] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table1(a int, b int);
2023-08-08 08:52:33.887 UTC [1734429] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 SELECT i % 3, 0 FROM generate_series(1,20) i;
2023-08-08 08:52:33.898 UTC [1734429] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table2(a int, b int);
2023-08-08 08:52:33.936 UTC [1734431] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-08 08:52:33.962 UTC [1734433] 031_recovery_conflict.pl LOG:  statement: SELECT '0/342B100' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-08 08:52:34.013 UTC [1734437] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-08 08:52:34.018 UTC [1734437] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 VALUES (1,0);
2023-08-08 08:52:34.024 UTC [1734437] 031_recovery_conflict.pl LOG:  statement: ROLLBACK;
2023-08-08 08:52:34.029 UTC [1734437] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-08 08:52:34.034 UTC [1734437] 031_recovery_conflict.pl LOG:  statement: LOCK test_recovery_conflict_table1;
2023-08-08 08:52:34.040 UTC [1734437] 031_recovery_conflict.pl LOG:  statement: COMMIT;
2023-08-08 08:52:34.072 UTC [1734439] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-08 08:52:34.097 UTC [1734441] 031_recovery_conflict.pl LOG:  statement: SELECT '0/342B1C0' <= replay_lsn AND state = 'streaming'
	         FROM pg_catalog.pg_stat_replication
	         WHERE application_name IN ('standby', 'walreceiver')
2023-08-08 08:52:34.149 UTC [1734443] 031_recovery_conflict.pl LOG:  statement: VACUUM test_recovery_conflict_table1;
2023-08-08 08:52:34.192 UTC [1734445] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-08 08:52:34.211 UTC [1734447] 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-08 08:52:34.348 UTC [1734449] 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-08 08:52:34.479 UTC [1734451] 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-08 08:52:34.617 UTC [1734453] 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-08 08:55:38.427 UTC [1734403] LOG:  received immediate shutdown request
2023-08-08 08:55:38.437 UTC [1734403] LOG:  database system is shut down