# Running: ipcs -am # Checking port 50048 # Found port 50048 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=50048 host=/tmp/ywksF4KmAB Log file: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log [00:10:28.907](0.022s) # 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 [00:10:28.944](0.036s) # gnat's datadir inode = 175561 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:28 0:07:21 6c6,7 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 --- > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:28 0:10:28 > m 262148 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 0 no-entry no-entry 0:10:28 8c9 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:28 0:10:22 ### 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 37027 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 262148 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 37027 0:10:28 0:10:28 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 262152 175562 --rw------- postgres postgres postgres postgres 6 4096 37027 37027 0:10:28 0:10:28 0:10:28 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 37061 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 262148 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 37061 0:10:29 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 327688 175562 --rw------- postgres postgres postgres postgres 6 4096 37061 37061 0:10:29 0:10:29 0:10:29 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### Killing node "gnat" using signal 9 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 262148 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 37061 0:10:29 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 327688 175562 --rw------- postgres postgres postgres postgres 0 4096 37061 37061 0:10:29 0:10:29 0:10:29 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 37086 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 262148 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 37086 0:10:29 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 393224 175562 --rw------- postgres postgres postgres postgres 6 4096 37086 37086 0:10:29 0:10:29 0:10:29 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 [00:10:29.462](0.519s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,8 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a10 > m 393224 175562 --rw------- postgres postgres postgres postgres 6 4096 37086 37086 0:10:29 0:10:29 0:10:29 12c13 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### Killing node "gnat" using signal 9 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,8 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a10 > m 393224 175562 --rw------- postgres postgres postgres postgres 0 4096 37086 37086 0:10:29 0:10:29 0:10:29 12c13 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 37125 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 327684 175561 --rw------- postgres postgres postgres postgres 6 4096 37125 37125 0:10:29 0:10:29 0:10:29 > m 655365 2592525 --rw------- postgres postgres postgres postgres 8 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 393224 175562 --rw------- postgres postgres postgres postgres 0 4096 37086 37125 0:10:29 0:10:29 0:10:29 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 8 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,8 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a10 > m 393224 175562 --rw------- postgres postgres postgres postgres 0 4096 37086 37125 0:10:29 0:10:29 0:10:29 12c13 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 8 4096 36458 36458 0:10:25 0:10:29 0:10:25 [00:10:29.740](0.278s) # re-creating conflicting shmem ... # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 393220 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 0 no-entry no-entry 0:10:29 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 393224 175562 --rw------- postgres postgres postgres postgres 0 4096 37086 37125 0:10:29 0:10:29 0:10:29 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 37165 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 393220 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 37165 0:10:29 0:10:29 0:10:29 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 9a11 > m 458760 175562 --rw------- postgres postgres postgres postgres 6 4096 37165 37165 0:10:29 0:10:29 0:10:29 12c14 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 393220 175561 --rw------- postgres postgres postgres postgres 0 4096 37006 37165 0:10:29 0:10:29 0:10:29 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 12c13 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 [00:10:30.007](0.267s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:29 0:07:21 5,8c5,8 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:29 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:29 0:10:28 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:29 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:29 0:10:22 12c12 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:29 0:10:25 ### 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 37205 # Running: ipcs -am 3c3 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 --- > m 524288 1652 --rw------- postgres postgres postgres postgres 6 4096 14193 14193 0:07:21 0:10:30 0:07:21 5,8c5,9 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 --- > m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:30 0:10:21 > m 524291 365816 --rw------- postgres postgres postgres postgres 6 4096 36897 36897 0:10:28 0:10:30 0:10:28 > m 458756 175561 --rw------- postgres postgres postgres postgres 6 4096 37205 37205 0:10:30 0:10:30 0:10:30 > m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:30 0:07:30 > m 524294 2576519 --rw------- postgres postgres postgres postgres 7 4096 36013 36013 0:10:22 0:10:30 0:10:22 12c13 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 --- > m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:30 0:10:25 [00:14:05.175](215.168s) # 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 [00:14:05.176](0.002s) not ok 1 - slow query started [00:14:05.177](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,13c3,13 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 < m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:10:24 0:06:44 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 < m 655367 255435 --rw------- postgres postgres postgres postgres 5 4096 36495 36495 0:10:25 no-entry 0:10:25 < m 131081 270684 --rw------- postgres postgres postgres postgres 5 4096 35869 35869 0:10:21 no-entry 0:10:21 < m 196618 334296 --rw------- postgres postgres postgres postgres 5 4096 35979 35979 0:10:22 0:10:22 0:10:22 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 < m 327692 382398 --rw------- postgres postgres postgres postgres 5 4096 36939 36939 0:10:28 no-entry 0:10:28 --- > m 1769472 175257 --rw------- postgres postgres postgres postgres 5 4096 41377 41377 0:11:02 no-entry 0:11:02 > m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:14:04 0:06:44 > m 786434 239239 --rw------- postgres postgres postgres postgres 6 4096 62242 62242 0:14:00 no-entry 0:14:00 > m 458756 175561 --rw------- postgres postgres postgres postgres 1 4096 37205 37205 0:10:30 0:14:05 0:10:30 > m 655365 2592525 --rw------- postgres postgres postgres postgres 8 4096 15365 15365 0:07:30 0:14:05 0:07:30 > m 983047 255773 --rw------- postgres postgres postgres postgres 7 4096 62580 62580 0:14:02 0:14:05 0:14:02 > m 1966088 2449282 --rw------- postgres postgres postgres postgres 8 4096 41335 41335 0:11:01 0:14:05 0:11:01 > m 196617 128227 --rw------- postgres postgres postgres postgres 6 4096 62045 62045 0:13:58 0:14:05 0:13:58 > m 262154 159060 --rw------- postgres postgres postgres postgres 8 4096 62202 62202 0:13:59 0:14:05 0:13:59 > m 524300 2466130 --rw------- postgres postgres postgres postgres 7 4096 62884 62884 0:14:04 0:14:05 0:14:04 > m 327693 207377 --rw------- postgres postgres postgres postgres 5 4096 41396 41396 0:11:02 no-entry 0:11:02 ### 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 00:14:05.216 UTC [62983][postmaster] FATAL: pre-existing shared memory block (key 175561, ID 458756) is still in use 2024-03-19 00:14:05.216 UTC [62983][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 00:14:05.216 UTC [62983][postmaster] LOG: database system is shut down # No postmaster PID for node "gnat" [00:14:05.318](0.141s) 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 [00:14:05.325](0.008s) ok 3 - live query blocks --single 2024-03-19 00:14:05.325 UTC [62996][standalone backend] FATAL: pre-existing shared memory block (key 175561, ID 458756) is still in use 2024-03-19 00:14:05.325 UTC [62996][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". [00:14:05.326](0.000s) ok 4 - single-user mode detected live backend via shared memory # Running: ipcs -am 3,13c3,13 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 < m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:10:24 0:06:44 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 < m 655367 255435 --rw------- postgres postgres postgres postgres 5 4096 36495 36495 0:10:25 no-entry 0:10:25 < m 131081 270684 --rw------- postgres postgres postgres postgres 5 4096 35869 35869 0:10:21 no-entry 0:10:21 < m 196618 334296 --rw------- postgres postgres postgres postgres 5 4096 35979 35979 0:10:22 0:10:22 0:10:22 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 < m 327692 382398 --rw------- postgres postgres postgres postgres 5 4096 36939 36939 0:10:28 no-entry 0:10:28 --- > m 1769472 175257 --rw------- postgres postgres postgres postgres 5 4096 41377 41377 0:11:02 no-entry 0:11:02 > m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:14:04 0:06:44 > m 786434 239239 --rw------- postgres postgres postgres postgres 6 4096 62242 62242 0:14:00 no-entry 0:14:00 > m 458756 175561 --rw------- postgres postgres postgres postgres 1 4096 37205 62996 0:14:05 0:14:05 0:10:30 > m 655365 2592525 --rw------- postgres postgres postgres postgres 8 4096 15365 15365 0:07:30 0:14:05 0:07:30 > m 983047 255773 --rw------- postgres postgres postgres postgres 7 4096 62580 62580 0:14:02 0:14:05 0:14:02 > m 1966088 2449282 --rw------- postgres postgres postgres postgres 7 4096 41335 41335 0:11:01 0:14:05 0:11:01 > m 196617 128227 --rw------- postgres postgres postgres postgres 6 4096 62045 62045 0:13:58 0:14:05 0:13:58 > m 262154 159060 --rw------- postgres postgres postgres postgres 8 4096 62202 62202 0:13:59 0:14:05 0:13:59 > m 524300 2466130 --rw------- postgres postgres postgres postgres 7 4096 62884 62884 0:14:04 0:14:05 0:14:04 > m 327693 207377 --rw------- postgres postgres postgres postgres 5 4096 41396 41396 0:11:02 no-entry 0:11:02 # Running: pg_ctl kill QUIT 37230 # Running: ipcs -am 3,13c3,13 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 < m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:10:24 0:06:44 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 < m 655367 255435 --rw------- postgres postgres postgres postgres 5 4096 36495 36495 0:10:25 no-entry 0:10:25 < m 131081 270684 --rw------- postgres postgres postgres postgres 5 4096 35869 35869 0:10:21 no-entry 0:10:21 < m 196618 334296 --rw------- postgres postgres postgres postgres 5 4096 35979 35979 0:10:22 0:10:22 0:10:22 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 < m 327692 382398 --rw------- postgres postgres postgres postgres 5 4096 36939 36939 0:10:28 no-entry 0:10:28 --- > m 1769472 175257 --rw------- postgres postgres postgres postgres 5 4096 41377 41377 0:11:02 no-entry 0:11:02 > m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:14:04 0:06:44 > m 786434 239239 --rw------- postgres postgres postgres postgres 6 4096 62242 62242 0:14:00 no-entry 0:14:00 > m 458756 175561 --rw------- postgres postgres postgres postgres 0 4096 37205 62996 0:14:05 0:14:05 0:10:30 > m 655365 2592525 --rw------- postgres postgres postgres postgres 8 4096 15365 15365 0:07:30 0:14:05 0:07:30 > m 983047 255773 --rw------- postgres postgres postgres postgres 7 4096 62580 62580 0:14:02 0:14:05 0:14:02 > m 1966088 2449282 --rw------- postgres postgres postgres postgres 7 4096 41335 41335 0:11:01 0:14:05 0:11:01 > m 196617 128227 --rw------- postgres postgres postgres postgres 6 4096 62045 62045 0:13:58 0:14:05 0:13:58 > m 262154 159060 --rw------- postgres postgres postgres postgres 8 4096 62202 62202 0:13:59 0:14:05 0:13:59 > m 524300 2466130 --rw------- postgres postgres postgres postgres 7 4096 62884 62884 0:14:04 0:14:05 0:14:04 > m 327693 207377 --rw------- postgres postgres postgres postgres 5 4096 41396 41396 0:11:02 no-entry 0:11:02 ### 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 63004 # Running: ipcs -am 3,13c3,13 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 < m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:10:24 0:06:44 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 < m 655367 255435 --rw------- postgres postgres postgres postgres 5 4096 36495 36495 0:10:25 no-entry 0:10:25 < m 131081 270684 --rw------- postgres postgres postgres postgres 5 4096 35869 35869 0:10:21 no-entry 0:10:21 < m 196618 334296 --rw------- postgres postgres postgres postgres 5 4096 35979 35979 0:10:22 0:10:22 0:10:22 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 < m 327692 382398 --rw------- postgres postgres postgres postgres 5 4096 36939 36939 0:10:28 no-entry 0:10:28 --- > m 1769472 175257 --rw------- postgres postgres postgres postgres 5 4096 41377 41377 0:11:02 no-entry 0:11:02 > m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:14:04 0:06:44 > m 786434 239239 --rw------- postgres postgres postgres postgres 6 4096 62242 62242 0:14:00 no-entry 0:14:00 > m 524292 175561 --rw------- postgres postgres postgres postgres 6 4096 63004 63004 0:14:05 0:14:05 0:14:05 > m 655365 2592525 --rw------- postgres postgres postgres postgres 8 4096 15365 15365 0:07:30 0:14:05 0:07:30 > m 983047 255773 --rw------- postgres postgres postgres postgres 7 4096 62580 62580 0:14:02 0:14:05 0:14:02 > m 1966088 2449282 --rw------- postgres postgres postgres postgres 7 4096 41335 41335 0:11:01 0:14:05 0:11:01 > m 196617 128227 --rw------- postgres postgres postgres postgres 6 4096 62045 62045 0:13:58 0:14:05 0:13:58 > m 262154 159060 --rw------- postgres postgres postgres postgres 8 4096 62202 62202 0:13:59 0:14:05 0:13:59 > m 524300 2466130 --rw------- postgres postgres postgres postgres 7 4096 62884 62884 0:14:04 0:14:05 0:14:04 > m 327693 207377 --rw------- postgres postgres postgres postgres 5 4096 41396 41396 0:11:02 no-entry 0:11:02 ### 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,13c3,12 < m 524288 1652 --rw------- postgres postgres postgres postgres 7 4096 14193 14193 0:07:21 0:10:28 0:07:21 < m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:10:24 0:06:44 < m 720898 223274 --rw------- postgres postgres postgres postgres 8 4096 35838 35838 0:10:21 0:10:28 0:10:21 < m 524291 365816 --rw------- postgres postgres postgres postgres 7 4096 36897 36897 0:10:28 0:10:28 0:10:28 < m 655365 2592525 --rw------- postgres postgres postgres postgres 7 4096 15365 15365 0:07:30 0:10:28 0:07:30 < m 524294 2576519 --rw------- postgres postgres postgres postgres 8 4096 36013 36013 0:10:22 0:10:28 0:10:22 < m 655367 255435 --rw------- postgres postgres postgres postgres 5 4096 36495 36495 0:10:25 no-entry 0:10:25 < m 131081 270684 --rw------- postgres postgres postgres postgres 5 4096 35869 35869 0:10:21 no-entry 0:10:21 < m 196618 334296 --rw------- postgres postgres postgres postgres 5 4096 35979 35979 0:10:22 0:10:22 0:10:22 < m 458763 79531 --rw------- postgres postgres postgres postgres 7 4096 36458 36458 0:10:25 0:10:28 0:10:25 < m 327692 382398 --rw------- postgres postgres postgres postgres 5 4096 36939 36939 0:10:28 no-entry 0:10:28 --- > m 1769472 175257 --rw------- postgres postgres postgres postgres 5 4096 41377 41377 0:11:02 no-entry 0:11:02 > m 65537 2369710 --rw------- postgres postgres postgres postgres 10 4096 7416 7416 0:06:44 0:14:04 0:06:44 > m 786434 239239 --rw------- postgres postgres postgres postgres 6 4096 62242 62242 0:14:00 no-entry 0:14:00 > m 655365 2592525 --rw------- postgres postgres postgres postgres 8 4096 15365 15365 0:07:30 0:14:05 0:07:30 > m 983047 255773 --rw------- postgres postgres postgres postgres 7 4096 62580 62580 0:14:02 0:14:05 0:14:02 > m 1966088 2449282 --rw------- postgres postgres postgres postgres 7 4096 41335 41335 0:11:01 0:14:05 0:11:01 > m 196617 128227 --rw------- postgres postgres postgres postgres 6 4096 62045 62045 0:13:58 0:14:05 0:13:58 > m 262154 159060 --rw------- postgres postgres postgres postgres 8 4096 62202 62202 0:13:59 0:14:05 0:13:59 > m 524300 2466130 --rw------- postgres postgres postgres postgres 7 4096 62884 62884 0:14:04 0:14:05 0:14:04 > m 327693 207377 --rw------- postgres postgres postgres postgres 5 4096 41396 41396 0:11:02 no-entry 0:11:02 [00:14:05.604](0.278s) 1..4 [00:14:05.605](0.001s) # Looks like you failed 1 test of 4.