t/026_overwrite_contrecord.pl ........ 
1..3
ok 1 - 000000010000000000000001 differs from 000000010000000000000002
ok 2 - standby replays past overwritten contrecord
ok 3 - found log line in standby
ok
t/031_recovery_conflict.pl ........... 
1..0 # SKIP disabled until after minor releases, due to instability
skipped: disabled until after minor releases, due to instability
t/033_replay_tsp_drops.pl ............ 
ok 1 - standby node started
ok 2 - invalid directory creation is detected
1..2
ok
t/037_invalid_database.pl ............ 
ok 1 - can't connect to invalid database - error code
ok 2 - can't connect to invalid database - error message
ok 3 - can't ALTER invalid database
ok 4 - can't use invalid database as template
ok 5 - invalid databases are ignored by vac_truncate_clog
ok 6 - can DROP invalid database
ok 7 - can't drop already dropped database
# issuing query via background psql: SELECT pg_backend_pid()
# issuing query via background psql: 
#   CREATE DATABASE regression_invalid_interrupt;
#   BEGIN;
#   LOCK pg_tablespace;
#   PREPARE TRANSACTION 'lock_tblspc';
ok 8 - blocked DROP DATABASE completion
# issuing query via background psql: 
# 	DO $$
# 	BEGIN
# 		WHILE NOT EXISTS(SELECT * FROM pg_locks WHERE NOT granted AND relation = 'pg_tablespace'::regclass AND mode = 'AccessShareLock') LOOP
# 			PERFORM pg_sleep(.1);
# 		END LOOP;
# 	END$$;
# 	SELECT pg_cancel_backend(2496772);
ok 9 - canceling DROP DATABASE
ok 10 - cancel processed
ok 11 - can't connect to invalid_interrupt database
# issuing query via background psql: ROLLBACK PREPARED 'lock_tblspc'
ok 12 - unblock DROP DATABASE
# issuing query via background psql: DROP DATABASE regression_invalid_interrupt
ok 13 - DROP DATABASE invalid_interrupt
1..13
ok
t/039_end_of_wal.pl .................. Dubious, test returned 2 (wstat 512, 0x200)
No subtests run 

Test Summary Report
-------------------
t/039_end_of_wal.pl                (Wstat: 512 Tests: 0 Failed: 0)
  Non-zero exit status: 2
  Parse errors: No plan found in TAP output
Files=26, Tests=257, 102 wallclock secs ( 0.11 usr  0.04 sys + 14.35 cusr 12.78 csys = 27.28 CPU)
Result: FAIL
make[2]: *** [Makefile:23: check] Error 1
make[2]: Leaving directory '/home/myon/projects/postgresql/debian/13/build/src/test/recovery'
make[1]: *** [Makefile:48: check-recovery-recurse] Error 2
make[1]: Leaving directory '/home/myon/projects/postgresql/debian/13/build/src/test'
make: *** [GNUmakefile:71: check-world-src/test-recurse] Error 2
make: Leaving directory '/home/myon/projects/postgresql/debian/13/build'
******** build/src/test/recovery/tmp_check/log/regress_log_031_recovery_conflict ********
1..0 # SKIP disabled until after minor releases, due to instability
******** build/src/test/recovery/tmp_check/log/regress_log_026_overwrite_contrecord ********
1..3
# Checking port 19609
# Found port 19609
Name: primary
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/archives
Connection string: port=19609 host=/home/myon/tmp/yfaXDJawbQ
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/026_overwrite_contrecord_primary.log
# Running: initdb -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata -A trust -N
The files belonging to this database system will be owned by user "myon".
This user must also own the server process.

The database cluster will be initialized with locale "C".
The default database encoding has accordingly been set to "SQL_ASCII".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... sysv
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... Etc/UTC
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok

Sync to disk skipped.
The data directory might become corrupt if the operating system crashes.

Success. You can now start the database server using:

    pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata -l logfile start

