# Running: ipcs -am # Checking port 59990 # Found port 59990 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=59990 host=/tmp/0lG1V0lv3D Log file: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log [23:41:00.388](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 [23:41:00.428](0.040s) # gnat's datadir inode = 1813119 # Running: ipcs -am 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 > m 262152 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 0 no-entry no-entry 23:41:00 ### 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 36824 # Running: ipcs -am 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 > m 262152 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 36824 23:41:00 23:41:00 23:41:00 12a14 > m 262155 1813120 --rw------- postgres postgres postgres postgres 6 4096 36824 36824 23:41:00 23:41:00 23:41:00 ### 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 36853 # Running: ipcs -am 6,7c6,7 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 8 4096 35830 35830 23:40:53 23:41:00 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 7 4096 36712 36712 23:40:59 23:41:00 23:40:59 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 > m 262152 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 36853 23:41:00 23:41:00 23:41:00 12a14 > m 327691 1813120 --rw------- postgres postgres postgres postgres 6 4096 36853 36853 23:41:00 23:41:00 23:41:00 ### Killing node "gnat" using signal 9 # Running: ipcs -am 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 > m 262152 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 36853 23:41:00 23:41:00 23:41:00 12a14 > m 327691 1813120 --rw------- postgres postgres postgres postgres 0 4096 36853 36853 23:41:00 23:41:00 23:41:00 ### 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 36884 # Running: ipcs -am 3c3 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:41:00 23:38:05 8c8 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 131077 1877881 --rw------- postgres postgres postgres postgres 9 4096 35737 35737 23:40:53 23:41:00 23:40:53 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 > m 262152 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 36884 23:41:00 23:41:00 23:41:00 12a14 > m 393227 1813120 --rw------- postgres postgres postgres postgres 6 4096 36884 36884 23:41:00 23:41:00 23:41:00 14c16 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 7 4096 14362 14362 23:37:53 23:41:00 23:37:53 [23:41:00.928](0.499s) # removing the conflicting shmem ... # Running: ipcs -am 10c10 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 12a13 > m 393227 1813120 --rw------- postgres postgres postgres postgres 6 4096 36884 36884 23:41:00 23:41:00 23:41:00 ### Killing node "gnat" using signal 9 # Running: ipcs -am 10c10 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 12a13 > m 393227 1813120 --rw------- postgres postgres postgres postgres 0 4096 36884 36884 23:41:00 23:41:00 23:41:00 ### 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 36913 # Running: ipcs -am 3c3 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:00 23:40:57 > m 327688 1813119 --rw------- postgres postgres postgres postgres 6 4096 36913 36913 23:41:00 23:41:00 23:41:00 12a14 > m 393227 1813120 --rw------- postgres postgres postgres postgres 0 4096 36884 36913 23:41:00 23:41:00 23:41:00 14c16 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37: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 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:01 23:40:57 12a13 > m 393227 1813120 --rw------- postgres postgres postgres postgres 0 4096 36884 36913 23:41:00 23:41:00 23:41:00 14c15 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37:53 [23:41:01.217](0.289s) # re-creating conflicting shmem ... # Running: ipcs -am 3c3 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:01 23:40:57 > m 393224 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 0 no-entry no-entry 23:41:01 12a14 > m 393227 1813120 --rw------- postgres postgres postgres postgres 0 4096 36884 36913 23:41:00 23:41:00 23:41:00 14c16 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37: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 36953 # Running: ipcs -am 3c3 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:01 23:40:57 > m 393224 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 36953 23:41:01 23:41:01 23:41:01 12a14 > m 458763 1813120 --rw------- postgres postgres postgres postgres 6 4096 36953 36953 23:41:01 23:41:01 23:41:01 14c16 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37: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 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:01 23:40:57 > m 393224 1813119 --rw------- postgres postgres postgres postgres 0 4096 36791 36953 23:41:01 23:41:01 23:41:01 14c15 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37:53 [23:41:01.496](0.279s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 8 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:01 23:40:57 14c14 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37: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 37003 # Running: ipcs -am 3c3 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:41:01 23:38:05 6,8c6,8 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:01 23:40:53 > m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:01 23:40:59 > m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:01 23:40:53 10c10,11 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 --- > m 327687 2147993 --rw------- postgres postgres postgres postgres 7 4096 36429 36429 23:40:57 23:41:01 23:40:57 > m 458760 1813119 --rw------- postgres postgres postgres postgres 6 4096 37003 37003 23:41:01 23:41:01 23:41:01 14c15 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:01 23:37:53 [23:44:44.945](223.449s) # 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 [23:44:44.945](0.000s) not ok 1 - slow query started [23:44:44.945](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,8c3,6 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 < m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:40:51 23:37:11 < m 1048578 2322296 --rw------- postgres postgres postgres postgres 5 4096 36764 36764 23:41:00 no-entry 23:41:00 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:44:44 23:38:05 > m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:44:32 23:37:11 > m 1245186 1782778 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 23:44:43 23:44:44 23:44:43 > m 720899 2307014 --rw------- postgres postgres postgres postgres 6 4096 61905 61905 23:44:36 no-entry 23:44:36 10,14c8,14 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 < m 196617 2052903 --rw------- postgres postgres postgres postgres 5 4096 35774 35774 23:40:53 no-entry 23:40:53 < m 458762 2226563 --rw------- postgres postgres postgres postgres 5 4096 35787 35787 23:40:53 23:40:53 23:40:53 < m 327692 2274883 --rw------- postgres postgres postgres postgres 5 4096 36463 36463 23:40:58 no-entry 23:40:58 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 458760 1813119 --rw------- postgres postgres postgres postgres 1 4096 37003 37003 23:41:01 23:44:44 23:41:01 > m 262153 2084052 --rw------- postgres postgres postgres postgres 6 4096 61751 61751 23:44:35 23:44:44 23:44:35 > m 524298 2100326 --rw------- postgres postgres postgres postgres 9 4096 61864 61864 23:44:36 23:44:44 23:44:36 > m 1966091 1813226 --rw------- postgres postgres postgres postgres 7 4096 41617 41617 23:41:38 23:44:44 23:41:38 > m 655372 1940293 --rw------- postgres postgres postgres postgres 7 4096 62451 62451 23:44:40 23:44:44 23:44:40 > m 262157 2036422 --rw------- postgres postgres postgres postgres 5 4096 41659 41659 23:41:38 no-entry 23:41:38 > m 458766 1718658 --rw------- postgres postgres postgres postgres 5 4096 41976 41976 23:41:41 no-entry 23:41:41 ### 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-26 23:44:44.991 UTC [63005][postmaster] FATAL: pre-existing shared memory block (key 1813119, ID 458760) is still in use 2024-03-26 23:44:44.991 UTC [63005][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-26 23:44:44.991 UTC [63005][postmaster] LOG: database system is shut down # No postmaster PID for node "gnat" [23:44:45.091](0.146s) 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 [23:44:45.101](0.010s) ok 3 - live query blocks --single 2024-03-26 23:44:45.100 UTC [63018][standalone backend] FATAL: pre-existing shared memory block (key 1813119, ID 458760) is still in use 2024-03-26 23:44:45.100 UTC [63018][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". [23:44:45.101](0.000s) ok 4 - single-user mode detected live backend via shared memory # Running: ipcs -am 3,8c3,6 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 < m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:40:51 23:37:11 < m 1048578 2322296 --rw------- postgres postgres postgres postgres 5 4096 36764 36764 23:41:00 no-entry 23:41:00 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:44:45 23:38:05 > m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:44:32 23:37:11 > m 1245186 1782778 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 23:44:43 23:44:45 23:44:43 > m 720899 2307014 --rw------- postgres postgres postgres postgres 6 4096 61905 61905 23:44:36 no-entry 23:44:36 10,14c8,14 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 < m 196617 2052903 --rw------- postgres postgres postgres postgres 5 4096 35774 35774 23:40:53 no-entry 23:40:53 < m 458762 2226563 --rw------- postgres postgres postgres postgres 5 4096 35787 35787 23:40:53 23:40:53 23:40:53 < m 327692 2274883 --rw------- postgres postgres postgres postgres 5 4096 36463 36463 23:40:58 no-entry 23:40:58 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 458760 1813119 --rw------- postgres postgres postgres postgres 1 4096 37003 63018 23:44:45 23:44:45 23:41:01 > m 262153 2084052 --rw------- postgres postgres postgres postgres 6 4096 61751 61751 23:44:35 23:44:45 23:44:35 > m 524298 2100326 --rw------- postgres postgres postgres postgres 8 4096 61864 61864 23:44:36 23:44:45 23:44:36 > m 1966091 1813226 --rw------- postgres postgres postgres postgres 7 4096 41617 41617 23:41:38 23:44:45 23:41:38 > m 655372 1940293 --rw------- postgres postgres postgres postgres 7 4096 62451 62451 23:44:40 23:44:45 23:44:40 > m 262157 2036422 --rw------- postgres postgres postgres postgres 5 4096 41659 41659 23:41:38 no-entry 23:41:38 > m 458766 1718658 --rw------- postgres postgres postgres postgres 5 4096 41976 41976 23:41:41 no-entry 23:41:41 # Running: pg_ctl kill QUIT 37028 # Running: ipcs -am 3,8c3,6 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 < m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:40:51 23:37:11 < m 1048578 2322296 --rw------- postgres postgres postgres postgres 5 4096 36764 36764 23:41:00 no-entry 23:41:00 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:44:45 23:38:05 > m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:44:32 23:37:11 > m 1245186 1782778 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 23:44:43 23:44:45 23:44:43 > m 720899 2307014 --rw------- postgres postgres postgres postgres 6 4096 61905 61905 23:44:36 no-entry 23:44:36 10,14c8,14 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 < m 196617 2052903 --rw------- postgres postgres postgres postgres 5 4096 35774 35774 23:40:53 no-entry 23:40:53 < m 458762 2226563 --rw------- postgres postgres postgres postgres 5 4096 35787 35787 23:40:53 23:40:53 23:40:53 < m 327692 2274883 --rw------- postgres postgres postgres postgres 5 4096 36463 36463 23:40:58 no-entry 23:40:58 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 458760 1813119 --rw------- postgres postgres postgres postgres 0 4096 37003 63018 23:44:45 23:44:45 23:41:01 > m 262153 2084052 --rw------- postgres postgres postgres postgres 6 4096 61751 61751 23:44:35 23:44:45 23:44:35 > m 524298 2100326 --rw------- postgres postgres postgres postgres 8 4096 61864 61864 23:44:36 23:44:45 23:44:36 > m 1966091 1813226 --rw------- postgres postgres postgres postgres 7 4096 41617 41617 23:41:38 23:44:45 23:41:38 > m 655372 1940293 --rw------- postgres postgres postgres postgres 7 4096 62451 62451 23:44:40 23:44:45 23:44:40 > m 262157 2036422 --rw------- postgres postgres postgres postgres 5 4096 41659 41659 23:41:38 no-entry 23:41:38 > m 458766 1718658 --rw------- postgres postgres postgres postgres 5 4096 41976 41976 23:41:41 no-entry 23:41:41 ### 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 63034 # Running: ipcs -am 3,8c3,6 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 < m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:40:51 23:37:11 < m 1048578 2322296 --rw------- postgres postgres postgres postgres 5 4096 36764 36764 23:41:00 no-entry 23:41:00 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 9 4096 15817 15817 23:38:05 23:44:45 23:38:05 > m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:44:32 23:37:11 > m 1245186 1782778 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 23:44:43 23:44:45 23:44:43 > m 720899 2307014 --rw------- postgres postgres postgres postgres 6 4096 61905 61905 23:44:36 no-entry 23:44:36 10,14c8,14 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 < m 196617 2052903 --rw------- postgres postgres postgres postgres 5 4096 35774 35774 23:40:53 no-entry 23:40:53 < m 458762 2226563 --rw------- postgres postgres postgres postgres 5 4096 35787 35787 23:40:53 23:40:53 23:40:53 < m 327692 2274883 --rw------- postgres postgres postgres postgres 5 4096 36463 36463 23:40:58 no-entry 23:40:58 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 524296 1813119 --rw------- postgres postgres postgres postgres 6 4096 63034 63034 23:44:45 23:44:45 23:44:45 > m 262153 2084052 --rw------- postgres postgres postgres postgres 6 4096 61751 61751 23:44:35 23:44:45 23:44:35 > m 524298 2100326 --rw------- postgres postgres postgres postgres 8 4096 61864 61864 23:44:36 23:44:45 23:44:36 > m 1966091 1813226 --rw------- postgres postgres postgres postgres 8 4096 41617 41617 23:41:38 23:44:45 23:41:38 > m 655372 1940293 --rw------- postgres postgres postgres postgres 7 4096 62451 62451 23:44:40 23:44:45 23:44:40 > m 262157 2036422 --rw------- postgres postgres postgres postgres 5 4096 41659 41659 23:41:38 no-entry 23:41:38 > m 458766 1718658 --rw------- postgres postgres postgres postgres 5 4096 41976 41976 23:41:41 no-entry 23:41:41 ### 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,8c3,6 < m 851968 1701741 --rw------- postgres postgres postgres postgres 7 4096 15817 15817 23:38:05 23:41:00 23:38:05 < m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:40:51 23:37:11 < m 1048578 2322296 --rw------- postgres postgres postgres postgres 5 4096 36764 36764 23:41:00 no-entry 23:41:00 < m 655363 2131148 --rw------- postgres postgres postgres postgres 7 4096 35830 35830 23:40:53 23:41:00 23:40:53 < m 262148 1734845 --rw------- postgres postgres postgres postgres 6 4096 36712 36712 23:40:59 23:41:00 23:40:59 < m 131077 1877881 --rw------- postgres postgres postgres postgres 8 4096 35737 35737 23:40:53 23:41:00 23:40:53 --- > m 851968 1701741 --rw------- postgres postgres postgres postgres 8 4096 15817 15817 23:38:05 23:44:45 23:38:05 > m 65537 1606955 --rw------- postgres postgres postgres postgres 10 4096 7395 7395 23:37:11 23:44:32 23:37:11 > m 1245186 1782778 --rw------- postgres postgres postgres postgres 7 4096 62836 62836 23:44:43 23:44:45 23:44:43 > m 720899 2307014 --rw------- postgres postgres postgres postgres 6 4096 61905 61905 23:44:36 no-entry 23:44:36 10,14c8,13 < m 327687 2147993 --rw------- postgres postgres postgres postgres 8 4096 36429 36429 23:40:57 23:41:00 23:40:57 < m 196617 2052903 --rw------- postgres postgres postgres postgres 5 4096 35774 35774 23:40:53 no-entry 23:40:53 < m 458762 2226563 --rw------- postgres postgres postgres postgres 5 4096 35787 35787 23:40:53 23:40:53 23:40:53 < m 327692 2274883 --rw------- postgres postgres postgres postgres 5 4096 36463 36463 23:40:58 no-entry 23:40:58 < m 196623 1924395 --rw------- postgres postgres postgres postgres 6 4096 14362 14362 23:37:53 23:41:00 23:37:53 --- > m 262153 2084052 --rw------- postgres postgres postgres postgres 6 4096 61751 61751 23:44:35 23:44:45 23:44:35 > m 524298 2100326 --rw------- postgres postgres postgres postgres 8 4096 61864 61864 23:44:36 23:44:45 23:44:36 > m 1966091 1813226 --rw------- postgres postgres postgres postgres 7 4096 41617 41617 23:41:38 23:44:45 23:41:38 > m 655372 1940293 --rw------- postgres postgres postgres postgres 7 4096 62451 62451 23:44:40 23:44:45 23:44:40 > m 262157 2036422 --rw------- postgres postgres postgres postgres 5 4096 41659 41659 23:41:38 no-entry 23:41:38 > m 458766 1718658 --rw------- postgres postgres postgres postgres 5 4096 41976 41976 23:41:41 no-entry 23:41:41 [23:44:45.388](0.286s) 1..4 [23:44:45.389](0.001s) # Looks like you failed 1 test of 4.