# Checking port 49925
# Found port 49925
Name: mynode
Data directory: /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/archives
Connection string: port=49925 host=/tmp/_CYQWTY2ue
Log file: /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/log/001_worker_spi_mynode.log
[10:49:11.182](0.013s) # 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/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata
### Starting node "mynode"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/log/001_worker_spi_mynode.log -o --cluster-name=mynode start
waiting for server to start.... done
server started
# Postmaster PID for node "mynode" is 29055
[10:49:11.339](0.158s) # testing dynamic bgworkers
[10:49:11.375](0.035s) ok 1 - dynamic bgworker launched
[10:52:51.134](219.760s) # poll_query_until timed out executing this query:
# SELECT count(*) > 0 FROM information_schema.tables
# 	    WHERE table_schema = 'schema4' AND table_name = 'counted';
# expecting this output:
# t
# last actual query output:
# t
# with stderr:
# WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
#### Begin standard error
psql:<stdin>:1: WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
#### End standard error
### Reloading node "mynode"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata reload
server signaled
[10:56:23.706](212.572s) # poll_query_until timed out executing this query:
# SELECT count(*) FROM schema4.counted WHERE type = 'delta';
# expecting this output:
# 0
# last actual query output:
# 1
# with stderr:
[10:56:23.717](0.010s) not ok 2 - dynamic bgworker correctly consumed tuple data
[10:56:23.717](0.000s) #   Failed test 'dynamic bgworker correctly consumed tuple data'
#   at /tmp/cirrus-ci-build/src/test/modules/worker_spi/t/001_worker_spi.pl line 42.
[10:56:23.717](0.000s) #          got: 'total|0
# delta|1'
#     expected: 'total|1'
[10:59:57.989](214.272s) # poll_query_until timed out executing this query:
# SELECT wait_event FROM pg_stat_activity WHERE backend_type ~ 'worker_spi';
# expecting this output:
# WorkerSpiMain
# last actual query output:
# 
# with stderr:
# WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
[10:59:57.989](0.001s) not ok 3 - dynamic bgworker has reported "WorkerSpiMain" as wait event
[10:59:57.990](0.000s) #   Failed test 'dynamic bgworker has reported "WorkerSpiMain" as wait event'
#   at /tmp/cirrus-ci-build/src/test/modules/worker_spi/t/001_worker_spi.pl line 49.
[10:59:57.990](0.000s) #          got: '0'
#     expected: '1'
#### Begin standard error
psql:<stdin>:1: WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
#### End standard error
[10:59:58.001](0.012s) ok 4 - "WorkerSpiMain" is reported in pg_wait_events
[10:59:58.002](0.000s) # testing bgworkers loaded with shared_preload_libraries
#### Begin standard error
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
#### End standard error
#### Begin standard error
psql:<stdin>:1: WARNING:  roles created by regression test cases should have names starting with "regress_"
#### End standard error
### Restarting node "mynode"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/log/001_worker_spi_mynode.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "mynode" is 54697
[11:03:34.100](216.098s) # poll_query_until timed out executing this query:
# SELECT datname, count(datname), wait_event FROM pg_stat_activity
#             WHERE backend_type = 'worker_spi' GROUP BY datname, wait_event;
# expecting this output:
# mydb|3|WorkerSpiMain
# last actual query output:
# 
# with stderr:
# WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
[11:03:34.100](0.000s) not ok 5 - bgworkers all launched
[11:03:34.100](0.000s) #   Failed test 'bgworkers all launched'
#   at /tmp/cirrus-ci-build/src/test/modules/worker_spi/t/001_worker_spi.pl line 78.
Timed out while waiting for bgworkers to be launched at /tmp/cirrus-ci-build/src/test/modules/worker_spi/t/001_worker_spi.pl line 78.
# Postmaster PID for node "mynode" is 54697
### Stopping node "mynode" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/worker_spi/001_worker_spi/data/t_001_worker_spi_mynode_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "mynode"
[11:03:34.210](0.110s) # Tests were run but no plan was declared and done_testing() was not seen.
[11:03:34.211](0.000s) # Looks like your test exited with 29 just after 5.