# Running: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/../../../src/test/regress/pg_regress --config-auth /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/026_overwrite_contrecord_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 2496532
ok 1 - 000000010000000000000001 differs from 000000010000000000000002
### Stopping node "primary" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
# Taking filesystem backup backup from node "primary"
# Backup finished
# Checking port 19610
# Found port 19610
Name: standby
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_standby_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_standby_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_standby_data/archives
Connection string: port=19610 host=/home/myon/tmp/yfaXDJawbQ
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/026_overwrite_contrecord_standby.log
# Initializing node "standby" from backup "backup" of node "primary"
### Enabling streaming replication for node "standby"
### Starting node "standby"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_standby_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/026_overwrite_contrecord_standby.log -o --cluster-name=standby start
waiting for server to start.... done
server started
# Postmaster PID for node "standby" is 2496562
### Starting node "primary"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/026_overwrite_contrecord_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 2496572
ok 2 - standby replays past overwritten contrecord
ok 3 - found log line in standby
### Promoting node "standby"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_standby_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/026_overwrite_contrecord_standby.log promote
waiting for server to promote.... done
server promoted
### Stopping node "primary" using mode fast
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_primary_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
### Stopping node "standby" using mode fast
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_026_overwrite_contrecord_standby_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby"
******** build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby2.log ********
2024-11-14 15:49:27.409 UTC [2496640] LOG:  starting PostgreSQL 13.17 (Debian 13.17-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-11-14 15:49:27.409 UTC [2496640] LOG:  listening on Unix socket "/home/myon/tmp/ofWde8rhGb/.s.PGSQL.21892"
2024-11-14 15:49:27.411 UTC [2496641] LOG:  database system was interrupted; last known up at 2024-11-14 15:49:27 UTC
2024-11-14 15:49:27.411 UTC [2496641] LOG:  entering standby mode
2024-11-14 15:49:27.412 UTC [2496641] LOG:  redo starts at 0/2000028
2024-11-14 15:49:27.412 UTC [2496641] WARNING:  unexpected directory entry "16385" found in pg_tblspc/
2024-11-14 15:49:27.412 UTC [2496641] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:27.412 UTC [2496641] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:27.412 UTC [2496641] WARNING:  unexpected directory entry "16387" found in pg_tblspc/
2024-11-14 15:49:27.412 UTC [2496641] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:27.412 UTC [2496641] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:27.412 UTC [2496641] WARNING:  unexpected directory entry "16384" found in pg_tblspc/
2024-11-14 15:49:27.412 UTC [2496641] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:27.412 UTC [2496641] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:27.412 UTC [2496641] WARNING:  unexpected directory entry "16386" found in pg_tblspc/
2024-11-14 15:49:27.412 UTC [2496641] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:27.412 UTC [2496641] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:27.412 UTC [2496641] LOG:  consistent recovery state reached at 0/2000100
2024-11-14 15:49:27.413 UTC [2496640] LOG:  database system is ready to accept read only connections
2024-11-14 15:49:27.417 UTC [2496645] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-11-14 15:49:27.746 UTC [2496659] 033_replay_tsp_drops.pl LOG:  statement: ALTER SYSTEM SET log_min_messages TO debug1;
2024-11-14 15:49:27.750 UTC [2496640] LOG:  received immediate shutdown request
2024-11-14 15:49:27.753 UTC [2496640] LOG:  database system is shut down
2024-11-14 15:49:27.872 UTC [2496664] DEBUG:  registering background worker "logical replication launcher"
2024-11-14 15:49:27.872 UTC [2496664] DEBUG:  mmap(4194304) with MAP_HUGETLB failed, huge pages disabled: Cannot allocate memory
2024-11-14 15:49:27.872 UTC [2496664] LOG:  starting PostgreSQL 13.17 (Debian 13.17-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-11-14 15:49:27.872 UTC [2496664] LOG:  listening on Unix socket "/home/myon/tmp/ofWde8rhGb/.s.PGSQL.21892"
2024-11-14 15:49:27.874 UTC [2496665] LOG:  database system was interrupted while in recovery at log time 2024-11-14 15:49:27 UTC
2024-11-14 15:49:27.874 UTC [2496665] HINT:  If this has occurred more than once some data might be corrupted and you might need to choose an earlier recovery target.
2024-11-14 15:49:27.874 UTC [2496665] LOG:  entering standby mode
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  checkpoint record is at 0/2000060
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  redo record is at 0/2000028; shutdown false
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  next transaction ID: 490; next OID: 24576
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  oldest unfrozen transaction ID: 478, in database 1
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  oldest MultiXactId: 1, in database 1
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  commit timestamp Xid oldest/newest: 0/0
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  transaction ID wrap limit is 2147484125, limited by database with OID 1
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID 1
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  starting up replication slots
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  xmin required by slots: data 0, catalog 0
2024-11-14 15:49:27.874 UTC [2496665] DEBUG:  resetting unlogged relations: cleanup 1 init 0
2024-11-14 15:49:27.875 UTC [2496665] DEBUG:  initializing for hot standby
2024-11-14 15:49:27.875 UTC [2496665] LOG:  redo starts at 0/2000028
2024-11-14 15:49:27.875 UTC [2496665] DEBUG:  recovery snapshots are now enabled
2024-11-14 15:49:27.875 UTC [2496665] CONTEXT:  WAL redo at 0/2000028 for Standby/RUNNING_XACTS: nextXid 490 latestCompletedXid 489 oldestRunningXid 490
2024-11-14 15:49:27.875 UTC [2496665] DEBUG:  could not forward fsync request because request queue is full
2024-11-14 15:49:27.875 UTC [2496665] CONTEXT:  WAL redo at 0/3000028 for Heap/INSERT: off 7 flags 0x00
2024-11-14 15:49:27.875 UTC [2496665] DEBUG:  could not forward fsync request because request queue is full
2024-11-14 15:49:27.875 UTC [2496665] CONTEXT:  WAL redo at 0/3000610 for Btree/INSERT_LEAF: off 1
2024-11-14 15:49:27.875 UTC [2496665] DEBUG:  could not forward fsync request because request queue is full
2024-11-14 15:49:27.875 UTC [2496665] CONTEXT:  WAL redo at 0/3000700 for Btree/INSERT_LEAF: off 5
2024-11-14 15:49:27.891 UTC [2496665] DEBUG:  creating missing directory: pg_tblspc/16386/PG_13_202007201
2024-11-14 15:49:27.891 UTC [2496665] CONTEXT:  WAL redo at 0/3015AA0 for Database/CREATE: copy dir 1663/1 to 16386/16394
2024-11-14 15:49:27.914 UTC [2496665] DEBUG:  creating missing directory: base/16388
2024-11-14 15:49:27.914 UTC [2496665] CONTEXT:  WAL redo at 0/3017C08 for Database/CREATE: copy dir 1663/16388 to 1663/16395
2024-11-14 15:49:27.920 UTC [2496665] WARNING:  unexpected directory entry "16387" found in pg_tblspc/
2024-11-14 15:49:27.920 UTC [2496665] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:27.920 UTC [2496665] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:27.920 UTC [2496665] WARNING:  unexpected directory entry "16384" found in pg_tblspc/
2024-11-14 15:49:27.920 UTC [2496665] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:27.920 UTC [2496665] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:27.920 UTC [2496665] LOG:  consistent recovery state reached at 0/301FCF8
2024-11-14 15:49:27.920 UTC [2496665] LOG:  invalid record length at 0/301FCF8: wanted 24, got 0
2024-11-14 15:49:27.921 UTC [2496664] LOG:  database system is ready to accept read only connections
2024-11-14 15:49:27.926 UTC [2496669] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-11-14 15:49:27.968 UTC [2496664] LOG:  received immediate shutdown request
2024-11-14 15:49:27.971 UTC [2496664] LOG:  database system is shut down
******** build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_primary1.log ********
2024-11-14 15:49:26.988 UTC [2496623] LOG:  starting PostgreSQL 13.17 (Debian 13.17-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-11-14 15:49:26.988 UTC [2496623] LOG:  listening on Unix socket "/home/myon/tmp/ofWde8rhGb/.s.PGSQL.21891"
2024-11-14 15:49:26.990 UTC [2496624] LOG:  database system was shut down at 2024-11-14 15:49:26 UTC
2024-11-14 15:49:26.994 UTC [2496623] LOG:  database system is ready to accept connections
2024-11-14 15:49:27.099 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: SET allow_in_place_tablespaces=on;
2024-11-14 15:49:27.099 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLESPACE dropme_ts1 LOCATION '';
2024-11-14 15:49:27.100 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLESPACE dropme_ts2 LOCATION '';
2024-11-14 15:49:27.100 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLESPACE source_ts  LOCATION '';
2024-11-14 15:49:27.100 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLESPACE target_ts  LOCATION '';
2024-11-14 15:49:27.100 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE template_db IS_TEMPLATE = true;
2024-11-14 15:49:27.117 UTC [2496632] 033_replay_tsp_drops.pl LOG:  statement: SELECT pg_create_physical_replication_slot('slot', true);
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl LOG:  received replication command: SHOW data_directory_mode
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl STATEMENT:  SHOW data_directory_mode
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl LOG:  received replication command: SHOW wal_segment_size
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl STATEMENT:  SHOW wal_segment_size
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl LOG:  received replication command: BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS  FAST NOWAIT    MANIFEST 'yes' 
2024-11-14 15:49:27.219 UTC [2496634] 033_replay_tsp_drops.pl STATEMENT:  BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS  FAST NOWAIT    MANIFEST 'yes' 
2024-11-14 15:49:27.254 UTC [2496635] 033_replay_tsp_drops.pl LOG:  received replication command: SHOW data_directory_mode
2024-11-14 15:49:27.254 UTC [2496635] 033_replay_tsp_drops.pl STATEMENT:  SHOW data_directory_mode
2024-11-14 15:49:27.254 UTC [2496635] 033_replay_tsp_drops.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_2496635" TEMPORARY PHYSICAL RESERVE_WAL
2024-11-14 15:49:27.254 UTC [2496635] 033_replay_tsp_drops.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_2496635" TEMPORARY PHYSICAL RESERVE_WAL
2024-11-14 15:49:27.255 UTC [2496635] 033_replay_tsp_drops.pl LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:27.255 UTC [2496635] 033_replay_tsp_drops.pl STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:27.256 UTC [2496635] 033_replay_tsp_drops.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_2496635" 0/2000000 TIMELINE 1
2024-11-14 15:49:27.256 UTC [2496635] 033_replay_tsp_drops.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_2496635" 0/2000000 TIMELINE 1
2024-11-14 15:49:27.417 UTC [2496646] standby2 LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:27.417 UTC [2496646] standby2 STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:27.417 UTC [2496646] standby2 LOG:  received replication command: START_REPLICATION SLOT "slot" 0/3000000 TIMELINE 1
2024-11-14 15:49:27.417 UTC [2496646] standby2 STATEMENT:  START_REPLICATION SLOT "slot" 0/3000000 TIMELINE 1
2024-11-14 15:49:27.508 UTC [2496648] 033_replay_tsp_drops.pl LOG:  statement: SELECT pg_current_wal_lsn()
2024-11-14 15:49:27.542 UTC [2496650] 033_replay_tsp_drops.pl LOG:  statement: SELECT '0/3000000' <= write_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby2';
2024-11-14 15:49:27.584 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE dropme_db1 WITH TABLESPACE dropme_ts1;
2024-11-14 15:49:27.605 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLE t (a int) TABLESPACE dropme_ts2;
2024-11-14 15:49:27.606 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE dropme_db2 WITH TABLESPACE dropme_ts2;
2024-11-14 15:49:27.616 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE moveme_db TABLESPACE source_ts;
2024-11-14 15:49:27.626 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: ALTER DATABASE moveme_db SET TABLESPACE target_ts;
2024-11-14 15:49:27.638 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE newdb TEMPLATE template_db;
2024-11-14 15:49:27.648 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: ALTER DATABASE template_db IS_TEMPLATE = false;
2024-11-14 15:49:27.648 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: DROP DATABASE dropme_db1;
2024-11-14 15:49:27.653 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: DROP TABLE t;
2024-11-14 15:49:27.653 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: DROP DATABASE dropme_db2;
2024-11-14 15:49:27.657 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: DROP TABLESPACE dropme_ts2;
2024-11-14 15:49:27.657 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: DROP TABLESPACE source_ts;
2024-11-14 15:49:27.658 UTC [2496652] 033_replay_tsp_drops.pl LOG:  statement: DROP DATABASE template_db;
2024-11-14 15:49:27.672 UTC [2496654] 033_replay_tsp_drops.pl LOG:  statement: SELECT pg_current_wal_lsn()
2024-11-14 15:49:27.704 UTC [2496656] 033_replay_tsp_drops.pl LOG:  statement: SELECT '0/301FCF8' <= write_lsn AND state = 'streaming' FROM pg_catalog.pg_stat_replication WHERE application_name = 'standby2';
2024-11-14 15:49:27.751 UTC [2496646] standby2 LOG:  unexpected EOF on standby connection
2024-11-14 15:49:27.751 UTC [2496646] standby2 STATEMENT:  START_REPLICATION SLOT "slot" 0/3000000 TIMELINE 1
2024-11-14 15:49:27.925 UTC [2496670] standby2 LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:27.925 UTC [2496670] standby2 STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:27.926 UTC [2496670] standby2 LOG:  received replication command: START_REPLICATION SLOT "slot" 0/3000000 TIMELINE 1
2024-11-14 15:49:27.926 UTC [2496670] standby2 STATEMENT:  START_REPLICATION SLOT "slot" 0/3000000 TIMELINE 1
2024-11-14 15:49:27.969 UTC [2496670] standby2 LOG:  unexpected EOF on standby connection
2024-11-14 15:49:27.969 UTC [2496670] standby2 STATEMENT:  START_REPLICATION SLOT "slot" 0/3000000 TIMELINE 1
2024-11-14 15:49:29.025 UTC [2496623] LOG:  received immediate shutdown request
2024-11-14 15:49:29.025 UTC [2496628] WARNING:  terminating connection because of crash of another server process
2024-11-14 15:49:29.025 UTC [2496628] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2024-11-14 15:49:29.025 UTC [2496628] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2024-11-14 15:49:29.027 UTC [2496623] LOG:  database system is shut down
******** build/src/test/recovery/tmp_check/log/regress_log_033_replay_tsp_drops ********
# Checking port 21891
# Found port 21891
Name: primary1
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/archives
Connection string: port=21891 host=/home/myon/tmp/ofWde8rhGb
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_primary1.log
# Running: initdb -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata -A trust -N
The files belonging to this database system will be owned by user "myon".
This user must also own the server process.

The database cluster will be initialized with locale "C".
The default database encoding has accordingly been set to "SQL_ASCII".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... sysv
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... Etc/UTC
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok

Sync to disk skipped.
The data directory might become corrupt if the operating system crashes.

Success. You can now start the database server using:

    pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata -l logfile start

# Running: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/../../../src/test/regress/pg_regress --config-auth /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata
### Starting node "primary1"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_primary1.log -o --cluster-name=primary1 start
waiting for server to start.... done
server started
# Postmaster PID for node "primary1" is 2496623
(slot,0/15CFA68)
# Taking pg_basebackup my_backup from node "primary1"
# Running: pg_basebackup -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/backup/my_backup -h /home/myon/tmp/ofWde8rhGb -p 21891 --checkpoint fast --no-sync
# Backup finished
# Checking port 21892
# Found port 21892
Name: standby2
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/archives
Connection string: port=21892 host=/home/myon/tmp/ofWde8rhGb
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby2.log
# Initializing node "standby2" from backup "my_backup" of node "primary1"
### Enabling streaming replication for node "standby2"
### Starting node "standby2"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby2.log -o --cluster-name=standby2 start
waiting for server to start.... done
server started
# Postmaster PID for node "standby2" is 2496640
Waiting for replication conn standby2's write_lsn to pass '0/3000000' on primary1
done
Waiting for replication conn standby2's write_lsn to pass '0/301FCF8' on primary1
done
### Stopping node "standby2" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby2"
### Starting node "standby2"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby2.log -o --cluster-name=standby2 start
waiting for server to start.... done
server started
# Postmaster PID for node "standby2" is 2496664
ok 1 - standby node started
### Stopping node "standby2" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby2_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby2"
# Checking port 21893
# Found port 21893
Name: primary2
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/archives
Connection string: port=21893 host=/home/myon/tmp/ofWde8rhGb
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_primary2.log
# Running: initdb -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata -A trust -N
The files belonging to this database system will be owned by user "myon".
This user must also own the server process.

The database cluster will be initialized with locale "C".
The default database encoding has accordingly been set to "SQL_ASCII".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... sysv
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... Etc/UTC
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok

Sync to disk skipped.
The data directory might become corrupt if the operating system crashes.

Success. You can now start the database server using:

    pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata -l logfile start

# Running: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/../../../src/test/regress/pg_regress --config-auth /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata
### Starting node "primary2"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_primary2.log -o --cluster-name=primary2 start
waiting for server to start.... done
server started
# Postmaster PID for node "primary2" is 2496692
# Taking pg_basebackup my_backup from node "primary2"
# Running: pg_basebackup -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/backup/my_backup -h /home/myon/tmp/ofWde8rhGb -p 21893 --checkpoint fast --no-sync
# Backup finished
# Checking port 21894
# Found port 21894
Name: standby3
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby3_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby3_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby3_data/archives
Connection string: port=21894 host=/home/myon/tmp/ofWde8rhGb
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby3.log
# Initializing node "standby3" from backup "my_backup" of node "primary2"
### Enabling streaming replication for node "standby3"
### Starting node "standby3"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby3_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby3.log -o --cluster-name=standby3 start
waiting for server to start.... done
server started
# Postmaster PID for node "standby3" is 2496711
ok 2 - invalid directory creation is detected
1..2
### Stopping node "primary1" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary1_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary1"
### Stopping node "primary2" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_primary2_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary2"
### Stopping node "standby3" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_033_replay_tsp_drops_standby3_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby3"
******** build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_primary2.log ********
2024-11-14 15:49:28.447 UTC [2496692] LOG:  starting PostgreSQL 13.17 (Debian 13.17-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-11-14 15:49:28.447 UTC [2496692] LOG:  listening on Unix socket "/home/myon/tmp/ofWde8rhGb/.s.PGSQL.21893"
2024-11-14 15:49:28.448 UTC [2496693] LOG:  database system was shut down at 2024-11-14 15:49:28 UTC
2024-11-14 15:49:28.451 UTC [2496692] LOG:  database system is ready to accept connections
2024-11-14 15:49:28.559 UTC [2496701] 033_replay_tsp_drops.pl LOG:  statement: SET allow_in_place_tablespaces=on;
2024-11-14 15:49:28.559 UTC [2496701] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLESPACE ts1 LOCATION ''
		
2024-11-14 15:49:28.575 UTC [2496703] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE db1 WITH TABLESPACE ts1
2024-11-14 15:49:28.607 UTC [2496705] 033_replay_tsp_drops.pl LOG:  received replication command: SHOW data_directory_mode
2024-11-14 15:49:28.607 UTC [2496705] 033_replay_tsp_drops.pl STATEMENT:  SHOW data_directory_mode
2024-11-14 15:49:28.608 UTC [2496705] 033_replay_tsp_drops.pl LOG:  received replication command: SHOW wal_segment_size
2024-11-14 15:49:28.608 UTC [2496705] 033_replay_tsp_drops.pl STATEMENT:  SHOW wal_segment_size
2024-11-14 15:49:28.608 UTC [2496705] 033_replay_tsp_drops.pl LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:28.608 UTC [2496705] 033_replay_tsp_drops.pl STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:28.608 UTC [2496705] 033_replay_tsp_drops.pl LOG:  received replication command: BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS  FAST NOWAIT    MANIFEST 'yes' 
2024-11-14 15:49:28.608 UTC [2496705] 033_replay_tsp_drops.pl STATEMENT:  BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS  FAST NOWAIT    MANIFEST 'yes' 
2024-11-14 15:49:28.639 UTC [2496706] 033_replay_tsp_drops.pl LOG:  received replication command: SHOW data_directory_mode
2024-11-14 15:49:28.639 UTC [2496706] 033_replay_tsp_drops.pl STATEMENT:  SHOW data_directory_mode
2024-11-14 15:49:28.639 UTC [2496706] 033_replay_tsp_drops.pl LOG:  received replication command: CREATE_REPLICATION_SLOT "pg_basebackup_2496706" TEMPORARY PHYSICAL RESERVE_WAL
2024-11-14 15:49:28.639 UTC [2496706] 033_replay_tsp_drops.pl STATEMENT:  CREATE_REPLICATION_SLOT "pg_basebackup_2496706" TEMPORARY PHYSICAL RESERVE_WAL
2024-11-14 15:49:28.641 UTC [2496706] 033_replay_tsp_drops.pl LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:28.641 UTC [2496706] 033_replay_tsp_drops.pl STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:28.641 UTC [2496706] 033_replay_tsp_drops.pl LOG:  received replication command: START_REPLICATION SLOT "pg_basebackup_2496706" 0/2000000 TIMELINE 1
2024-11-14 15:49:28.641 UTC [2496706] 033_replay_tsp_drops.pl STATEMENT:  START_REPLICATION SLOT "pg_basebackup_2496706" 0/2000000 TIMELINE 1
2024-11-14 15:49:28.788 UTC [2496717] standby3 LOG:  received replication command: IDENTIFY_SYSTEM
2024-11-14 15:49:28.788 UTC [2496717] standby3 STATEMENT:  IDENTIFY_SYSTEM
2024-11-14 15:49:28.788 UTC [2496717] standby3 LOG:  received replication command: START_REPLICATION 0/3000000 TIMELINE 1
2024-11-14 15:49:28.788 UTC [2496717] standby3 STATEMENT:  START_REPLICATION 0/3000000 TIMELINE 1
2024-11-14 15:49:28.978 UTC [2496723] 033_replay_tsp_drops.pl LOG:  statement: CREATE TABLE should_not_replay_insertion(a int);
2024-11-14 15:49:28.980 UTC [2496723] 033_replay_tsp_drops.pl LOG:  statement: CREATE DATABASE db2 WITH TABLESPACE ts1;
2024-11-14 15:49:28.994 UTC [2496723] 033_replay_tsp_drops.pl LOG:  statement: INSERT INTO should_not_replay_insertion VALUES (1);
2024-11-14 15:49:29.196 UTC [2496692] LOG:  received immediate shutdown request
2024-11-14 15:49:29.196 UTC [2496697] WARNING:  terminating connection because of crash of another server process
2024-11-14 15:49:29.196 UTC [2496697] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2024-11-14 15:49:29.196 UTC [2496697] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2024-11-14 15:49:29.196 UTC [2496717] standby3 WARNING:  terminating connection because of crash of another server process
2024-11-14 15:49:29.196 UTC [2496717] standby3 DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2024-11-14 15:49:29.196 UTC [2496717] standby3 HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2024-11-14 15:49:29.198 UTC [2496692] LOG:  database system is shut down
******** build/src/test/recovery/tmp_check/log/033_replay_tsp_drops_standby3.log ********
2024-11-14 15:49:28.781 UTC [2496711] LOG:  starting PostgreSQL 13.17 (Debian 13.17-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-11-14 15:49:28.782 UTC [2496711] LOG:  listening on Unix socket "/home/myon/tmp/ofWde8rhGb/.s.PGSQL.21894"
2024-11-14 15:49:28.783 UTC [2496712] LOG:  database system was interrupted; last known up at 2024-11-14 15:49:28 UTC
2024-11-14 15:49:28.783 UTC [2496712] LOG:  entering standby mode
2024-11-14 15:49:28.784 UTC [2496712] LOG:  redo starts at 0/2000028
2024-11-14 15:49:28.784 UTC [2496712] WARNING:  unexpected directory entry "16384" found in pg_tblspc/
2024-11-14 15:49:28.784 UTC [2496712] DETAIL:  All directory entries in pg_tblspc/ should be symbolic links.
2024-11-14 15:49:28.784 UTC [2496712] HINT:  Remove those directories, or set allow_in_place_tablespaces to ON transiently to let recovery complete.
2024-11-14 15:49:28.784 UTC [2496712] LOG:  consistent recovery state reached at 0/2000100
2024-11-14 15:49:28.785 UTC [2496711] LOG:  database system is ready to accept read only connections
2024-11-14 15:49:28.788 UTC [2496716] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-11-14 15:49:28.896 UTC [2496719] 033_replay_tsp_drops.pl LOG:  statement: SELECT 1
2024-11-14 15:49:28.942 UTC [2496721] 033_replay_tsp_drops.pl LOG:  statement: SELECT oid FROM pg_tablespace WHERE spcname = 'ts1';
2024-11-14 15:49:28.994 UTC [2496712] WARNING:  creating missing directory: pg_tblspc/16384/PG_13_202007201
2024-11-14 15:49:28.994 UTC [2496712] CONTEXT:  WAL redo at 0/3014470 for Database/CREATE: copy dir 1663/1 to 16384/16389
WARNING:  terminating connection because of crash of another server process
DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2024-11-14 15:49:29.197 UTC [2496716] FATAL:  could not receive data from WAL stream: server closed the connection unexpectedly
		This probably means the server terminated abnormally
		before or while processing the request.
2024-11-14 15:49:29.197 UTC [2496712] LOG:  invalid record length at 0/3016998: wanted 24, got 0
2024-11-14 15:49:29.200 UTC [2496726] FATAL:  could not connect to the primary server: could not connect to server: No such file or directory
		Is the server running locally and accepting
		connections on Unix domain socket "/home/myon/tmp/ofWde8rhGb/.s.PGSQL.21893"?
2024-11-14 15:49:29.343 UTC [2496711] LOG:  received immediate shutdown request
2024-11-14 15:49:29.345 UTC [2496711] LOG:  database system is shut down
******** build/src/test/recovery/tmp_check/log/037_invalid_database_node.log ********
2024-11-14 15:49:29.908 UTC [2496748] LOG:  starting PostgreSQL 13.17 (Debian 13.17-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-11-14 15:49:29.908 UTC [2496748] LOG:  listening on Unix socket "/home/myon/tmp/t2SutJfjwH/.s.PGSQL.24733"
2024-11-14 15:49:29.910 UTC [2496749] LOG:  database system was shut down at 2024-11-14 15:49:29 UTC
2024-11-14 15:49:29.913 UTC [2496748] LOG:  database system is ready to accept connections
2024-11-14 15:49:30.008 UTC [2496756] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.009 UTC [2496756] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.015 UTC [2496756] 037_invalid_database.pl LOG:  statement: CREATE DATABASE regression_invalid;
2024-11-14 15:49:30.034 UTC [2496756] 037_invalid_database.pl LOG:  duration: 19.120 ms
2024-11-14 15:49:30.035 UTC [2496756] 037_invalid_database.pl LOG:  statement: UPDATE pg_database SET datconnlimit = -2 WHERE datname = 'regression_invalid';
2024-11-14 15:49:30.060 UTC [2496756] 037_invalid_database.pl LOG:  duration: 25.265 ms
2024-11-14 15:49:30.060 UTC [2496756] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.052 user=myon database=postgres host=[local]
2024-11-14 15:49:30.068 UTC [2496758] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.068 UTC [2496758] [unknown] LOG:  connection authorized: user=myon database=regression_invalid application_name=037_invalid_database.pl
2024-11-14 15:49:30.068 UTC [2496758] [unknown] FATAL:  cannot connect to invalid database "regression_invalid"
2024-11-14 15:49:30.068 UTC [2496758] [unknown] HINT:  Use DROP DATABASE to drop invalid databases.
2024-11-14 15:49:30.077 UTC [2496760] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.078 UTC [2496760] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.079 UTC [2496760] 037_invalid_database.pl LOG:  statement: ALTER DATABASE regression_invalid CONNECTION LIMIT 10
2024-11-14 15:49:30.079 UTC [2496760] 037_invalid_database.pl FATAL:  cannot alter invalid database "regression_invalid"
2024-11-14 15:49:30.079 UTC [2496760] 037_invalid_database.pl HINT:  Use DROP DATABASE to drop invalid databases.
2024-11-14 15:49:30.079 UTC [2496760] 037_invalid_database.pl STATEMENT:  ALTER DATABASE regression_invalid CONNECTION LIMIT 10
2024-11-14 15:49:30.079 UTC [2496760] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.002 user=myon database=postgres host=[local]
2024-11-14 15:49:30.126 UTC [2496762] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.127 UTC [2496762] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.129 UTC [2496762] 037_invalid_database.pl LOG:  statement: CREATE DATABASE copy_invalid TEMPLATE regression_invalid
2024-11-14 15:49:30.130 UTC [2496762] 037_invalid_database.pl ERROR:  cannot use invalid database "regression_invalid" as template
2024-11-14 15:49:30.130 UTC [2496762] 037_invalid_database.pl HINT:  Use DROP DATABASE to drop invalid databases.
2024-11-14 15:49:30.130 UTC [2496762] 037_invalid_database.pl STATEMENT:  CREATE DATABASE copy_invalid TEMPLATE regression_invalid
2024-11-14 15:49:30.130 UTC [2496762] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.004 user=myon database=postgres host=[local]
2024-11-14 15:49:30.172 UTC [2496764] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.172 UTC [2496764] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.174 UTC [2496764] 037_invalid_database.pl LOG:  statement: UPDATE pg_database SET datfrozenxid = '123456' WHERE datname = 'regression_invalid';
2024-11-14 15:49:30.203 UTC [2496764] 037_invalid_database.pl LOG:  duration: 28.990 ms
2024-11-14 15:49:30.204 UTC [2496764] 037_invalid_database.pl LOG:  statement: DROP TABLE IF EXISTS foo_tbl;
2024-11-14 15:49:30.204 UTC [2496764] 037_invalid_database.pl LOG:  duration: 0.100 ms
2024-11-14 15:49:30.204 UTC [2496764] 037_invalid_database.pl LOG:  statement: CREATE TABLE foo_tbl();
2024-11-14 15:49:30.205 UTC [2496764] 037_invalid_database.pl LOG:  duration: 0.673 ms
2024-11-14 15:49:30.205 UTC [2496764] 037_invalid_database.pl LOG:  statement: VACUUM FREEZE;
2024-11-14 15:49:30.223 UTC [2496764] 037_invalid_database.pl LOG:  duration: 17.936 ms
2024-11-14 15:49:30.223 UTC [2496764] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.051 user=myon database=postgres host=[local]
2024-11-14 15:49:30.233 UTC [2496766] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.233 UTC [2496766] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.236 UTC [2496766] 037_invalid_database.pl LOG:  statement: DROP DATABASE regression_invalid
2024-11-14 15:49:30.239 UTC [2496766] 037_invalid_database.pl LOG:  duration: 3.507 ms
2024-11-14 15:49:30.239 UTC [2496766] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.006 user=myon database=postgres host=[local]
2024-11-14 15:49:30.248 UTC [2496768] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.249 UTC [2496768] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.250 UTC [2496768] 037_invalid_database.pl LOG:  statement: DROP DATABASE regression_invalid
2024-11-14 15:49:30.250 UTC [2496768] 037_invalid_database.pl ERROR:  database "regression_invalid" does not exist
2024-11-14 15:49:30.250 UTC [2496768] 037_invalid_database.pl STATEMENT:  DROP DATABASE regression_invalid
2024-11-14 15:49:30.250 UTC [2496768] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.002 user=myon database=postgres host=[local]
2024-11-14 15:49:30.300 UTC [2496770] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.301 UTC [2496770] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.318 UTC [2496772] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.318 UTC [2496772] [unknown] LOG:  connection authorized: user=myon database=postgres application_name=037_invalid_database.pl
2024-11-14 15:49:30.321 UTC [2496772] 037_invalid_database.pl LOG:  statement: SELECT pg_backend_pid()
	;
2024-11-14 15:49:30.357 UTC [2496772] 037_invalid_database.pl LOG:  duration: 35.685 ms
2024-11-14 15:49:30.357 UTC [2496772] 037_invalid_database.pl LOG:  statement: CREATE DATABASE regression_invalid_interrupt;
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  duration: 10.419 ms
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  statement: BEGIN;
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  duration: 0.020 ms
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  statement: LOCK pg_tablespace;
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  duration: 0.082 ms
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  statement: PREPARE TRANSACTION 'lock_tblspc';
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  duration: 0.024 ms
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  statement: ;
2024-11-14 15:49:30.368 UTC [2496772] 037_invalid_database.pl LOG:  duration: 0.003 ms
2024-11-14 15:49:30.369 UTC [2496772] 037_invalid_database.pl LOG:  statement: DROP DATABASE regression_invalid_interrupt;
2024-11-14 15:49:30.369 UTC [2496770] 037_invalid_database.pl LOG:  statement: DO $$
		BEGIN
			WHILE NOT EXISTS(SELECT * FROM pg_locks WHERE NOT granted AND relation = 'pg_tablespace'::regclass AND mode = 'AccessShareLock') LOOP
				PERFORM pg_sleep(.1);
			END LOOP;
		END$$;
2024-11-14 15:49:30.403 UTC [2496770] 037_invalid_database.pl LOG:  duration: 33.972 ms
2024-11-14 15:49:30.403 UTC [2496770] 037_invalid_database.pl LOG:  statement: SELECT pg_cancel_backend(2496772);
2024-11-14 15:49:30.404 UTC [2496770] 037_invalid_database.pl LOG:  duration: 0.752 ms
2024-11-14 15:49:30.404 UTC [2496772] 037_invalid_database.pl ERROR:  canceling statement due to user request
2024-11-14 15:49:30.404 UTC [2496772] 037_invalid_database.pl STATEMENT:  DROP DATABASE regression_invalid_interrupt;
2024-11-14 15:49:30.404 UTC [2496770] 037_invalid_database.pl LOG:  statement: ;
2024-11-14 15:49:30.404 UTC [2496770] 037_invalid_database.pl LOG:  duration: 0.020 ms
2024-11-14 15:49:30.405 UTC [2496770] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.105 user=myon database=postgres host=[local]
2024-11-14 15:49:30.414 UTC [2496774] [unknown] LOG:  connection received: host=[local]
2024-11-14 15:49:30.414 UTC [2496774] [unknown] LOG:  connection authorized: user=myon database=regression_invalid_interrupt application_name=037_invalid_database.pl
2024-11-14 15:49:30.414 UTC [2496774] [unknown] FATAL:  cannot connect to invalid database "regression_invalid_interrupt"
2024-11-14 15:49:30.414 UTC [2496774] [unknown] HINT:  Use DROP DATABASE to drop invalid databases.
2024-11-14 15:49:30.430 UTC [2496772] 037_invalid_database.pl LOG:  statement: ROLLBACK PREPARED 'lock_tblspc'
	;
2024-11-14 15:49:30.431 UTC [2496772] 037_invalid_database.pl LOG:  duration: 0.291 ms
2024-11-14 15:49:30.432 UTC [2496772] 037_invalid_database.pl LOG:  statement: DROP DATABASE regression_invalid_interrupt
	;
2024-11-14 15:49:30.439 UTC [2496772] 037_invalid_database.pl LOG:  duration: 6.967 ms
2024-11-14 15:49:30.440 UTC [2496772] 037_invalid_database.pl LOG:  disconnection: session time: 0:00:00.122 user=myon database=postgres host=[local]
2024-11-14 15:49:30.445 UTC [2496748] LOG:  received immediate shutdown request
2024-11-14 15:49:30.449 UTC [2496748] LOG:  database system is shut down
******** build/src/test/recovery/tmp_check/log/regress_log_039_end_of_wal ********
No such file or directory at /home/myon/projects/postgresql/debian/13/build/../src/test/perl/TestLib.pm line 655.
******** build/src/test/recovery/tmp_check/log/regress_log_037_invalid_database ********
# Checking port 24733
# Found port 24733
Name: node
Data directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata
Backup directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/backup
Archive directory: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/archives
Connection string: port=24733 host=/home/myon/tmp/t2SutJfjwH
Log file: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/037_invalid_database_node.log
# Running: initdb -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata -A trust -N
The files belonging to this database system will be owned by user "myon".
This user must also own the server process.

The database cluster will be initialized with locale "C".
The default database encoding has accordingly been set to "SQL_ASCII".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... sysv
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... Etc/UTC
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok

Sync to disk skipped.
The data directory might become corrupt if the operating system crashes.

Success. You can now start the database server using:

    pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata -l logfile start

# Running: /home/myon/projects/postgresql/debian/13/build/src/test/recovery/../../../src/test/regress/pg_regress --config-auth /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata
### Starting node "node"
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata -l /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/log/037_invalid_database_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 2496748
ok 1 - can't connect to invalid database - error code
ok 2 - can't connect to invalid database - error message
psql:<stdin>:1: FATAL:  cannot alter invalid database "regression_invalid"
HINT:  Use DROP DATABASE to drop invalid databases.
server closed the connection unexpectedly
	This probably means the server terminated abnormally
	before or while processing the request.
psql:<stdin>:1: fatal: connection to server was lost
ok 3 - can't ALTER invalid database
psql:<stdin>:1: ERROR:  cannot use invalid database "regression_invalid" as template
HINT:  Use DROP DATABASE to drop invalid databases.
ok 4 - can't use invalid database as template
ok 5 - invalid databases are ignored by vac_truncate_clog
ok 6 - can DROP invalid database
psql:<stdin>:1: ERROR:  database "regression_invalid" does not exist
ok 7 - can't drop already dropped database
# issuing query via background psql: SELECT pg_backend_pid()
# issuing query via background psql: 
#   CREATE DATABASE regression_invalid_interrupt;
#   BEGIN;
#   LOCK pg_tablespace;
#   PREPARE TRANSACTION 'lock_tblspc';
ok 8 - blocked DROP DATABASE completion
# issuing query via background psql: 
# 	DO $$
# 	BEGIN
# 		WHILE NOT EXISTS(SELECT * FROM pg_locks WHERE NOT granted AND relation = 'pg_tablespace'::regclass AND mode = 'AccessShareLock') LOOP
# 			PERFORM pg_sleep(.1);
# 		END LOOP;
# 	END$$;
# 	SELECT pg_cancel_backend(2496772);
ok 9 - canceling DROP DATABASE
ok 10 - cancel processed
psql: error: FATAL:  cannot connect to invalid database "regression_invalid_interrupt"
HINT:  Use DROP DATABASE to drop invalid databases.
ok 11 - can't connect to invalid_interrupt database
# issuing query via background psql: ROLLBACK PREPARED 'lock_tblspc'
ok 12 - unblock DROP DATABASE
# issuing query via background psql: DROP DATABASE regression_invalid_interrupt
ok 13 - DROP DATABASE invalid_interrupt
1..13
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /home/myon/projects/postgresql/debian/13/build/src/test/recovery/tmp_check/t_037_invalid_database_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
make[1]: *** [debian/rules:194: override_dh_auto_test-arch] Fehler 1
make[1]: Verzeichnis „/home/myon/projects/postgresql/debian/13“ wird verlassen
make: *** [debian/rules:93: binary] Fehler 2
dpkg-buildpackage: Fehler: Unterprozess debian/rules binary lieferte Exitstatus 2
Script done.