021_row_visibility_primary.log

2021-03-02 17:20:31.759 PST [3428] LOG:  starting PostgreSQL 14devel, compiled by Visual C++ build 1900, 64-bit
2021-03-02 17:20:31.761 PST [3428] LOG:  listening on IPv4 address "127.0.0.1", port 57417
2021-03-02 17:20:31.778 PST [4180] LOG:  database system was shut down at 2021-03-02 17:20:31 PST
2021-03-02 17:20:31.789 PST [3428] LOG:  database system is ready to accept connections
2021-03-02 17:20:31.923 PST [2532] 021_row_visibility.pl LOG:  statement: CREATE TABLE public.test_visibility (data text not null)
2021-03-02 17:20:32.024 PST [6012] 021_row_visibility.pl LOG:  received replication command: SHOW data_directory_mode
2021-03-02 17:20:32.024 PST [6012] 021_row_visibility.pl STATEMENT:  SHOW data_directory_mode
2021-03-02 17:20:32.025 PST [6012] 021_row_visibility.pl LOG:  received replication command: SHOW wal_segment_size
2021-03-02 17:20:32.025 PST [6012] 021_row_visibility.pl STATEMENT:  SHOW wal_segment_size
2021-03-02 17:20:32.025 PST [6012] 021_row_visibility.pl LOG:  received replication command: IDENTIFY_SYSTEM
2021-03-02 17:20:32.025 PST [6012] 021_row_visibility.pl STATEMENT:  IDENTIFY_SYSTEM
2021-03-02 17:20:32.025 PST [6012] 021_row_visibility.pl LOG:  received replication command: BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS  FAST NOWAIT    MANIFEST 'yes' 
2021-03-02 17:20:32.025 PST [6012] 021_row_visibility.pl STATEMENT:  BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS  FAST NOWAIT    MANIFEST 'yes' 
2021-03-02 17:20:32.539 PST [4992] 021_row_visibility.pl LOG:  received replication command: SHOW data_directory_mode
2021-03-02 17:20:32.539 PST [4992] 021_row_visibility.pl STATEMENT:  SHOW data_directory_mode
2021-03-02 17:20:32.539 PST [4992] 021_row_visibility.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_4992" TEMPORARY PHYSICAL RESERVE_WAL
2021-03-02 17:20:32.539 PST [4992] 021_row_visibility.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_4992" TEMPORARY PHYSICAL RESERVE_WAL
2021-03-02 17:20:32.542 PST [4992] 021_row_visibility.pl LOG:  received replication command: IDENTIFY_SYSTEM
2021-03-02 17:20:32.542 PST [4992] 021_row_visibility.pl STATEMENT:  IDENTIFY_SYSTEM
2021-03-02 17:20:32.543 PST [4992] 021_row_visibility.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_4992" 0/2000000 TIMELINE 1
2021-03-02 17:20:32.543 PST [4992] 021_row_visibility.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_4992" 0/2000000 TIMELINE 1
2021-03-02 17:20:43.208 PST [2984] standby LOG:  received replication command: IDENTIFY_SYSTEM
2021-03-02 17:20:43.208 PST [2984] standby STATEMENT:  IDENTIFY_SYSTEM
2021-03-02 17:20:43.208 PST [2984] standby LOG:  received replication command: START_REPLICATION 0/3000000 TIMELINE 1
2021-03-02 17:20:43.208 PST [2984] standby STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2021-03-02 17:20:43.627 PST [4516] 021_row_visibility.pl LOG:  statement: INSERT INTO test_visibility VALUES ('first insert')
2021-03-02 17:20:43.804 PST [5280] 021_row_visibility.pl LOG:  statement: SELECT pg_current_wal_insert_lsn()
2021-03-02 17:20:43.918 PST [3412] 021_row_visibility.pl LOG:  statement: SELECT '0/3002640' <= replay_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby';
2021-03-02 17:20:43.940 PST [3948] 021_row_visibility.pl LOG:  statement: BEGIN;
2021-03-02 17:20:43.940 PST [3948] 021_row_visibility.pl LOG:  statement: UPDATE test_visibility SET data = 'first update' RETURNING data;
2021-03-02 17:20:44.020 PST [6028] 021_row_visibility.pl LOG:  statement: SELECT txid_current();
2021-03-02 17:20:44.062 PST [4900] 021_row_visibility.pl LOG:  statement: SELECT pg_current_wal_insert_lsn()
2021-03-02 17:20:44.093 PST [5336] 021_row_visibility.pl LOG:  statement: SELECT '0/30026B8' <= replay_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby';
2021-03-02 17:20:44.127 PST [3948] 021_row_visibility.pl LOG:  statement: COMMIT;
2021-03-02 17:20:44.155 PST [5060] 021_row_visibility.pl LOG:  statement: SELECT pg_current_wal_insert_lsn()
2021-03-02 17:20:44.220 PST [5256] 021_row_visibility.pl LOG:  statement: SELECT '0/30026E0' <= replay_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby';
2021-03-02 17:20:44.255 PST [3948] 021_row_visibility.pl LOG:  statement: DELETE from test_visibility;
2021-03-02 17:20:44.256 PST [3948] 021_row_visibility.pl LOG:  statement: BEGIN;
2021-03-02 17:20:44.256 PST [3948] 021_row_visibility.pl LOG:  statement: INSERT INTO test_visibility VALUES('inserted in prepared will_commit');
2021-03-02 17:20:44.257 PST [3948] 021_row_visibility.pl LOG:  statement: PREPARE TRANSACTION 'will_commit';
2021-03-02 17:20:44.257 PST [3948] 021_row_visibility.pl LOG:  statement: BEGIN;
2021-03-02 17:20:44.258 PST [3948] 021_row_visibility.pl LOG:  statement: INSERT INTO test_visibility VALUES('inserted in prepared will_abort');
2021-03-02 17:20:44.258 PST [3948] 021_row_visibility.pl LOG:  statement: PREPARE TRANSACTION 'will_abort';
2021-03-02 17:20:44.299 PST [4168] 021_row_visibility.pl LOG:  statement: SELECT pg_current_wal_insert_lsn()
2021-03-02 17:20:44.350 PST [5756] 021_row_visibility.pl LOG:  statement: SELECT '0/30029E0' <= replay_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby';
2021-03-02 17:20:44.418 PST [4248] 021_row_visibility.pl LOG:  statement: COMMIT PREPARED 'will_commit';
2021-03-02 17:20:44.476 PST [3916] 021_row_visibility.pl LOG:  statement: ROLLBACK PREPARED 'will_abort';
2021-03-02 17:20:44.543 PST [4580] 021_row_visibility.pl LOG:  statement: SELECT pg_current_wal_insert_lsn()
2021-03-02 17:20:44.618 PST [5512] 021_row_visibility.pl LOG:  statement: SELECT '0/3002A40' <= replay_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby';
2021-03-02 17:20:44.723 PST [2984] standby ERROR:  canceling statement due to user request
2021-03-02 17:20:44.723 PST [2984] standby STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2021-03-02 17:20:44.723 PST [2508] ERROR:  canceling statement due to user request
2021-03-02 17:20:44.723 PST [4052] ERROR:  canceling statement due to user request
2021-03-02 17:20:44.724 PST [3428] LOG:  received fast shutdown request
2021-03-02 17:20:44.731 PST [3428] LOG:  aborting any active transactions
2021-03-02 17:20:44.731 PST [3948] 021_row_visibility.pl FATAL:  terminating connection due to administrator command
2021-03-02 17:20:44.740 PST [3428] LOG:  background worker "logical replication launcher" (PID 2508) exited with exit code 1
2021-03-02 17:20:44.752 PST [2112] LOG:  shutting down
2021-03-02 17:20:44.775 PST [3428] LOG:  database system is shut down