031_recovery_conflict_primary.log

2023-08-10 01:00:48.765 UTC [892297] 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-10 01:00:48.765 UTC [892297] LOG:  listening on Unix socket "/tmp/5M7Y6uSm5n/.s.PGSQL.63013"
2023-08-10 01:00:48.769 UTC [892300] LOG:  database system was shut down at 2023-08-10 01:00:48 UTC
2023-08-10 01:00:48.772 UTC [892297] LOG:  database system is ready to accept connections
2023-08-10 01:00:48.867 UTC [892305] 031_recovery_conflict.pl LOG:  statement: CREATE TABLESPACE test_recovery_conflict_tblspc LOCATION ''
2023-08-10 01:00:48.889 UTC [892307] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-10 01:00:48.889 UTC [892307] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-10 01:00:48.896 UTC [892307] 031_recovery_conflict.pl LOG:  received replication command: SHOW wal_segment_size
2023-08-10 01:00:48.896 UTC [892307] 031_recovery_conflict.pl STATEMENT:  SHOW wal_segment_size
2023-08-10 01:00:48.901 UTC [892307] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-10 01:00:48.901 UTC [892307] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-10 01:00:48.907 UTC [892307] 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-10 01:00:48.907 UTC [892307] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-10 01:00:48.913 UTC [892298] LOG:  checkpoint starting: immediate force wait
2023-08-10 01:00:48.921 UTC [892298] 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.009 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-10 01:00:48.939 UTC [892308] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-10 01:00:48.939 UTC [892308] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-10 01:00:48.944 UTC [892308] 031_recovery_conflict.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_892308" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-10 01:00:48.944 UTC [892308] 031_recovery_conflict.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_892308" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-10 01:00:48.950 UTC [892308] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-10 01:00:48.950 UTC [892308] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-10 01:00:48.952 UTC [892308] 031_recovery_conflict.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_892308" 0/2000000 TIMELINE 1
2023-08-10 01:00:48.952 UTC [892308] 031_recovery_conflict.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_892308" 0/2000000 TIMELINE 1
2023-08-10 01:00:49.188 UTC [892307] 031_recovery_conflict.pl LOG:  temporary file: path "base/pgsql_tmp/pgsql_tmp892307.0", size 137324
2023-08-10 01:00:49.188 UTC [892307] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-10 01:00:49.487 UTC [892319] standby LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-10 01:00:49.487 UTC [892319] standby STATEMENT:  IDENTIFY_SYSTEM
2023-08-10 01:00:49.493 UTC [892319] standby LOG:  received replication command: START_REPLICATION 0/3000000 TIMELINE 1
2023-08-10 01:00:49.493 UTC [892319] standby STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2023-08-10 01:00:49.546 UTC [892321] 031_recovery_conflict.pl LOG:  statement: CREATE DATABASE test_db
2023-08-10 01:00:49.598 UTC [892323] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table1(a int, b int);
2023-08-10 01:00:49.603 UTC [892323] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 SELECT i % 3, 0 FROM generate_series(1,20) i;
2023-08-10 01:00:49.609 UTC [892323] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table2(a int, b int);
2023-08-10 01:00:49.627 UTC [892325] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-10 01:00:49.648 UTC [892327] 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-10 01:00:49.696 UTC [892331] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-10 01:00:49.702 UTC [892331] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 VALUES (1,0);
2023-08-10 01:00:49.707 UTC [892331] 031_recovery_conflict.pl LOG:  statement: ROLLBACK;
2023-08-10 01:00:49.712 UTC [892331] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-10 01:00:49.712 UTC [892331] 031_recovery_conflict.pl LOG:  statement: LOCK test_recovery_conflict_table1;
2023-08-10 01:00:49.718 UTC [892331] 031_recovery_conflict.pl LOG:  statement: COMMIT;
2023-08-10 01:00:49.742 UTC [892333] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-10 01:00:49.766 UTC [892335] 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-10 01:00:49.806 UTC [892337] 031_recovery_conflict.pl LOG:  statement: VACUUM test_recovery_conflict_table1;
2023-08-10 01:00:49.843 UTC [892339] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-10 01:00:49.857 UTC [892341] 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-10 01:00:50.359 UTC [892343] 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-10 01:03:54.140 UTC [892297] LOG:  received immediate shutdown request
2023-08-10 01:03:54.148 UTC [892297] LOG:  database system is shut down