# Running: pg_receivewal --help [13:53:51.054](0.053s) ok 1 - pg_receivewal --help exit code 0 [13:53:51.054](0.000s) ok 2 - pg_receivewal --help goes to stdout [13:53:51.054](0.000s) ok 3 - pg_receivewal --help nothing to stderr [13:53:51.054](0.000s) ok 4 - pg_receivewal --help maximum line length # Running: pg_receivewal --version [13:53:51.061](0.006s) ok 5 - pg_receivewal --version exit code 0 [13:53:51.061](0.000s) ok 6 - pg_receivewal --version goes to stdout [13:53:51.061](0.000s) ok 7 - pg_receivewal --version nothing to stderr # Running: pg_receivewal --not-a-valid-option [13:53:51.067](0.006s) ok 8 - pg_receivewal with invalid option nonzero exit code [13:53:51.068](0.000s) ok 9 - pg_receivewal with invalid option prints error message # Checking port 61327 # Found port 61327 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archives Connection string: port=61327 host=/tmp/9UEpr0d_iJ Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/log/020_pg_receivewal_primary.log [13:53:51.072](0.004s) # initializing database system by running initdb # Running: initdb -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata -A trust -N --wal-segsize=1 The files belonging to this database system will be owned by user "postgres". This user must also own the server process. The database cluster will be initialized with this locale configuration: locale provider: libc LC_COLLATE: en_US.UTF-8 LC_CTYPE: en_US.UTF-8 LC_MESSAGES: C LC_MONETARY: en_US.UTF-8 LC_NUMERIC: en_US.UTF-8 LC_TIME: en_US.UTF-8 The default database encoding has accordingly been set to "UTF8". The default text search configuration will be set to "english". Data page checksums are disabled. creating directory /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata ... ok creating subdirectories ... ok selecting dynamic shared memory implementation ... posix selecting default max_connections ... 100 selecting default shared_buffers ... 128MB selecting default time zone ... UTC creating configuration files ... ok running bootstrap script ... ok performing post-bootstrap initialization ... 2024-03-19 13:53:51.437 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.695 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.699 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.701 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.702 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.704 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.704 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.705 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.706 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.706 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.707 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree 2024-03-19 13:53:51.816 UTC [34985] WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree ok Sync to disk skipped. The data directory might become corrupt if the operating system crashes. Success. You can now start the database server using: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata -l logfile start # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/log/020_pg_receivewal_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 35123 # Running: pg_receivewal pg_receivewal: error: no target directory specified pg_receivewal: hint: Try "pg_receivewal --help" for more information. [13:53:52.169](1.097s) ok 10 - pg_receivewal needs target directory specified # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --create-slot --drop-slot pg_receivewal: error: cannot use --create-slot together with --drop-slot pg_receivewal: hint: Try "pg_receivewal --help" for more information. [13:53:52.184](0.015s) ok 11 - failure if both --create-slot and --drop-slot specified # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --create-slot pg_receivewal: error: --create-slot needs a slot to be specified using --slot pg_receivewal: hint: Try "pg_receivewal --help" for more information. [13:53:52.199](0.015s) ok 12 - failure if --create-slot specified without --slot # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --synchronous --no-sync pg_receivewal: error: cannot use --synchronous together with --no-sync pg_receivewal: hint: Try "pg_receivewal --help" for more information. [13:53:52.214](0.015s) ok 13 - failure if --synchronous specified with --no-sync # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --compress none:1 [13:53:52.221](0.007s) ok 14 - failure if --compress none:N (where N > 0): exit code not 0 [13:53:52.222](0.001s) ok 15 - failure if --compress none:N (where N > 0): matches # Running: pg_receivewal --slot test --create-slot [13:53:52.236](0.015s) ok 16 - creating a replication slot #### Begin standard error psql::1: WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree #### End standard error [13:53:52.254](0.018s) ok 17 - physical replication slot was created [13:53:52.255](0.001s) ok 18 - restart LSN of new slot is null # Running: pg_receivewal --slot test --drop-slot [13:53:52.270](0.015s) ok 19 - dropping a replication slot #### Begin standard error psql::1: WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree #### End standard error [13:53:52.282](0.013s) ok 20 - replication slot was removed 0/641158 # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --verbose --endpos 0/700028 --synchronous --no-loop pg_receivewal: starting log streaming at 0/700000 (timeline 1) pg_receivewal: stopped log streaming at 0/700130 (timeline 1) pg_receivewal: not renaming "000000010000000000000007.partial", segment is not complete pg_receivewal: stopped log streaming at 0/700130 (timeline 1) [13:53:52.444](0.162s) ok 21 - streaming some WAL with --synchronous [13:53:52.445](0.001s) ok 22 - one partial WAL segment was created [13:53:52.445](0.000s) # Testing pg_receivewal with compression methods 0/700148 # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --verbose --endpos 0/800028 --compress gzip:1 --no-loop pg_receivewal: starting log streaming at 0/700000 (timeline 1) pg_receivewal: finished segment at 0/800000 (timeline 1) pg_receivewal: stopped log streaming at 0/8000D0 (timeline 1) pg_receivewal: not renaming "000000010000000000000008.gz.partial", segment is not complete pg_receivewal: stopped log streaming at 0/8000D0 (timeline 1) [13:53:52.560](0.115s) ok 23 - streaming some WAL using ZLIB compression [13:53:52.560](0.001s) ok 24 - one WAL segment compressed with ZLIB was created [13:53:52.561](0.000s) ok 25 - one partial WAL segment compressed with ZLIB was created [13:53:52.561](0.000s) ok 26 - one partial WAL segment is now completed # Running: /usr/bin/gzip --test /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal/000000010000000000000007.gz [13:53:52.567](0.006s) ok 27 - gzip verified the integrity of compressed WAL segments 0/8000E8 # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --verbose --endpos 0/900028 --no-loop --compress lz4 pg_receivewal: starting log streaming at 0/800000 (timeline 1) pg_receivewal: finished segment at 0/900000 (timeline 1) pg_receivewal: stopped log streaming at 0/9000D0 (timeline 1) pg_receivewal: not renaming "000000010000000000000009.lz4.partial", segment is not complete pg_receivewal: stopped log streaming at 0/9000D0 (timeline 1) [13:53:52.711](0.144s) ok 28 - streaming some WAL using --compress=lz4 [13:53:52.712](0.001s) ok 29 - one WAL segment compressed with LZ4 was created [13:53:52.713](0.001s) ok 30 - one partial WAL segment compressed with LZ4 was created [13:53:52.713](0.000s) ok 31 - one partial WAL segment is now completed # Running: /usr/local/bin/lz4 -t /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal/000000010000000000000008.lz4 /tmp/cirrus-ci-build : decoded 1048576 bytes [13:53:52.722](0.009s) ok 32 - lz4 verified the integrity of compressed WAL segments 0/9000E8 # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/archive_wal --verbose --endpos 0/A00028 --no-loop pg_receivewal: starting log streaming at 0/900000 (timeline 1) pg_receivewal: finished segment at 0/A00000 (timeline 1) pg_receivewal: stopped log streaming at 0/A000D0 (timeline 1) pg_receivewal: not renaming "00000001000000000000000A.partial", segment is not complete pg_receivewal: stopped log streaming at 0/A000D0 (timeline 1) [13:53:52.838](0.116s) ok 33 - streaming some WAL [13:53:52.838](0.000s) ok 34 - check that previously partial WAL is now complete [13:53:52.839](0.000s) ok 35 - check stream dir permissions [13:53:52.839](0.000s) # Testing pg_receivewal with slot as starting streaming point (archive_slot,0/A000D0) #### Begin standard error psql::3: WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree #### End standard error 0/A003B0 # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/slot_wal --slot nonexistentslot -n --no-sync --verbose --endpos 0/B00028 [13:53:53.104](0.265s) ok 36 - pg_receivewal fails with non-existing slot: exit code not 0 [13:53:53.104](0.000s) ok 37 - pg_receivewal fails with non-existing slot: matches # Running: pg_receivewal -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/slot_wal --slot archive_slot -n --no-sync --verbose --endpos 0/B00028 pg_receivewal: starting log streaming at 0/A00000 (timeline 1) pg_receivewal: finished segment at 0/B00000 (timeline 1) pg_receivewal: stopped log streaming at 0/B000D0 (timeline 1) pg_receivewal: not renaming "00000001000000000000000B.partial", segment is not complete pg_receivewal: stopped log streaming at 0/B000D0 (timeline 1) [13:53:53.118](0.014s) ok 38 - WAL streamed from the slot's restart_lsn [13:53:53.118](0.000s) ok 39 - WAL from the slot's restart_lsn has been archived # Taking pg_basebackup basebackup from node "primary" # Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/backup/basebackup -h /tmp/9UEpr0d_iJ -p 61327 --checkpoint fast --no-sync # Backup finished # Checking port 61328 # Found port 61328 Name: standby Data directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_standby_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_standby_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_standby_data/archives Connection string: port=61328 host=/tmp/9UEpr0d_iJ Log file: /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/log/020_pg_receivewal_standby.log # Initializing node "standby" from backup "basebackup" of node "primary" ### Enabling streaming replication for node "standby" ### Starting node "standby" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_standby_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/log/020_pg_receivewal_standby.log -o --cluster-name=standby start waiting for server to start.... done server started # Postmaster PID for node "standby" is 35368 archive_slot|0/0|| Waiting for replication conn standby's replay_lsn to pass 0/D00000 on primary [13:57:26.972](213.854s) # poll_query_until timed out executing this query: # SELECT '0/D00000' <= replay_lsn AND state = 'streaming' # FROM pg_catalog.pg_stat_replication # WHERE application_name IN ('standby', 'walreceiver') # expecting this output: # t # last actual query output: # t # with stderr: # WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree timed out waiting for catchup at /tmp/cirrus-ci-build/src/bin/pg_basebackup/t/020_pg_receivewal.pl line 281. # Postmaster PID for node "primary" is 35123 ### Stopping node "primary" using mode immediate # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_primary_data/pgdata -m immediate stop waiting for server to shut down.... done server stopped # No postmaster PID for node "primary" # Postmaster PID for node "standby" is 35368 ### Stopping node "standby" using mode immediate # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_basebackup/020_pg_receivewal/data/t_020_pg_receivewal_standby_data/pgdata -m immediate stop waiting for server to shut down.... done server stopped # No postmaster PID for node "standby" [13:57:27.191](0.219s) # Tests were run but no plan was declared and done_testing() was not seen. [13:57:27.191](0.000s) # Looks like your test exited with 29 just after 39.