# Checking port 55796
# Found port 55796
Name: node
Data directory: /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/archives
Connection string: port=55796 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/W1JNVXtanE
Log file: /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log
[00:31:54.603](0.159s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata
### Starting node "node"
# Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 17866
[00:31:55.133](0.530s) # Single-page end-of-WAL detection
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 17946
[00:31:55.497](0.364s) not ok 1 - xl_tot_len zero
[00:31:55.497](0.000s) #   Failed test 'xl_tot_len zero'
#   at /Users/admin/pgsql/src/test/recovery/t/039_end_of_wal.pl line 267.
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18049
[00:31:55.809](0.312s) ok 2 - xl_tot_len short
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18221
[00:31:56.557](0.748s) ok 3 - xl_tot_len short at end-of-page
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18244
[00:31:56.855](0.299s) ok 4 - xl_prev bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18274
[00:31:57.188](0.332s) ok 5 - xl_crc bad
[00:31:57.188](0.000s) # Multi-page end-of-WAL detection, header is not split
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18338
[00:31:57.827](0.639s) ok 6 - xlp_magic zero
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18482
[00:31:58.359](0.532s) ok 7 - xlp_magic bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18567
[00:31:58.692](0.334s) ok 8 - xlp_pageaddr bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18625
[00:31:58.979](0.286s) ok 9 - xlp_info bad
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18759
[00:31:59.262](0.283s) ok 10 - xlp_info lacks XLP_FIRST_IS_CONTRECORD
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 18836
[00:31:59.604](0.342s) ok 11 - xlp_rem_len bad
[00:31:59.605](0.000s) # Multi-page, but header is split, so page checks are done first
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 19104
[00:32:01.227](1.623s) ok 12 - xlp_magic zero (split record header)
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 19330
[00:32:01.946](0.718s) ok 13 - xlp_pageaddr bad (split record header)
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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 /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/data/t_039_end_of_wal_node_data/pgdata -l /Users/admin/pgsql/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 19490
[00:32:02.962](1.016s) ok 14 - xlp_rem_len bad (split record header)
[00:32:02.962](0.000s) 1..14
### Stopping node "node" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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"
[00:32:03.080](0.118s) # Looks like you failed 1 test of 14.