031_recovery_conflict_primary.log

2023-08-08 22:20:22.732 UTC [778626] 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 22:20:22.732 UTC [778626] LOG:  listening on Unix socket "/tmp/S8kif7vda0/.s.PGSQL.59145"
2023-08-08 22:20:22.733 UTC [778630] LOG:  database system was shut down at 2023-08-08 22:20:22 UTC
2023-08-08 22:20:22.755 UTC [778626] LOG:  database system is ready to accept connections
2023-08-08 22:20:22.832 UTC [778639] 031_recovery_conflict.pl LOG:  statement: CREATE TABLESPACE test_recovery_conflict_tblspc LOCATION ''
2023-08-08 22:20:22.964 UTC [778644] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-08 22:20:22.964 UTC [778644] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-08 22:20:22.969 UTC [778644] 031_recovery_conflict.pl LOG:  received replication command: SHOW wal_segment_size
2023-08-08 22:20:22.969 UTC [778644] 031_recovery_conflict.pl STATEMENT:  SHOW wal_segment_size
2023-08-08 22:20:22.975 UTC [778644] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-08 22:20:22.975 UTC [778644] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-08 22:20:22.991 UTC [778644] 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 22:20:22.991 UTC [778644] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-08 22:20:22.998 UTC [778628] LOG:  checkpoint starting: immediate force wait
2023-08-08 22:20:23.006 UTC [778628] 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=11350 kB, estimate=11350 kB; lsn=0/2000060, redo lsn=0/2000028
2023-08-08 22:20:23.027 UTC [778654] 031_recovery_conflict.pl LOG:  received replication command: SHOW data_directory_mode
2023-08-08 22:20:23.027 UTC [778654] 031_recovery_conflict.pl STATEMENT:  SHOW data_directory_mode
2023-08-08 22:20:23.035 UTC [778654] 031_recovery_conflict.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_778654" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-08 22:20:23.035 UTC [778654] 031_recovery_conflict.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_778654" TEMPORARY PHYSICAL ( RESERVE_WAL)
2023-08-08 22:20:23.073 UTC [778654] 031_recovery_conflict.pl LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-08 22:20:23.073 UTC [778654] 031_recovery_conflict.pl STATEMENT:  IDENTIFY_SYSTEM
2023-08-08 22:20:23.140 UTC [778654] 031_recovery_conflict.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_778654" 0/2000000 TIMELINE 1
2023-08-08 22:20:23.140 UTC [778654] 031_recovery_conflict.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_778654" 0/2000000 TIMELINE 1
2023-08-08 22:20:23.679 UTC [778644] 031_recovery_conflict.pl LOG:  temporary file: path "base/pgsql_tmp/pgsql_tmp778644.0", size 137324
2023-08-08 22:20:23.679 UTC [778644] 031_recovery_conflict.pl STATEMENT:  BASE_BACKUP ( LABEL 'pg_basebackup base backup',  PROGRESS,  CHECKPOINT 'fast',  WAIT 0,  MANIFEST 'yes',  TARGET 'client')
2023-08-08 22:20:24.216 UTC [778709] standby LOG:  received replication command: IDENTIFY_SYSTEM
2023-08-08 22:20:24.216 UTC [778709] standby STATEMENT:  IDENTIFY_SYSTEM
2023-08-08 22:20:24.216 UTC [778709] standby LOG:  received replication command: START_REPLICATION 0/3000000 TIMELINE 1
2023-08-08 22:20:24.216 UTC [778709] standby STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2023-08-08 22:20:24.377 UTC [778711] 031_recovery_conflict.pl LOG:  statement: CREATE DATABASE test_db
2023-08-08 22:20:24.427 UTC [778722] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table1(a int, b int);
2023-08-08 22:20:24.430 UTC [778722] 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 22:20:24.442 UTC [778722] 031_recovery_conflict.pl LOG:  statement: CREATE TABLE test_recovery_conflict_table2(a int, b int);
2023-08-08 22:20:24.469 UTC [778740] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-08 22:20:24.479 UTC [778743] 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 22:20:24.675 UTC [778767] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-08 22:20:24.675 UTC [778767] 031_recovery_conflict.pl LOG:  statement: INSERT INTO test_recovery_conflict_table1 VALUES (1,0);
2023-08-08 22:20:24.676 UTC [778767] 031_recovery_conflict.pl LOG:  statement: ROLLBACK;
2023-08-08 22:20:24.676 UTC [778767] 031_recovery_conflict.pl LOG:  statement: BEGIN;
2023-08-08 22:20:24.676 UTC [778767] 031_recovery_conflict.pl LOG:  statement: LOCK test_recovery_conflict_table1;
2023-08-08 22:20:24.676 UTC [778767] 031_recovery_conflict.pl LOG:  statement: COMMIT;
2023-08-08 22:20:24.692 UTC [778773] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-08 22:20:24.710 UTC [778783] 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 22:20:25.051 UTC [778797] 031_recovery_conflict.pl LOG:  statement: VACUUM test_recovery_conflict_table1;
2023-08-08 22:20:25.142 UTC [778883] 031_recovery_conflict.pl LOG:  statement: SELECT pg_current_wal_flush_lsn()
2023-08-08 22:20:25.173 UTC [778886] 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 22:20:25.344 UTC [778912] 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 22:23:50.441 UTC [778626] LOG:  received immediate shutdown request
2023-08-08 22:23:50.456 UTC [796024] [unknown] FATAL:  the database system is shutting down
2023-08-08 22:23:50.955 UTC [796036] [unknown] FATAL:  the database system is shutting down
2023-08-08 22:23:50.988 UTC [778626] LOG:  database system is shut down