# Checking port 49576
# Found port 49576
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=49576 host=/tmp/5TffkAVKcf
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log
[16:55:27.575](0.066s) # 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 22939
[16:55:28.525](0.950s) # 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 23048
[16:55:29.184](0.659s) not ok 1 - xl_tot_len zero
[16:55:29.184](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 23090
[16:55:29.665](0.481s) 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 23230
[16:55:30.865](1.200s) 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 23290
[16:55:31.388](0.523s) 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 23390
[16:55:31.812](0.424s) ok 5 - xl_crc bad
[16:55:31.812](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 23416
[16:55:32.029](0.217s) 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 23519
[16:55:32.421](0.393s) 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 23701
[16:55:32.932](0.511s) 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 23903
[16:55:33.699](0.767s) 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 23960
[16:55:34.276](0.577s) 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 24077
[16:55:35.096](0.820s) ok 11 - xlp_rem_len bad
[16:55:35.096](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 24373
[16:55:37.253](2.156s) 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 24589
[16:55:38.422](1.170s) 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 24861
[16:55:39.273](0.851s) ok 14 - xlp_rem_len bad (split record header)
[16:55:39.273](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"
[16:55:39.281](0.008s) # Looks like you failed 1 test of 14.