# Running: ipcs -am # Checking port 62146 # Found port 62146 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=62146 host=/tmp/RYKc1rRhM0 Log file: /tmp/cirrus-ci-build/build/testrun/recovery/017_shm/log/017_shm_gnat.log [16:35:06.764](0.038s) # 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 [16:35:06.806](0.041s) # gnat's datadir inode = 302260 # Running: ipcs -am 8a9 > m 720902 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 0 no-entry no-entry 16:35:06 ### 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 36677 # Running: ipcs -am 5c5 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:35:06 16:32:15 7c7 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 589828 15962 --rw------- postgres postgres postgres postgres 8 4096 36225 36225 16:35:03 16:35:06 16:35:03 8a9 > m 720902 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 36677 16:35:06 16:35:06 16:35:06 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 720905 302261 --rw------- postgres postgres postgres postgres 6 4096 36677 36677 16:35:06 16:35:06 16:35:06 > m 262155 49097 --rw------- postgres postgres postgres postgres 8 4096 35885 35885 16:35:02 16:35:06 16:35:02 ### 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 36708 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 720902 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 36708 16:35:07 16:35:07 16:35:06 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 786441 302261 --rw------- postgres postgres postgres postgres 6 4096 36708 36708 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35:02 ### Killing node "gnat" using signal 9 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 720902 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 36708 16:35:07 16:35:07 16:35:06 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 786441 302261 --rw------- postgres postgres postgres postgres 0 4096 36708 36708 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35: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.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 36736 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 720902 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 36736 16:35:07 16:35:07 16:35:06 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 851977 302261 --rw------- postgres postgres postgres postgres 6 4096 36736 36736 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35:02 [16:35:07.328](0.523s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c11,12 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 851977 302261 --rw------- postgres postgres postgres postgres 6 4096 36736 36736 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35:02 ### Killing node "gnat" using signal 9 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c11,12 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 851977 302261 --rw------- postgres postgres postgres postgres 0 4096 36736 36736 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35: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.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 36769 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 7 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 9 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 786438 302260 --rw------- postgres postgres postgres postgres 6 4096 36769 36769 16:35:07 16:35:07 16:35:07 > m 393223 96434 --rw------- postgres postgres postgres postgres 7 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 851977 302261 --rw------- postgres postgres postgres postgres 0 4096 36736 36769 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35: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 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 7 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 9 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 393223 96434 --rw------- postgres postgres postgres postgres 7 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c11,12 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 851977 302261 --rw------- postgres postgres postgres postgres 0 4096 36736 36769 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35:02 [16:35:07.611](0.283s) # re-creating conflicting shmem ... # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 851974 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 0 no-entry no-entry 16:35:07 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 851977 302261 --rw------- postgres postgres postgres postgres 0 4096 36736 36769 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35: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.log -o --cluster-name=gnat start waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36809 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 851974 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 36809 16:35:07 16:35:07 16:35:07 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12,13 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 917513 302261 --rw------- postgres postgres postgres postgres 6 4096 36809 36809 16:35:07 16:35:07 16:35:07 > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35: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 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 851974 302260 --rw------- postgres postgres postgres postgres 0 4096 36646 36809 16:35:07 16:35:07 16:35:07 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35:02 [16:35:07.886](0.276s) # removing the conflicting shmem ... # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c11 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:07 16:35: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.log -o --cluster-name=gnat start waiting for server to start.... done server started # Postmaster PID for node "gnat" is 36855 # Running: ipcs -am 3c3 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 --- > m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:07 16:32:03 5,7c5,7 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 --- > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:35:07 16:32:15 > m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:07 16:34:59 > m 589828 15962 --rw------- postgres postgres postgres postgres 8 4096 36225 36225 16:35:03 16:35:07 16:35:03 9c9,10 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 --- > m 917510 302260 --rw------- postgres postgres postgres postgres 6 4096 36855 36855 16:35:07 16:35:07 16:35:07 > m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:07 16:35:05 11c12 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 --- > m 262155 49097 --rw------- postgres postgres postgres postgres 8 4096 35885 35885 16:35:02 16:35:07 16:35:02 [16:38:51.230](223.344s) # 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 [16:38:51.231](0.001s) not ok 1 - slow query started [16:38:51.231](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 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 < m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:34:55 16:31:15 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 < m 262149 144191 --rw------- postgres postgres postgres postgres 5 4096 36572 36572 16:35:06 no-entry 16:35:06 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 < m 262152 160419 --rw------- postgres postgres postgres postgres 5 4096 36271 36271 16:35:04 no-entry 16:35:04 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 < m 131084 477140 --rw------- postgres postgres postgres postgres 5 4096 35623 35623 16:35:00 no-entry 16:35:00 < m 327693 509555 --rw------- postgres postgres postgres postgres 5 4096 35838 35838 16:35:01 16:35:02 16:35:01 --- > m 1900544 430033 --rw------- postgres postgres postgres postgres 5 4096 41961 41961 16:35:49 no-entry 16:35:49 > m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:38:36 16:31:15 > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:38:51 16:32:15 > m 262147 636173 --rw------- postgres postgres postgres postgres 6 4096 62015 62015 16:38:46 no-entry 16:38:46 > m 786436 683882 --rw------- postgres postgres postgres postgres 7 4096 62506 62506 16:38:49 16:38:51 16:38:49 > m 327685 542034 --rw------- postgres postgres postgres postgres 7 4096 62375 62375 16:38:48 16:38:51 16:38:48 > m 917510 302260 --rw------- postgres postgres postgres postgres 1 4096 36855 36855 16:35:07 16:38:51 16:35:07 > m 2424841 350560 --rw------- postgres postgres postgres postgres 7 4096 41910 41910 16:35:48 16:38:51 16:35:48 > m 196618 367090 --rw------- postgres postgres postgres postgres 5 4096 42294 42294 16:35:52 no-entry 16:35:52 > m 196620 31857 --rw------- postgres postgres postgres postgres 6 4096 61646 61646 16:38:43 16:38:51 16:38:43 > m 393229 318702 --rw------- postgres postgres postgres postgres 8 4096 61973 61973 16:38:45 16:38:51 16:38:45 ### 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-21 16:38:51.276 UTC [62747][postmaster] FATAL: pre-existing shared memory block (key 302260, ID 917510) is still in use 2024-03-21 16:38:51.276 UTC [62747][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-21 16:38:51.276 UTC [62747][postmaster] LOG: database system is shut down # No postmaster PID for node "gnat" [16:38:51.376](0.145s) 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 [16:38:51.388](0.012s) ok 3 - live query blocks --single 2024-03-21 16:38:51.387 UTC [62760][standalone backend] FATAL: pre-existing shared memory block (key 302260, ID 917510) is still in use 2024-03-21 16:38:51.387 UTC [62760][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". [16:38:51.389](0.000s) ok 4 - single-user mode detected live backend via shared memory # Running: ipcs -am 3,13c3,13 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 < m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:34:55 16:31:15 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 < m 262149 144191 --rw------- postgres postgres postgres postgres 5 4096 36572 36572 16:35:06 no-entry 16:35:06 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 < m 262152 160419 --rw------- postgres postgres postgres postgres 5 4096 36271 36271 16:35:04 no-entry 16:35:04 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 < m 131084 477140 --rw------- postgres postgres postgres postgres 5 4096 35623 35623 16:35:00 no-entry 16:35:00 < m 327693 509555 --rw------- postgres postgres postgres postgres 5 4096 35838 35838 16:35:01 16:35:02 16:35:01 --- > m 1900544 430033 --rw------- postgres postgres postgres postgres 5 4096 41961 41961 16:35:49 no-entry 16:35:49 > m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:38:36 16:31:15 > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:38:51 16:32:15 > m 262147 636173 --rw------- postgres postgres postgres postgres 6 4096 62015 62015 16:38:46 no-entry 16:38:46 > m 786436 683882 --rw------- postgres postgres postgres postgres 7 4096 62506 62506 16:38:49 16:38:51 16:38:49 > m 327685 542034 --rw------- postgres postgres postgres postgres 7 4096 62375 62375 16:38:48 16:38:51 16:38:48 > m 917510 302260 --rw------- postgres postgres postgres postgres 1 4096 36855 62760 16:38:51 16:38:51 16:35:07 > m 2424841 350560 --rw------- postgres postgres postgres postgres 7 4096 41910 41910 16:35:48 16:38:51 16:35:48 > m 196618 367090 --rw------- postgres postgres postgres postgres 5 4096 42294 42294 16:35:52 no-entry 16:35:52 > m 196620 31857 --rw------- postgres postgres postgres postgres 6 4096 61646 61646 16:38:43 16:38:51 16:38:43 > m 393229 318702 --rw------- postgres postgres postgres postgres 8 4096 61973 61973 16:38:45 16:38:51 16:38:45 # Running: pg_ctl kill QUIT 36880 # Running: ipcs -am 3,13c3,13 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 < m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:34:55 16:31:15 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 < m 262149 144191 --rw------- postgres postgres postgres postgres 5 4096 36572 36572 16:35:06 no-entry 16:35:06 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 < m 262152 160419 --rw------- postgres postgres postgres postgres 5 4096 36271 36271 16:35:04 no-entry 16:35:04 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 < m 131084 477140 --rw------- postgres postgres postgres postgres 5 4096 35623 35623 16:35:00 no-entry 16:35:00 < m 327693 509555 --rw------- postgres postgres postgres postgres 5 4096 35838 35838 16:35:01 16:35:02 16:35:01 --- > m 1900544 430033 --rw------- postgres postgres postgres postgres 5 4096 41961 41961 16:35:49 no-entry 16:35:49 > m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:38:36 16:31:15 > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:38:51 16:32:15 > m 262147 636173 --rw------- postgres postgres postgres postgres 6 4096 62015 62015 16:38:46 no-entry 16:38:46 > m 786436 683882 --rw------- postgres postgres postgres postgres 7 4096 62506 62506 16:38:49 16:38:51 16:38:49 > m 327685 542034 --rw------- postgres postgres postgres postgres 7 4096 62375 62375 16:38:48 16:38:51 16:38:48 > m 917510 302260 --rw------- postgres postgres postgres postgres 0 4096 36855 62760 16:38:51 16:38:51 16:35:07 > m 2424841 350560 --rw------- postgres postgres postgres postgres 7 4096 41910 41910 16:35:48 16:38:51 16:35:48 > m 196618 367090 --rw------- postgres postgres postgres postgres 5 4096 42294 42294 16:35:52 no-entry 16:35:52 > m 196620 31857 --rw------- postgres postgres postgres postgres 6 4096 61646 61646 16:38:43 16:38:51 16:38:43 > m 393229 318702 --rw------- postgres postgres postgres postgres 9 4096 61973 61973 16:38:45 16:38:51 16:38:45 ### 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 62772 # Running: ipcs -am 3,13c3,13 < m 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 < m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:34:55 16:31:15 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 < m 262149 144191 --rw------- postgres postgres postgres postgres 5 4096 36572 36572 16:35:06 no-entry 16:35:06 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 < m 262152 160419 --rw------- postgres postgres postgres postgres 5 4096 36271 36271 16:35:04 no-entry 16:35:04 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 < m 131084 477140 --rw------- postgres postgres postgres postgres 5 4096 35623 35623 16:35:00 no-entry 16:35:00 < m 327693 509555 --rw------- postgres postgres postgres postgres 5 4096 35838 35838 16:35:01 16:35:02 16:35:01 --- > m 1900544 430033 --rw------- postgres postgres postgres postgres 5 4096 41961 41961 16:35:49 no-entry 16:35:49 > m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:38:36 16:31:15 > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:38:51 16:32:15 > m 262147 636173 --rw------- postgres postgres postgres postgres 6 4096 62015 62015 16:38:46 no-entry 16:38:46 > m 786436 683882 --rw------- postgres postgres postgres postgres 7 4096 62506 62506 16:38:49 16:38:51 16:38:49 > m 327685 542034 --rw------- postgres postgres postgres postgres 7 4096 62375 62375 16:38:48 16:38:51 16:38:48 > m 983046 302260 --rw------- postgres postgres postgres postgres 6 4096 62772 62772 16:38:51 16:38:51 16:38:51 > m 2424841 350560 --rw------- postgres postgres postgres postgres 7 4096 41910 41910 16:35:48 16:38:51 16:35:48 > m 196618 367090 --rw------- postgres postgres postgres postgres 5 4096 42294 42294 16:35:52 no-entry 16:35:52 > m 196620 31857 --rw------- postgres postgres postgres postgres 6 4096 61646 61646 16:38:43 16:38:51 16:38:43 > m 393229 318702 --rw------- postgres postgres postgres postgres 8 4096 61973 61973 16:38:45 16:38:51 16:38:45 ### 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 655360 206770 --rw------- postgres postgres postgres postgres 6 4096 14673 14673 16:32:03 16:35:06 16:32:03 < m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:34:55 16:31:15 < m 851970 80564 --rw------- postgres postgres postgres postgres 7 4096 16260 16260 16:32:15 16:35:06 16:32:15 < m 196611 414120 --rw------- postgres postgres postgres postgres 8 4096 35597 35597 16:34:59 16:35:06 16:34:59 < m 589828 15962 --rw------- postgres postgres postgres postgres 7 4096 36225 36225 16:35:03 16:35:06 16:35:03 < m 262149 144191 --rw------- postgres postgres postgres postgres 5 4096 36572 36572 16:35:06 no-entry 16:35:06 < m 393223 96434 --rw------- postgres postgres postgres postgres 6 4096 36537 36537 16:35:05 16:35:06 16:35:05 < m 262152 160419 --rw------- postgres postgres postgres postgres 5 4096 36271 36271 16:35:04 no-entry 16:35:04 < m 262155 49097 --rw------- postgres postgres postgres postgres 7 4096 35885 35885 16:35:02 16:35:06 16:35:02 < m 131084 477140 --rw------- postgres postgres postgres postgres 5 4096 35623 35623 16:35:00 no-entry 16:35:00 < m 327693 509555 --rw------- postgres postgres postgres postgres 5 4096 35838 35838 16:35:01 16:35:02 16:35:01 --- > m 1900544 430033 --rw------- postgres postgres postgres postgres 5 4096 41961 41961 16:35:49 no-entry 16:35:49 > m 65537 2576472 --rw------- postgres postgres postgres postgres 10 4096 7411 7411 16:31:15 16:38:36 16:31:15 > m 851970 80564 --rw------- postgres postgres postgres postgres 8 4096 16260 16260 16:32:15 16:38:51 16:32:15 > m 262147 636173 --rw------- postgres postgres postgres postgres 6 4096 62015 62015 16:38:46 no-entry 16:38:46 > m 786436 683882 --rw------- postgres postgres postgres postgres 7 4096 62506 62506 16:38:49 16:38:51 16:38:49 > m 327685 542034 --rw------- postgres postgres postgres postgres 7 4096 62375 62375 16:38:48 16:38:51 16:38:48 > m 2424841 350560 --rw------- postgres postgres postgres postgres 7 4096 41910 41910 16:35:48 16:38:51 16:35:48 > m 196618 367090 --rw------- postgres postgres postgres postgres 5 4096 42294 42294 16:35:52 no-entry 16:35:52 > m 196620 31857 --rw------- postgres postgres postgres postgres 6 4096 61646 61646 16:38:43 16:38:51 16:38:43 > m 393229 318702 --rw------- postgres postgres postgres postgres 8 4096 61973 61973 16:38:45 16:38:51 16:38:45 [16:38:51.681](0.293s) 1..4 [16:38:51.683](0.002s) # Looks like you failed 1 test of 4.