# Checking port 63978
# Found port 63978
Name: node
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/archives
Connection string: port=63978 host=/tmp/gnDxVe5wrT
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log
[08:47:22.647](0.235s) # 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/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 23357
[08:47:23.608](0.961s) # Single-page end-of-WAL detection
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 23422
[08:47:23.996](0.388s) not ok 1 - xl_tot_len zero
[08:47:23.996](0.000s) #   Failed test 'xl_tot_len zero'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/039_end_of_wal.pl line 267.
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 23477
[08:47:24.510](0.513s) ok 2 - xl_tot_len short
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 23744
[08:47:25.754](1.244s) ok 3 - xl_tot_len short at end-of-page
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 23822
[08:47:26.252](0.498s) ok 4 - xl_prev bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 23901
[08:47:26.755](0.504s) ok 5 - xl_crc bad
[08:47:26.755](0.000s) # Multi-page end-of-WAL detection, header is not split
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24017
[08:47:27.089](0.334s) ok 6 - xlp_magic zero
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24086
[08:47:27.453](0.364s) ok 7 - xlp_magic bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24211
[08:47:28.098](0.645s) ok 8 - xlp_pageaddr bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24314
[08:47:28.669](0.571s) ok 9 - xlp_info bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24429
[08:47:29.229](0.560s) ok 10 - xlp_info lacks XLP_FIRST_IS_CONTRECORD
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24487
[08:47:29.872](0.643s) ok 11 - xlp_rem_len bad
[08:47:29.872](0.000s) # Multi-page, but header is split, so page checks are done first
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24724
[08:47:31.207](1.335s) ok 12 - xlp_magic zero (split record header)
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 24980
[08:47:32.611](1.404s) ok 13 - xlp_pageaddr bad (split record header)
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log -o --cluster-name=node start
waiting for server to start.... done
server started
# Postmaster PID for node "node" is 25226
[08:47:33.869](1.257s) ok 14 - xlp_rem_len bad (split record header)
[08:47:33.869](0.000s) 1..14
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "node"
[08:47:33.892](0.023s) # Looks like you failed 1 test of 14.