# Checking port 63767
# Found port 63767
Name: node
Data directory: /tmp/cirrus-ci-build/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/archives
Connection string: port=63767 host=/tmp/F8y2kNy1nW
Log file: /tmp/cirrus-ci-build/src/test/recovery/tmp_check/log/039_end_of_wal_node.log
[08:51:29.085](0.021s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/recovery/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata
### Starting node "node"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52132
[08:51:29.337](0.252s) # Single-page end-of-WAL detection
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52154
[08:51:29.588](0.251s) not ok 1 - xl_tot_len zero
[08:51:29.588](0.000s) 
[08:51:29.588](0.000s) #   Failed test 'xl_tot_len zero'
#   at t/039_end_of_wal.pl line 267.
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52168
[08:51:29.854](0.265s) ok 2 - xl_tot_len short
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52218
[08:51:30.367](0.513s) ok 3 - xl_tot_len short at end-of-page
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52232
[08:51:30.632](0.265s) ok 4 - xl_prev bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52248
[08:51:30.910](0.278s) ok 5 - xl_crc bad
[08:51:30.911](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/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52264
[08:51:31.188](0.277s) ok 6 - xlp_magic zero
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52280
[08:51:31.469](0.281s) ok 7 - xlp_magic bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52296
[08:51:31.749](0.281s) ok 8 - xlp_pageaddr bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52312
[08:51:32.025](0.276s) ok 9 - xlp_info bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52328
[08:51:32.302](0.277s) ok 10 - xlp_info lacks XLP_FIRST_IS_CONTRECORD
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52344
[08:51:32.582](0.280s) ok 11 - xlp_rem_len bad
[08:51:32.582](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/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52394
[08:51:33.087](0.505s) ok 12 - xlp_magic zero (split record header)
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52444
[08:51:33.607](0.520s) ok 13 - xlp_pageaddr bad (split record header)
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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/src/test/recovery/tmp_check/t_039_end_of_wal_node_data/pgdata -l /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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 52494
[08:51:34.115](0.508s) ok 14 - xlp_rem_len bad (split record header)
[08:51:34.115](0.000s) 1..14
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/recovery/tmp_check/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:51:34.220](0.105s) # Looks like you failed 1 test of 14.