# Running: ipcs -am # Checking port 57554 # Found port 57554 Name: gnat Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/archives Connection string: port=57554 host=/tmp/svtycnJklG Log file: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log [22:40:46.440](0.037s) # 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/017_shm/data/t_017_shm_gnat_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata [22:40:46.480](0.040s) # gnat's datadir inode = 1638467 # Running: ipcs -am 5a6 > m 262147 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 0 no-entry no-entry 22:40:46 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log -o --cluster-name=gnat start waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36723 # Running: ipcs -am 5a6 > m 262147 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 36723 22:40:46 22:40:46 22:40:46 7a9 > m 458758 1638468 --rw------- postgres postgres postgres postgres 6 4096 36723 36723 22:40:46 22:40:46 22:40:46 ### Restarting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36765 # Running: ipcs -am 6c6,7 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 --- > m 262147 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 36765 22:40:46 22:40:46 22:40:46 > m 851972 1177229 --rw------- postgres postgres postgres postgres 7 4096 36701 36701 22:40:46 22:40:46 22:40:46 7a9 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 8a11 > m 262152 1638468 --rw------- postgres postgres postgres postgres 6 4096 36765 36765 22:40:46 22:40:46 22:40:46 ### Killing node "gnat" using signal 9 # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 7 4096 14564 14564 22:37:41 22:40:46 22:37:41 6c6,7 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 --- > m 262147 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 36765 22:40:46 22:40:46 22:40:46 > m 851972 1177229 --rw------- postgres postgres postgres postgres 7 4096 36701 36701 22:40:46 22:40:46 22:40:46 7a9 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 8a11 > m 262152 1638468 --rw------- postgres postgres postgres postgres 0 4096 36765 36765 22:40:46 22:40:46 22:40:46 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log -o --cluster-name=gnat start pg_ctl: another server might be running; trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36802 # Running: ipcs -am 6c6,7 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 --- > m 262147 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 36802 22:40:46 22:40:46 22:40:46 > m 851972 1177229 --rw------- postgres postgres postgres postgres 7 4096 36701 36701 22:40:46 22:40:46 22:40:46 7a9 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 8a11 > m 327688 1638468 --rw------- postgres postgres postgres postgres 6 4096 36802 36802 22:40:46 22:40:46 22:40:46 [22:40:46.988](0.509s) # removing the conflicting shmem ... # Running: ipcs -am 6,7c6,8 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:46 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 8 4096 15852 15852 22:37:52 22:40:46 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 8a10 > m 327688 1638468 --rw------- postgres postgres postgres postgres 6 4096 36802 36802 22:40:46 22:40:46 22:40:46 11c13 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 9 4096 35701 35701 22:40:39 22:40:46 22:40:39 ### Killing node "gnat" using signal 9 # Running: ipcs -am 6c6 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 --- > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:46 22:40:46 7a8 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 8a10 > m 327688 1638468 --rw------- postgres postgres postgres postgres 0 4096 36802 36802 22:40:46 22:40:46 22:40:46 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log -o --cluster-name=gnat start pg_ctl: another server might be running; trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36834 # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,9 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 327683 1638467 --rw------- postgres postgres postgres postgres 6 4096 36834 36834 22:40:47 22:40:47 22:40:47 > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c11,12 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 327688 1638468 --rw------- postgres postgres postgres postgres 0 4096 36802 36834 22:40:47 22:40:47 22:40:46 > m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c14 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 ### Stopping node "gnat" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "gnat" # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,8 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c10,11 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 327688 1638468 --rw------- postgres postgres postgres postgres 0 4096 36802 36834 22:40:47 22:40:47 22:40:46 > m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c13 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 [22:40:47.278](0.290s) # re-creating conflicting shmem ... # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,9 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 393219 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 0 no-entry no-entry 22:40:47 > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c11,12 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 327688 1638468 --rw------- postgres postgres postgres postgres 0 4096 36802 36834 22:40:47 22:40:47 22:40:46 > m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c14 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log -o --cluster-name=gnat start waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36876 # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,9 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 393219 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 36876 22:40:47 22:40:47 22:40:47 > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c11,12 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 393224 1638468 --rw------- postgres postgres postgres postgres 6 4096 36876 36876 22:40:47 22:40:47 22:40:47 > m 851977 1257037 --rw------- postgres postgres postgres postgres 8 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c14 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 ### Stopping node "gnat" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "gnat" # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,9 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 393219 1638467 --rw------- postgres postgres postgres postgres 0 4096 36689 36876 22:40:47 22:40:47 22:40:47 > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c11 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c13 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 [22:40:47.548](0.270s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 7 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,8 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 851972 1177229 --rw------- postgres postgres postgres postgres 6 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c10 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c12 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log -o --cluster-name=gnat start waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36923 # Running: ipcs -am 3c3 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 --- > m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:47 22:37:41 5,7c5,9 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 --- > m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:47 22:40:40 > m 458755 1638467 --rw------- postgres postgres postgres postgres 6 4096 36923 36923 22:40:47 22:40:47 22:40:47 > m 851972 1177229 --rw------- postgres postgres postgres postgres 7 4096 36701 36701 22:40:46 22:40:47 22:40:46 > m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:47 22:37:52 > m 524294 1686737 --rw------- postgres postgres postgres postgres 5 4096 36755 36755 22:40:46 no-entry 22:40:46 9c11 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 --- > m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:47 22:40:44 11c13 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 --- > m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:47 22:40:39 [22:44:25.321](217.773s) # poll_query_until timed out executing this query: # SELECT 1 FROM pg_stat_activity WHERE query = 'SELECT wait_pid(pg_backend_pid())' # expecting this output: # 1 # last actual query output: # 1 # with stderr: # WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree [22:44:25.322](0.001s) not ok 1 - slow query started [22:44:25.322](0.000s) # Failed test 'slow query started' # at /tmp/cirrus-ci-build/src/test/recovery/t/017_shm.pl line 135. #### Begin standard error psql::1: WARNING: outfuncs/readfuncs failed to produce an equal rewritten parse tree #### End standard error ### Killing node "gnat" using signal 9 # Running: ipcs -am 3,12c3,13 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 < m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:40:39 22:36:59 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 < m 393223 1606915 --rw------- postgres postgres postgres postgres 5 4096 35790 35790 22:40:40 22:40:40 22:40:40 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 < m 131082 1384663 --rw------- postgres postgres postgres postgres 5 4096 36416 36416 22:40:44 no-entry 22:40:44 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 < m 196621 1558607 --rw------- postgres postgres postgres postgres 5 4096 35745 35745 22:40:40 no-entry 22:40:40 --- > m 1769472 1289235 --rw------- postgres postgres postgres postgres 5 4096 41838 41838 22:41:26 no-entry 22:41:26 > m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:44:19 22:36:59 > m 720898 1496258 --rw------- postgres postgres postgres postgres 6 4096 61988 61988 22:44:19 no-entry 22:44:19 > m 458755 1638467 --rw------- postgres postgres postgres postgres 1 4096 36923 36923 22:40:47 22:44:25 22:40:47 > m 786437 1097392 --rw------- postgres postgres postgres postgres 8 4096 15852 15852 22:37:52 22:44:25 22:37:52 > m 720902 1209697 --rw------- postgres postgres postgres postgres 7 4096 62711 62711 22:44:24 22:44:25 22:44:24 > m 458759 1464164 --rw------- postgres postgres postgres postgres 8 4096 61946 61946 22:44:19 22:44:25 22:44:19 > m 1900552 1431388 --rw------- postgres postgres postgres postgres 8 4096 41818 41818 22:41:26 22:44:25 22:41:26 > m 458762 1113315 --rw------- postgres postgres postgres postgres 7 4096 62534 62534 22:44:23 22:44:25 22:44:23 > m 262156 1432396 --rw------- postgres postgres postgres postgres 5 4096 41867 41867 22:41:26 no-entry 22:41:26 > m 262157 1415486 --rw------- postgres postgres postgres postgres 6 4096 61833 61833 22:44:18 22:44:25 22:44:18 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat_1.log -o --cluster-name=gnat start waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl start failed; logfile: 2024-03-27 22:44:25.369 UTC [62796][postmaster] FATAL: pre-existing shared memory block (key 1638467, ID 458755) is still in use 2024-03-27 22:44:25.369 UTC [62796][postmaster] HINT: Terminate any old server processes associated with data directory "/tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata". 2024-03-27 22:44:25.369 UTC [62796][postmaster] LOG: database system is shut down # No postmaster PID for node "gnat" [22:44:25.470](0.148s) ok 2 - detected live backend via shared memory # Running: postgres --single -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata template1 [22:44:25.479](0.009s) ok 3 - live query blocks --single 2024-03-27 22:44:25.478 UTC [62809][standalone backend] FATAL: pre-existing shared memory block (key 1638467, ID 458755) is still in use 2024-03-27 22:44:25.478 UTC [62809][standalone backend] HINT: Terminate any old server processes associated with data directory "/tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata". [22:44:25.479](0.000s) ok 4 - single-user mode detected live backend via shared memory # Running: ipcs -am 3,12c3,13 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 < m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:40:39 22:36:59 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 < m 393223 1606915 --rw------- postgres postgres postgres postgres 5 4096 35790 35790 22:40:40 22:40:40 22:40:40 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 < m 131082 1384663 --rw------- postgres postgres postgres postgres 5 4096 36416 36416 22:40:44 no-entry 22:40:44 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 < m 196621 1558607 --rw------- postgres postgres postgres postgres 5 4096 35745 35745 22:40:40 no-entry 22:40:40 --- > m 1769472 1289235 --rw------- postgres postgres postgres postgres 5 4096 41838 41838 22:41:26 no-entry 22:41:26 > m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:44:19 22:36:59 > m 720898 1496258 --rw------- postgres postgres postgres postgres 6 4096 61988 61988 22:44:19 no-entry 22:44:19 > m 458755 1638467 --rw------- postgres postgres postgres postgres 1 4096 36923 62809 22:44:25 22:44:25 22:40:47 > m 786437 1097392 --rw------- postgres postgres postgres postgres 8 4096 15852 15852 22:37:52 22:44:25 22:37:52 > m 720902 1209697 --rw------- postgres postgres postgres postgres 7 4096 62711 62711 22:44:24 22:44:25 22:44:24 > m 458759 1464164 --rw------- postgres postgres postgres postgres 8 4096 61946 61946 22:44:19 22:44:25 22:44:19 > m 1900552 1431388 --rw------- postgres postgres postgres postgres 7 4096 41818 41818 22:41:26 22:44:25 22:41:26 > m 458762 1113315 --rw------- postgres postgres postgres postgres 7 4096 62534 62534 22:44:23 22:44:25 22:44:23 > m 262156 1432396 --rw------- postgres postgres postgres postgres 5 4096 41867 41867 22:41:26 no-entry 22:41:26 > m 262157 1415486 --rw------- postgres postgres postgres postgres 6 4096 61833 61833 22:44:18 22:44:25 22:44:18 # Running: pg_ctl kill QUIT 36947 # Running: ipcs -am 3,12c3,13 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 < m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:40:39 22:36:59 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 < m 393223 1606915 --rw------- postgres postgres postgres postgres 5 4096 35790 35790 22:40:40 22:40:40 22:40:40 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 < m 131082 1384663 --rw------- postgres postgres postgres postgres 5 4096 36416 36416 22:40:44 no-entry 22:40:44 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 < m 196621 1558607 --rw------- postgres postgres postgres postgres 5 4096 35745 35745 22:40:40 no-entry 22:40:40 --- > m 1769472 1289235 --rw------- postgres postgres postgres postgres 5 4096 41838 41838 22:41:26 no-entry 22:41:26 > m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:44:19 22:36:59 > m 720898 1496258 --rw------- postgres postgres postgres postgres 6 4096 61988 61988 22:44:19 no-entry 22:44:19 > m 458755 1638467 --rw------- postgres postgres postgres postgres 0 4096 36923 62809 22:44:25 22:44:25 22:40:47 > m 786437 1097392 --rw------- postgres postgres postgres postgres 8 4096 15852 15852 22:37:52 22:44:25 22:37:52 > m 720902 1209697 --rw------- postgres postgres postgres postgres 7 4096 62711 62711 22:44:24 22:44:25 22:44:24 > m 458759 1464164 --rw------- postgres postgres postgres postgres 8 4096 61946 61946 22:44:19 22:44:25 22:44:19 > m 1900552 1431388 --rw------- postgres postgres postgres postgres 7 4096 41818 41818 22:41:26 22:44:25 22:41:26 > m 458762 1113315 --rw------- postgres postgres postgres postgres 7 4096 62534 62534 22:44:23 22:44:25 22:44:23 > m 262156 1432396 --rw------- postgres postgres postgres postgres 5 4096 41867 41867 22:41:26 no-entry 22:41:26 > m 262157 1415486 --rw------- postgres postgres postgres postgres 6 4096 61833 61833 22:44:18 22:44:25 22:44:18 ### Starting node "gnat" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat_1.log -o --cluster-name=gnat start waiting for server to start.... done server started # Postmaster PID for node "gnat" is 62817 # Running: ipcs -am 3,12c3,13 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 < m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:40:39 22:36:59 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 < m 393223 1606915 --rw------- postgres postgres postgres postgres 5 4096 35790 35790 22:40:40 22:40:40 22:40:40 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 < m 131082 1384663 --rw------- postgres postgres postgres postgres 5 4096 36416 36416 22:40:44 no-entry 22:40:44 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 < m 196621 1558607 --rw------- postgres postgres postgres postgres 5 4096 35745 35745 22:40:40 no-entry 22:40:40 --- > m 1769472 1289235 --rw------- postgres postgres postgres postgres 5 4096 41838 41838 22:41:26 no-entry 22:41:26 > m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:44:19 22:36:59 > m 720898 1496258 --rw------- postgres postgres postgres postgres 6 4096 61988 61988 22:44:19 no-entry 22:44:19 > m 524291 1638467 --rw------- postgres postgres postgres postgres 6 4096 62817 62817 22:44:25 22:44:25 22:44:25 > m 786437 1097392 --rw------- postgres postgres postgres postgres 8 4096 15852 15852 22:37:52 22:44:25 22:37:52 > m 720902 1209697 --rw------- postgres postgres postgres postgres 7 4096 62711 62711 22:44:24 22:44:25 22:44:24 > m 458759 1464164 --rw------- postgres postgres postgres postgres 8 4096 61946 61946 22:44:19 22:44:25 22:44:19 > m 1900552 1431388 --rw------- postgres postgres postgres postgres 7 4096 41818 41818 22:41:26 22:44:25 22:41:26 > m 458762 1113315 --rw------- postgres postgres postgres postgres 7 4096 62534 62534 22:44:23 22:44:25 22:44:23 > m 262156 1432396 --rw------- postgres postgres postgres postgres 5 4096 41867 41867 22:41:26 no-entry 22:41:26 > m 262157 1415486 --rw------- postgres postgres postgres postgres 6 4096 61833 61833 22:44:18 22:44:25 22:44:18 ### Stopping node "gnat" using mode fast # Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/data/t_017_shm_gnat_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "gnat" # Running: ipcs -am 3,12c3,12 < m 524288 1335982 --rw------- postgres postgres postgres postgres 6 4096 14564 14564 22:37:41 22:40:46 22:37:41 < m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:40:39 22:36:59 < m 655362 1336408 --rw------- postgres postgres postgres postgres 7 4096 35828 35828 22:40:40 22:40:46 22:40:40 < m 851972 1177229 --rw------- postgres postgres postgres postgres 5 4096 36701 36701 22:40:46 22:40:46 22:40:46 < m 786437 1097392 --rw------- postgres postgres postgres postgres 7 4096 15852 15852 22:37:52 22:40:46 22:37:52 < m 393223 1606915 --rw------- postgres postgres postgres postgres 5 4096 35790 35790 22:40:40 22:40:40 22:40:40 < m 851977 1257037 --rw------- postgres postgres postgres postgres 7 4096 36370 36370 22:40:44 22:40:46 22:40:44 < m 131082 1384663 --rw------- postgres postgres postgres postgres 5 4096 36416 36416 22:40:44 no-entry 22:40:44 < m 196619 1479694 --rw------- postgres postgres postgres postgres 8 4096 35701 35701 22:40:39 22:40:46 22:40:39 < m 196621 1558607 --rw------- postgres postgres postgres postgres 5 4096 35745 35745 22:40:40 no-entry 22:40:40 --- > m 1769472 1289235 --rw------- postgres postgres postgres postgres 5 4096 41838 41838 22:41:26 no-entry 22:41:26 > m 65537 1018476 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 22:36:59 22:44:19 22:36:59 > m 720898 1496258 --rw------- postgres postgres postgres postgres 6 4096 61988 61988 22:44:19 no-entry 22:44:19 > m 786437 1097392 --rw------- postgres postgres postgres postgres 8 4096 15852 15852 22:37:52 22:44:25 22:37:52 > m 720902 1209697 --rw------- postgres postgres postgres postgres 7 4096 62711 62711 22:44:24 22:44:25 22:44:24 > m 458759 1464164 --rw------- postgres postgres postgres postgres 8 4096 61946 61946 22:44:19 22:44:25 22:44:19 > m 1900552 1431388 --rw------- postgres postgres postgres postgres 7 4096 41818 41818 22:41:26 22:44:25 22:41:26 > m 458762 1113315 --rw------- postgres postgres postgres postgres 7 4096 62534 62534 22:44:23 22:44:25 22:44:23 > m 262156 1432396 --rw------- postgres postgres postgres postgres 5 4096 41867 41867 22:41:26 no-entry 22:41:26 > m 262157 1415486 --rw------- postgres postgres postgres postgres 6 4096 61833 61833 22:44:18 22:44:25 22:44:18 [22:44:25.751](0.272s) 1..4 [22:44:25.753](0.001s) # Looks like you failed 1 test of 4.