# Running: ipcs -am # Checking port 53234 # Found port 53234 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=53234 host=/tmp/S5vzUBrHit Log file: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log [15:46:49.182](0.034s) # 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 [15:46:49.235](0.053s) # gnat's datadir inode = 206772 # Running: ipcs -am 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 7 4096 36750 36750 15:46:49 15:46:49 15:46:49 7a8 > m 720901 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 0 no-entry no-entry 15:46:49 ### 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 36781 # Running: ipcs -am 7a8 > m 720901 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 36781 15:46:49 15:46:49 15:46:49 8a10 > m 327687 206773 --rw------- postgres postgres postgres postgres 6 4096 36781 36781 15:46:49 15:46:49 15:46:49 ### 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 36818 # Running: ipcs -am 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 7a8 > m 720901 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 36818 15:46:49 15:46:49 15:46:49 8a10 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a15 > m 196620 206773 --rw------- postgres postgres postgres postgres 6 4096 36818 36818 15:46:49 15:46:49 15:46:49 ### Killing node "gnat" using signal 9 # Running: ipcs -am 3c3 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 --- > m 524288 2496951 --rw------- postgres postgres postgres postgres 7 4096 14432 14432 15:43:42 15:46:49 15:43:42 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 7a8 > m 720901 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 36818 15:46:49 15:46:49 15:46:49 8a10 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 10c12 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 196617 2337995 --rw------- postgres postgres postgres postgres 8 4096 35899 35899 15:46:43 15:46:49 15:46:43 12a15 > m 196620 206773 --rw------- postgres postgres postgres postgres 0 4096 36818 36818 15:46:49 15:46:49 15:46:49 ### 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 36860 # Running: ipcs -am 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 7a8 > m 720901 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 36860 15:46:49 15:46:49 15:46:49 8a10 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a15 > m 262156 206773 --rw------- postgres postgres postgres postgres 6 4096 36860 36860 15:46:49 15:46:49 15:46:49 [15:46:49.747](0.512s) # removing the conflicting shmem ... # Running: ipcs -am 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 8a9 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a14 > m 262156 206773 --rw------- postgres postgres postgres postgres 6 4096 36860 36860 15:46:49 15:46:49 15:46:49 ### Killing node "gnat" using signal 9 # Running: ipcs -am 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 8a9 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a14 > m 262156 206773 --rw------- postgres postgres postgres postgres 0 4096 36860 36860 15:46:49 15:46:49 15:46:49 ### 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 36889 # Running: ipcs -am 5,6c5,6 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 > m 786435 2482926 --rw------- postgres postgres postgres postgres 8 4096 36435 36435 15:46:46 15:46:49 15:46:46 7a8 > m 786437 206772 --rw------- postgres postgres postgres postgres 6 4096 36889 36889 15:46:49 15:46:49 15:46:49 8a10 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a15 > m 262156 206773 --rw------- postgres postgres postgres postgres 0 4096 36860 36889 15:46:49 15:46:49 15:46:49 ### 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 5c5 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 8a9 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a14 > m 262156 206773 --rw------- postgres postgres postgres postgres 0 4096 36860 36889 15:46:49 15:46:49 15:46:49 [15:46:50.008](0.261s) # re-creating conflicting shmem ... # Running: ipcs -am 5,6c5,6 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:49 15:46:49 > m 786435 2482926 --rw------- postgres postgres postgres postgres 8 4096 36435 36435 15:46:46 15:46:49 15:46:46 7a8 > m 851973 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 0 no-entry no-entry 15:46:50 8a10 > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 12a15 > m 262156 206773 --rw------- postgres postgres postgres postgres 0 4096 36860 36889 15:46:49 15:46:49 15:46:49 ### 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 36929 # Running: ipcs -am 3c3 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 --- > m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:50 15:43:42 5,6c5,6 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:50 15:46:49 > m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:50 15:46:46 7a8 > m 851973 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 36929 15:46:50 15:46:50 15:46:50 9,10c10,12 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 > m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:50 15:46:41 > m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:50 15:46:43 13c15,16 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 327692 206773 --rw------- postgres postgres postgres postgres 6 4096 36929 36929 15:46:50 15:46:50 15:46:50 > m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:50 15:43:53 ### 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 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 --- > m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:50 15:43:42 5,6c5,6 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:50 15:46:49 > m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:50 15:46:46 7a8 > m 851973 206772 --rw------- postgres postgres postgres postgres 0 4096 36744 36929 15:46:50 15:46:50 15:46:50 9,10c10,12 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 > m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:50 15:46:41 > m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:50 15:46:43 13c15 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:50 15:43:53 [15:46:50.276](0.268s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 --- > m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:50 15:43:42 5,6c5,6 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 6 4096 36750 36750 15:46:49 15:46:50 15:46:49 > m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:50 15:46:46 9,10c9,11 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 > m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:50 15:46:41 > m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:50 15:46:43 13c14 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:50 15:43:53 ### 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 36971 # Running: ipcs -am 3c3 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 --- > m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:50 15:43:42 5,6c5,6 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 --- > m 458754 2418703 --rw------- postgres postgres postgres postgres 7 4096 36750 36750 15:46:49 15:46:50 15:46:49 > m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:50 15:46:46 7a8 > m 917509 206772 --rw------- postgres postgres postgres postgres 6 4096 36971 36971 15:46:50 15:46:50 15:46:50 9,10c10,12 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 393223 96360 --rw------- postgres postgres postgres postgres 5 4096 36809 36809 15:46:49 no-entry 15:46:49 > m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:50 15:46:41 > m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:50 15:46:43 13c15 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:50 15:43:53 [15:50:32.070](221.794s) # 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 [15:50:32.070](0.001s) not ok 1 - slow query started [15:50:32.071](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,10c3,9 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 < m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:46:40 15:43:00 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 < m 262148 32427 --rw------- postgres postgres postgres postgres 5 4096 35727 35727 15:46:42 no-entry 15:46:42 < m 458758 80439 --rw------- postgres postgres postgres postgres 5 4096 36471 36471 15:46:47 no-entry 15:46:47 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 1769472 37 --rw------- postgres postgres postgres postgres 5 4096 41605 41605 15:47:27 no-entry 15:47:27 > m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:50:20 15:43:00 > m 327684 2258409 --rw------- postgres postgres postgres postgres 6 4096 61771 61771 15:50:24 15:50:32 15:50:24 > m 917509 206772 --rw------- postgres postgres postgres postgres 1 4096 36971 36971 15:46:50 15:50:32 15:46:50 > m 786438 2306502 --rw------- postgres postgres postgres postgres 7 4096 62533 62533 15:50:29 15:50:32 15:50:29 > m 589831 176252 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 15:50:32 15:50:32 15:50:32 > m 524296 207550 --rw------- postgres postgres postgres postgres 6 4096 62034 62034 15:50:26 no-entry 15:50:26 12,13c11,14 < m 327691 111939 --rw------- postgres postgres postgres postgres 5 4096 35855 35855 15:46:43 15:46:43 15:46:43 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 393227 2576469 --rw------- postgres postgres postgres postgres 8 4096 61992 61992 15:50:25 15:50:32 15:50:25 > m 1835020 2560578 --rw------- postgres postgres postgres postgres 7 4096 41561 41561 15:47:26 15:50:32 15:47:26 > m 393229 2561294 --rw------- postgres postgres postgres postgres 5 4096 41782 41782 15:47:28 no-entry 15:47:28 > m 720912 2274997 --rw------- postgres postgres postgres postgres 8 4096 15686 15686 15:43:53 15:50:32 15:43:53 ### 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-19 15:50:32.121 UTC [62852][postmaster] FATAL: pre-existing shared memory block (key 206772, ID 917509) is still in use 2024-03-19 15:50:32.121 UTC [62852][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-19 15:50:32.121 UTC [62852][postmaster] LOG: database system is shut down # No postmaster PID for node "gnat" [15:50:32.219](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 [15:50:32.231](0.012s) ok 3 - live query blocks --single 2024-03-19 15:50:32.230 UTC [62870][standalone backend] FATAL: pre-existing shared memory block (key 206772, ID 917509) is still in use 2024-03-19 15:50:32.230 UTC [62870][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". [15:50:32.231](0.000s) ok 4 - single-user mode detected live backend via shared memory # Running: ipcs -am 3,10c3,9 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 < m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:46:40 15:43:00 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 < m 262148 32427 --rw------- postgres postgres postgres postgres 5 4096 35727 35727 15:46:42 no-entry 15:46:42 < m 458758 80439 --rw------- postgres postgres postgres postgres 5 4096 36471 36471 15:46:47 no-entry 15:46:47 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 1769472 37 --rw------- postgres postgres postgres postgres 5 4096 41605 41605 15:47:27 no-entry 15:47:27 > m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:50:20 15:43:00 > m 327684 2258409 --rw------- postgres postgres postgres postgres 6 4096 61771 61771 15:50:24 15:50:32 15:50:24 > m 917509 206772 --rw------- postgres postgres postgres postgres 1 4096 36971 62870 15:50:32 15:50:32 15:46:50 > m 786438 2306502 --rw------- postgres postgres postgres postgres 7 4096 62533 62533 15:50:29 15:50:32 15:50:29 > m 589831 176252 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 15:50:32 15:50:32 15:50:32 > m 524296 207550 --rw------- postgres postgres postgres postgres 6 4096 62034 62034 15:50:26 no-entry 15:50:26 12,13c11,14 < m 327691 111939 --rw------- postgres postgres postgres postgres 5 4096 35855 35855 15:46:43 15:46:43 15:46:43 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 393227 2576469 --rw------- postgres postgres postgres postgres 8 4096 61992 61992 15:50:25 15:50:32 15:50:25 > m 1835020 2560578 --rw------- postgres postgres postgres postgres 7 4096 41561 41561 15:47:26 15:50:32 15:47:26 > m 393229 2561294 --rw------- postgres postgres postgres postgres 5 4096 41782 41782 15:47:28 no-entry 15:47:28 > m 720912 2274997 --rw------- postgres postgres postgres postgres 8 4096 15686 15686 15:43:53 15:50:32 15:43:53 # Running: pg_ctl kill QUIT 37005 # Running: ipcs -am 3,10c3,9 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 < m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:46:40 15:43:00 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 < m 262148 32427 --rw------- postgres postgres postgres postgres 5 4096 35727 35727 15:46:42 no-entry 15:46:42 < m 458758 80439 --rw------- postgres postgres postgres postgres 5 4096 36471 36471 15:46:47 no-entry 15:46:47 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 1769472 37 --rw------- postgres postgres postgres postgres 5 4096 41605 41605 15:47:27 no-entry 15:47:27 > m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:50:20 15:43:00 > m 327684 2258409 --rw------- postgres postgres postgres postgres 6 4096 61771 61771 15:50:24 15:50:32 15:50:24 > m 917509 206772 --rw------- postgres postgres postgres postgres 0 4096 36971 62870 15:50:32 15:50:32 15:46:50 > m 786438 2306502 --rw------- postgres postgres postgres postgres 7 4096 62533 62533 15:50:29 15:50:32 15:50:29 > m 589831 176252 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 15:50:32 15:50:32 15:50:32 > m 524296 207550 --rw------- postgres postgres postgres postgres 6 4096 62034 62034 15:50:26 no-entry 15:50:26 12,13c11,14 < m 327691 111939 --rw------- postgres postgres postgres postgres 5 4096 35855 35855 15:46:43 15:46:43 15:46:43 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 393227 2576469 --rw------- postgres postgres postgres postgres 8 4096 61992 61992 15:50:25 15:50:32 15:50:25 > m 1835020 2560578 --rw------- postgres postgres postgres postgres 7 4096 41561 41561 15:47:26 15:50:32 15:47:26 > m 393229 2561294 --rw------- postgres postgres postgres postgres 5 4096 41782 41782 15:47:28 no-entry 15:47:28 > m 720912 2274997 --rw------- postgres postgres postgres postgres 8 4096 15686 15686 15:43:53 15:50:32 15:43:53 ### 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 62886 # Running: ipcs -am 3,10c3,9 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 < m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:46:40 15:43:00 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 < m 262148 32427 --rw------- postgres postgres postgres postgres 5 4096 35727 35727 15:46:42 no-entry 15:46:42 < m 458758 80439 --rw------- postgres postgres postgres postgres 5 4096 36471 36471 15:46:47 no-entry 15:46:47 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 1769472 37 --rw------- postgres postgres postgres postgres 5 4096 41605 41605 15:47:27 no-entry 15:47:27 > m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:50:20 15:43:00 > m 327684 2258409 --rw------- postgres postgres postgres postgres 6 4096 61771 61771 15:50:24 15:50:32 15:50:24 > m 983045 206772 --rw------- postgres postgres postgres postgres 6 4096 62886 62886 15:50:32 15:50:32 15:50:32 > m 786438 2306502 --rw------- postgres postgres postgres postgres 7 4096 62533 62533 15:50:29 15:50:32 15:50:29 > m 589831 176252 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 15:50:32 15:50:32 15:50:32 > m 524296 207550 --rw------- postgres postgres postgres postgres 6 4096 62034 62034 15:50:26 no-entry 15:50:26 12,13c11,14 < m 327691 111939 --rw------- postgres postgres postgres postgres 5 4096 35855 35855 15:46:43 15:46:43 15:46:43 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 393227 2576469 --rw------- postgres postgres postgres postgres 8 4096 61992 61992 15:50:25 15:50:32 15:50:25 > m 1835020 2560578 --rw------- postgres postgres postgres postgres 7 4096 41561 41561 15:47:26 15:50:32 15:47:26 > m 393229 2561294 --rw------- postgres postgres postgres postgres 5 4096 41782 41782 15:47:28 no-entry 15:47:28 > m 720912 2274997 --rw------- postgres postgres postgres postgres 8 4096 15686 15686 15:43:53 15:50:32 15:43:53 ### 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,10c3,8 < m 524288 2496951 --rw------- postgres postgres postgres postgres 6 4096 14432 14432 15:43:42 15:46:49 15:43:42 < m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:46:40 15:43:00 < m 458754 2418703 --rw------- postgres postgres postgres postgres 5 4096 36750 36750 15:46:49 15:46:49 15:46:49 < m 786435 2482926 --rw------- postgres postgres postgres postgres 7 4096 36435 36435 15:46:46 15:46:49 15:46:46 < m 262148 32427 --rw------- postgres postgres postgres postgres 5 4096 35727 35727 15:46:42 no-entry 15:46:42 < m 458758 80439 --rw------- postgres postgres postgres postgres 5 4096 36471 36471 15:46:47 no-entry 15:46:47 < m 458760 2608274 --rw------- postgres postgres postgres postgres 8 4096 35682 35682 15:46:41 15:46:49 15:46:41 < m 196617 2337995 --rw------- postgres postgres postgres postgres 7 4096 35899 35899 15:46:43 15:46:49 15:46:43 --- > m 1769472 37 --rw------- postgres postgres postgres postgres 5 4096 41605 41605 15:47:27 no-entry 15:47:27 > m 65537 2163922 --rw------- postgres postgres postgres postgres 10 4096 7400 7400 15:43:00 15:50:20 15:43:00 > m 327684 2258409 --rw------- postgres postgres postgres postgres 6 4096 61771 61771 15:50:24 15:50:32 15:50:24 > m 786438 2306502 --rw------- postgres postgres postgres postgres 7 4096 62533 62533 15:50:29 15:50:32 15:50:29 > m 589831 176252 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 15:50:32 15:50:32 15:50:32 > m 524296 207550 --rw------- postgres postgres postgres postgres 6 4096 62034 62034 15:50:26 no-entry 15:50:26 12,13c10,13 < m 327691 111939 --rw------- postgres postgres postgres postgres 5 4096 35855 35855 15:46:43 15:46:43 15:46:43 < m 720912 2274997 --rw------- postgres postgres postgres postgres 7 4096 15686 15686 15:43:53 15:46:49 15:43:53 --- > m 393227 2576469 --rw------- postgres postgres postgres postgres 8 4096 61992 61992 15:50:25 15:50:32 15:50:25 > m 1835020 2560578 --rw------- postgres postgres postgres postgres 7 4096 41561 41561 15:47:26 15:50:32 15:47:26 > m 393229 2561294 --rw------- postgres postgres postgres postgres 5 4096 41782 41782 15:47:28 no-entry 15:47:28 > m 720912 2274997 --rw------- postgres postgres postgres postgres 8 4096 15686 15686 15:43:53 15:50:32 15:43:53 [15:50:32.509](0.278s) 1..4 [15:50:32.510](0.001s) # Looks like you failed 1 test of 4.