# Checking port 58880 # Found port 58880 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/archives Connection string: port=58880 host=/tmp/WhZ08pg9T6 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/log/008_fsm_truncation_primary.log [02:07:01.770](0.030s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/log/008_fsm_truncation_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 20642 # Taking pg_basebackup primary_backup from node "primary" # Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/backup/primary_backup -h /tmp/WhZ08pg9T6 -p 58880 --checkpoint fast --no-sync # Backup finished # Checking port 58881 # Found port 58881 Name: standby Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_standby_data/archives Connection string: port=58881 host=/tmp/WhZ08pg9T6 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/log/008_fsm_truncation_standby.log # Initializing node "standby" from backup "primary_backup" of node "primary" ### Enabling streaming replication for node "standby" ### Starting node "standby" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/log/008_fsm_truncation_standby.log -o --cluster-name=standby start waiting for server to start.... done server started # Postmaster PID for node "standby" is 20762 psql::1: INFO: vacuuming "postgres.public.testtab" psql::1: INFO: "testtab": stopping truncate due to conflicting lock request psql::1: INFO: finished vacuuming "postgres.public.testtab": index scans: 0 pages: 0 removed, 35 remain, 35 scanned (100.00% of total) tuples: 1536 removed, 464 remain, 0 are dead but not yet removable removable cutoff: 742, which was 2 XIDs old when operation ended new relfrozenxid: 739, which is 1 XIDs ahead of previous value frozen: 0 pages from table (0.00% of total) had 0 tuples frozen index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed avg read rate: 0.006 MB/s, avg write rate: 0.005 MB/s buffer usage: 88 hits, 4 misses, 3 dirtied WAL usage: 65 records, 3 full page images, 31435 bytes system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 5.02 s psql::4: INFO: vacuuming "postgres.public.testtab" psql::4: INFO: "testtab": stopping truncate due to conflicting lock request psql::4: INFO: finished vacuuming "postgres.public.testtab": index scans: 0 pages: 0 removed, 35 remain, 35 scanned (100.00% of total) tuples: 0 removed, 1464 remain, 1000 are dead but not yet removable removable cutoff: 742, which was 5 XIDs old when operation ended frozen: 0 pages from table (0.00% of total) had 0 tuples frozen index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed avg read rate: 0.003 MB/s, avg write rate: 0.002 MB/s buffer usage: 78 hits, 2 misses, 1 dirtied WAL usage: 1 records, 1 full page images, 889 bytes system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 4.96 s psql: error: connection to server on socket "/tmp/WhZ08pg9T6/.s.PGSQL.58881" failed: No such file or directory Is the server running locally and accepting connections on that socket? psql::3: INFO: vacuuming "postgres.public.testtab" psql::3: INFO: table "testtab": truncated 35 to 8 pages psql::3: INFO: finished vacuuming "postgres.public.testtab": index scans: 0 pages: 27 removed, 8 remain, 35 scanned (100.00% of total) tuples: 1000 removed, 464 remain, 0 are dead but not yet removable removable cutoff: 747, which was 1 XIDs old when operation ended frozen: 0 pages from table (0.00% of total) had 0 tuples frozen index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed avg read rate: 0.000 MB/s, avg write rate: 29.876 MB/s buffer usage: 125 hits, 0 misses, 2 dirtied WAL usage: 43 records, 5 full page images, 45453 bytes system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s [02:10:34.637](212.867s) # poll_query_until timed out executing this query: # SELECT '0/30C8F30'::pg_lsn <= pg_last_wal_replay_lsn() # expecting this output: # t # last actual query output: # # with stderr: # psql: error: connection to server on socket "/tmp/WhZ08pg9T6/.s.PGSQL.58881" failed: No such file or directory # Is the server running locally and accepting connections on that socket? Timed out while waiting for standby to catch up at /tmp/cirrus-ci-build/src/test/recovery/t/008_fsm_truncation.pl line 83. # Postmaster PID for node "primary" is 20642 ### Stopping node "primary" using mode immediate # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/008_fsm_truncation/data/t_008_fsm_truncation_primary_data/pgdata -m immediate stop waiting for server to shut down.... done server stopped # No postmaster PID for node "primary" # No postmaster PID for node "standby"