# Checking port 56892
# Found port 56892
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=56892 host=/var/folders/xc/tpssff9959345bnqq4c6tlww0000gn/T/KVMXJMUIdy
Log file: /Users/admin/pgsql/build/testrun/recovery/039_end_of_wal/log/039_end_of_wal_node.log
[20:35:02.197](0.181s) # 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 20232
[20:35:04.189](1.993s) # 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 20318
[20:35:04.959](0.769s) not ok 1 - xl_tot_len zero
[20:35:04.959](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 20356
[20:35:05.581](0.622s) 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 20490
[20:35:06.451](0.870s) 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 20582
[20:35:06.854](0.404s) 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 20651
[20:35:07.773](0.918s) ok 5 - xl_crc bad
[20:35:07.773](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 20725
[20:35:08.936](1.163s) 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 20823
[20:35:10.645](1.708s) 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 20994
[20:35:12.127](1.483s) 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 21151
[20:35:12.707](0.579s) 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 21221
[20:35:13.135](0.428s) 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 21270
[20:35:13.743](0.609s) ok 11 - xlp_rem_len bad
[20:35:13.743](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 21498
[20:35:14.644](0.900s) 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 21659
[20:35:15.902](1.258s) 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 21814
[20:35:17.176](1.274s) ok 14 - xlp_rem_len bad (split record header)
[20:35:17.176](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"
[20:35:17.285](0.109s) # Looks like you failed 1 test of 14.