# Running: ipcs -am # Checking port 54070 # Found port 54070 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=54070 host=/tmp/pgubchRV6x Log file: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log [13:40:29.483](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 [13:40:29.523](0.040s) # gnat's datadir inode = 365804 # Running: ipcs -am 11a12 > m 262153 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 0 no-entry no-entry 13:40:29 ### 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 36820 # Running: ipcs -am 11a12,13 > m 262153 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 36820 13:40:29 13:40:29 13:40:29 > m 196618 365805 --rw------- postgres postgres postgres postgres 6 4096 36820 36820 13:40:29 13:40:29 13:40:29 ### 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 36854 # Running: ipcs -am 11a12,13 > m 262153 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 36854 13:40:29 13:40:29 13:40:29 > m 262154 365805 --rw------- postgres postgres postgres postgres 6 4096 36854 36854 13:40:29 13:40:29 13:40:29 ### Killing node "gnat" using signal 9 # Running: ipcs -am 11a12,13 > m 262153 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 36854 13:40:29 13:40:29 13:40:29 > m 262154 365805 --rw------- postgres postgres postgres postgres 0 4096 36854 36854 13:40:29 13:40:29 13:40:29 ### 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 36879 # Running: ipcs -am 11a12,13 > m 262153 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 36879 13:40:29 13:40:29 13:40:29 > m 327690 365805 --rw------- postgres postgres postgres postgres 6 4096 36879 36879 13:40:29 13:40:29 13:40:29 [13:40:30.023](0.500s) # removing the conflicting shmem ... # Running: ipcs -am 11a12 > m 327690 365805 --rw------- postgres postgres postgres postgres 6 4096 36879 36879 13:40:29 13:40:29 13:40:29 ### Killing node "gnat" using signal 9 # Running: ipcs -am 11a12 > m 327690 365805 --rw------- postgres postgres postgres postgres 0 4096 36879 36879 13:40:29 13:40:30 13:40:29 ### 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 36906 # Running: ipcs -am 3c3 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:30 13:40:28 11a12,13 > m 327689 365804 --rw------- postgres postgres postgres postgres 6 4096 36906 36906 13:40:30 13:40:30 13:40:30 > m 327690 365805 --rw------- postgres postgres postgres postgres 0 4096 36879 36906 13:40:30 13:40:30 13:40:29 13,14c15,16 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:30 13:37:35 ### 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 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:30 13:40:28 11a12 > m 327690 365805 --rw------- postgres postgres postgres postgres 0 4096 36879 36906 13:40:30 13:40:30 13:40:29 13,14c14,15 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:30 13:37:35 [13:40:30.313](0.290s) # re-creating conflicting shmem ... # Running: ipcs -am 3c3 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:30 13:40:28 11a12,13 > m 393225 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 0 no-entry no-entry 13:40:30 > m 327690 365805 --rw------- postgres postgres postgres postgres 0 4096 36879 36906 13:40:30 13:40:30 13:40:29 13,14c15,16 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:30 13:37:35 ### 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 36958 # Running: ipcs -am 3c3 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 8 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 7 4096 36669 36669 13:40:28 13:40:30 13:40:28 11a12,13 > m 393225 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 36958 13:40:30 13:40:30 13:40:30 > m 393226 365805 --rw------- postgres postgres postgres postgres 6 4096 36958 36958 13:40:30 13:40:30 13:40:30 13,14c15,16 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 9 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 8 4096 15714 15714 13:37:35 13:40:30 13:37:35 ### 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 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 7 4096 36669 36669 13:40:28 13:40:30 13:40:28 11a12 > m 393225 365804 --rw------- postgres postgres postgres postgres 0 4096 36787 36958 13:40:30 13:40:30 13:40:30 13,14c14,15 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:30 13:37:35 [13:40:30.585](0.272s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:30 13:40:28 13,14c13,14 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:30 13:37:35 ### 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 36998 # Running: ipcs -am 3c3 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 --- > m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:30 13:37:25 6,7c6,7 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 --- > m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:30 13:40:23 > m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:30 13:40:27 10c10 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 --- > m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:30 13:40:28 11a12 > m 458761 365804 --rw------- postgres postgres postgres postgres 6 4096 36998 36998 13:40:30 13:40:30 13:40:30 13,14c14,15 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:30 13:40:23 > m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:30 13:37:35 [13:44:09.364](218.778s) # 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 [13:44:09.364](0.000s) not ok 1 - slow query started [13:44:09.364](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,4c3,4 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 < m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:40:23 13:36:43 --- > m 1703936 286899 --rw------- postgres postgres postgres postgres 5 4096 41732 41732 13:41:08 no-entry 13:41:08 > m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:44:03 13:36:43 6,14c6,14 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 < m 196613 604977 --rw------- postgres postgres postgres postgres 5 4096 36714 36714 13:40:28 no-entry 13:40:28 < m 196614 493651 --rw------- postgres postgres postgres postgres 5 4096 35831 35831 13:40:23 13:40:23 13:40:23 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 < m 196616 588760 --rw------- postgres postgres postgres postgres 5 4096 36569 36569 13:40:27 no-entry 13:40:27 < m 262155 524847 --rw------- postgres postgres postgres postgres 5 4096 35842 35842 13:40:23 no-entry 13:40:23 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 262147 334605 --rw------- postgres postgres postgres postgres 8 4096 61989 61989 13:44:03 13:44:09 13:44:03 > m 393221 33211 --rw------- postgres postgres postgres postgres 7 4096 62724 62724 13:44:08 13:44:09 13:44:08 > m 262150 318090 --rw------- postgres postgres postgres postgres 6 4096 61946 61946 13:44:03 13:44:09 13:44:03 > m 524296 558249 --rw------- postgres postgres postgres postgres 7 4096 62552 62552 13:44:07 13:44:09 13:44:07 > m 458761 365804 --rw------- postgres postgres postgres postgres 1 4096 36998 36998 13:40:30 13:44:09 13:40:30 > m 1900554 270718 --rw------- postgres postgres postgres postgres 7 4096 41760 41760 13:41:08 13:44:09 13:41:08 > m 327691 541413 --rw------- postgres postgres postgres postgres 6 4096 62031 62031 13:44:03 no-entry 13:44:03 > m 393229 397614 --rw------- postgres postgres postgres postgres 5 4096 41805 41805 13:41:08 no-entry 13:41:08 > m 851984 2593372 --rw------- postgres postgres postgres postgres 8 4096 15714 15714 13:37:35 13:44:09 13:37:35 ### 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-28 13:44:09.406 UTC [62843][postmaster] FATAL: pre-existing shared memory block (key 365804, ID 458761) is still in use 2024-03-28 13:44:09.406 UTC [62843][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-28 13:44:09.406 UTC [62843][postmaster] LOG: database system is shut down # No postmaster PID for node "gnat" [13:44:09.502](0.138s) 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 [13:44:09.511](0.009s) ok 3 - live query blocks --single 2024-03-28 13:44:09.510 UTC [62856][standalone backend] FATAL: pre-existing shared memory block (key 365804, ID 458761) is still in use 2024-03-28 13:44:09.510 UTC [62856][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". [13:44:09.511](0.000s) ok 4 - single-user mode detected live backend via shared memory # Running: ipcs -am 3,4c3,4 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 < m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:40:23 13:36:43 --- > m 1703936 286899 --rw------- postgres postgres postgres postgres 5 4096 41732 41732 13:41:08 no-entry 13:41:08 > m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:44:03 13:36:43 6,14c6,14 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 < m 196613 604977 --rw------- postgres postgres postgres postgres 5 4096 36714 36714 13:40:28 no-entry 13:40:28 < m 196614 493651 --rw------- postgres postgres postgres postgres 5 4096 35831 35831 13:40:23 13:40:23 13:40:23 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 < m 196616 588760 --rw------- postgres postgres postgres postgres 5 4096 36569 36569 13:40:27 no-entry 13:40:27 < m 262155 524847 --rw------- postgres postgres postgres postgres 5 4096 35842 35842 13:40:23 no-entry 13:40:23 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 262147 334605 --rw------- postgres postgres postgres postgres 8 4096 61989 61989 13:44:03 13:44:09 13:44:03 > m 393221 33211 --rw------- postgres postgres postgres postgres 7 4096 62724 62724 13:44:08 13:44:09 13:44:08 > m 262150 318090 --rw------- postgres postgres postgres postgres 6 4096 61946 61946 13:44:03 13:44:09 13:44:03 > m 524296 558249 --rw------- postgres postgres postgres postgres 7 4096 62552 62552 13:44:07 13:44:09 13:44:07 > m 458761 365804 --rw------- postgres postgres postgres postgres 1 4096 36998 62856 13:44:09 13:44:09 13:40:30 > m 1900554 270718 --rw------- postgres postgres postgres postgres 7 4096 41760 41760 13:41:08 13:44:09 13:41:08 > m 327691 541413 --rw------- postgres postgres postgres postgres 6 4096 62031 62031 13:44:03 no-entry 13:44:03 > m 393229 397614 --rw------- postgres postgres postgres postgres 5 4096 41805 41805 13:41:08 no-entry 13:41:08 > m 851984 2593372 --rw------- postgres postgres postgres postgres 8 4096 15714 15714 13:37:35 13:44:09 13:37:35 # Running: pg_ctl kill QUIT 37023 # Running: ipcs -am 3,4c3,4 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 < m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:40:23 13:36:43 --- > m 1703936 286899 --rw------- postgres postgres postgres postgres 5 4096 41732 41732 13:41:08 no-entry 13:41:08 > m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:44:03 13:36:43 6,14c6,14 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 < m 196613 604977 --rw------- postgres postgres postgres postgres 5 4096 36714 36714 13:40:28 no-entry 13:40:28 < m 196614 493651 --rw------- postgres postgres postgres postgres 5 4096 35831 35831 13:40:23 13:40:23 13:40:23 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 < m 196616 588760 --rw------- postgres postgres postgres postgres 5 4096 36569 36569 13:40:27 no-entry 13:40:27 < m 262155 524847 --rw------- postgres postgres postgres postgres 5 4096 35842 35842 13:40:23 no-entry 13:40:23 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 262147 334605 --rw------- postgres postgres postgres postgres 8 4096 61989 61989 13:44:03 13:44:09 13:44:03 > m 393221 33211 --rw------- postgres postgres postgres postgres 7 4096 62724 62724 13:44:08 13:44:09 13:44:08 > m 262150 318090 --rw------- postgres postgres postgres postgres 6 4096 61946 61946 13:44:03 13:44:09 13:44:03 > m 524296 558249 --rw------- postgres postgres postgres postgres 7 4096 62552 62552 13:44:07 13:44:09 13:44:07 > m 458761 365804 --rw------- postgres postgres postgres postgres 0 4096 36998 62856 13:44:09 13:44:09 13:40:30 > m 1900554 270718 --rw------- postgres postgres postgres postgres 7 4096 41760 41760 13:41:08 13:44:09 13:41:08 > m 327691 541413 --rw------- postgres postgres postgres postgres 6 4096 62031 62031 13:44:03 no-entry 13:44:03 > m 393229 397614 --rw------- postgres postgres postgres postgres 5 4096 41805 41805 13:41:08 no-entry 13:41:08 > m 851984 2593372 --rw------- postgres postgres postgres postgres 8 4096 15714 15714 13:37:35 13:44:09 13:37:35 ### 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 62864 # Running: ipcs -am 3,4c3,4 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 < m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:40:23 13:36:43 --- > m 1703936 286899 --rw------- postgres postgres postgres postgres 5 4096 41732 41732 13:41:08 no-entry 13:41:08 > m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:44:03 13:36:43 6,14c6,14 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 < m 196613 604977 --rw------- postgres postgres postgres postgres 5 4096 36714 36714 13:40:28 no-entry 13:40:28 < m 196614 493651 --rw------- postgres postgres postgres postgres 5 4096 35831 35831 13:40:23 13:40:23 13:40:23 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 < m 196616 588760 --rw------- postgres postgres postgres postgres 5 4096 36569 36569 13:40:27 no-entry 13:40:27 < m 262155 524847 --rw------- postgres postgres postgres postgres 5 4096 35842 35842 13:40:23 no-entry 13:40:23 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 262147 334605 --rw------- postgres postgres postgres postgres 8 4096 61989 61989 13:44:03 13:44:09 13:44:03 > m 393221 33211 --rw------- postgres postgres postgres postgres 7 4096 62724 62724 13:44:08 13:44:09 13:44:08 > m 262150 318090 --rw------- postgres postgres postgres postgres 6 4096 61946 61946 13:44:03 13:44:09 13:44:03 > m 524296 558249 --rw------- postgres postgres postgres postgres 7 4096 62552 62552 13:44:07 13:44:09 13:44:07 > m 524297 365804 --rw------- postgres postgres postgres postgres 6 4096 62864 62864 13:44:09 13:44:09 13:44:09 > m 1900554 270718 --rw------- postgres postgres postgres postgres 7 4096 41760 41760 13:41:08 13:44:09 13:41:08 > m 327691 541413 --rw------- postgres postgres postgres postgres 6 4096 62031 62031 13:44:03 no-entry 13:44:03 > m 393229 397614 --rw------- postgres postgres postgres postgres 5 4096 41805 41805 13:41:08 no-entry 13:41:08 > m 851984 2593372 --rw------- postgres postgres postgres postgres 8 4096 15714 15714 13:37:35 13:44:09 13:37:35 ### 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,4c3,4 < m 458752 2609279 --rw------- postgres postgres postgres postgres 6 4096 14421 14421 13:37:25 13:40:29 13:37:25 < m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:40:23 13:36:43 --- > m 1703936 286899 --rw------- postgres postgres postgres postgres 5 4096 41732 41732 13:41:08 no-entry 13:41:08 > m 65537 2497552 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 13:36:43 13:44:03 13:36:43 6,14c6,13 < m 196611 144200 --rw------- postgres postgres postgres postgres 7 4096 35887 35887 13:40:23 13:40:29 13:40:23 < m 655364 525264 --rw------- postgres postgres postgres postgres 7 4096 36528 36528 13:40:27 13:40:29 13:40:27 < m 196613 604977 --rw------- postgres postgres postgres postgres 5 4096 36714 36714 13:40:28 no-entry 13:40:28 < m 196614 493651 --rw------- postgres postgres postgres postgres 5 4096 35831 35831 13:40:23 13:40:23 13:40:23 < m 1179655 127342 --rw------- postgres postgres postgres postgres 6 4096 36669 36669 13:40:28 13:40:29 13:40:28 < m 196616 588760 --rw------- postgres postgres postgres postgres 5 4096 36569 36569 13:40:27 no-entry 13:40:27 < m 262155 524847 --rw------- postgres postgres postgres postgres 5 4096 35842 35842 13:40:23 no-entry 13:40:23 < m 327692 1113 --rw------- postgres postgres postgres postgres 8 4096 35804 35804 13:40:23 13:40:29 13:40:23 < m 851984 2593372 --rw------- postgres postgres postgres postgres 7 4096 15714 15714 13:37:35 13:40:29 13:37:35 --- > m 262147 334605 --rw------- postgres postgres postgres postgres 8 4096 61989 61989 13:44:03 13:44:09 13:44:03 > m 393221 33211 --rw------- postgres postgres postgres postgres 7 4096 62724 62724 13:44:08 13:44:09 13:44:08 > m 262150 318090 --rw------- postgres postgres postgres postgres 6 4096 61946 61946 13:44:03 13:44:09 13:44:03 > m 524296 558249 --rw------- postgres postgres postgres postgres 7 4096 62552 62552 13:44:07 13:44:09 13:44:07 > m 1900554 270718 --rw------- postgres postgres postgres postgres 7 4096 41760 41760 13:41:08 13:44:09 13:41:08 > m 327691 541413 --rw------- postgres postgres postgres postgres 6 4096 62031 62031 13:44:03 no-entry 13:44:03 > m 393229 397614 --rw------- postgres postgres postgres postgres 5 4096 41805 41805 13:41:08 no-entry 13:41:08 > m 851984 2593372 --rw------- postgres postgres postgres postgres 8 4096 15714 15714 13:37:35 13:44:09 13:37:35 [13:44:09.799](0.288s) 1..4 [13:44:09.800](0.001s) # Looks like you failed 1 test of 4.