0s autopkgtest [15:11:05]: starting date and time: 2024-07-23 15:11:05+0000 0s autopkgtest [15:11:05]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [15:11:05]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.s_1qkmne/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:lvm2 --apt-upgrade multipath-tools --timeout-short=300 --timeout-copy=20000 --timeout-test=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=lvm2/2.03.22-1ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-big --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-ppc64el-12.secgroup --name adt-oracular-ppc64el-multipath-tools-20240723-151105-juju-7f2275-prod-proposed-migration-environment-3-fbcd5bf6-8c59-48da-9a22-44f00071356b --image adt/ubuntu-oracular-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 78s autopkgtest [15:12:23]: testbed dpkg architecture: ppc64el 78s autopkgtest [15:12:23]: testbed apt version: 2.9.6 78s autopkgtest [15:12:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 79s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 79s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [66.5 kB] 79s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [5704 B] 79s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [392 kB] 80s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 80s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [116 kB] 80s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 80s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 80s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 80s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [531 kB] 80s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 80s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [2432 B] 80s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 83s Fetched 1261 kB in 1s (884 kB/s) 83s Reading package lists... 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Calculating upgrade... 86s The following packages will be upgraded: 86s dmsetup libdevmapper1.02.1 86s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 273 kB of archives. 86s After this operation, 1024 B of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB] 87s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB] 87s Fetched 273 kB in 1s (410 kB/s) 87s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 72787 files and directories currently installed.) 87s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 87s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 87s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 87s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 87s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ... 87s Setting up dmsetup (2:1.02.196-1ubuntu1) ... 87s Processing triggers for libc-bin (2.39-0ubuntu9) ... 87s Processing triggers for man-db (2.12.1-2) ... 88s Processing triggers for initramfs-tools (0.142ubuntu30) ... 88s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 88s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 96s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 96s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 96s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 96s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 97s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 97s Reading package lists... 97s Reading package lists... 98s Building dependency tree... 98s Reading state information... 98s Calculating upgrade... 98s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s Reading package lists... 98s Building dependency tree... 98s Reading state information... 98s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s autopkgtest [15:12:43]: rebooting testbed after setup commands that affected boot 102s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 135s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 142s autopkgtest [15:13:27]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 145s autopkgtest [15:13:30]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools 149s Get:1 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (dsc) [2770 B] 149s Get:2 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (tar) [554 kB] 149s Get:3 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (diff) [42.7 kB] 149s gpgv: Signature made Sun Apr 7 00:31:08 2024 UTC 149s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 149s gpgv: issuer "steve.langasek@ubuntu.com" 149s gpgv: Can't check signature: No public key 149s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.4-5ubuntu8.dsc: no acceptable signature found 149s autopkgtest [15:13:34]: testing package multipath-tools version 0.9.4-5ubuntu8 150s autopkgtest [15:13:35]: build not needed 152s autopkgtest [15:13:37]: test kpartx-file-loopback: preparing testbed 153s Reading package lists... 153s Building dependency tree... 153s Reading state information... 153s Starting pkgProblemResolver with broken count: 0 153s Starting 2 pkgProblemResolver with broken count: 0 153s Done 154s The following additional packages will be installed: 154s liburing2 qemu-utils 154s Recommended packages: 154s qemu-block-extra 154s The following NEW packages will be installed: 154s autopkgtest-satdep liburing2 qemu-utils 154s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 154s Need to get 2330 kB/2331 kB of archives. 154s After this operation, 15.4 MB of additional disk space will be used. 154s Get:1 /tmp/autopkgtest.G5BFpj/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 154s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el liburing2 ppc64el 2.6-1 [26.9 kB] 154s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el qemu-utils ppc64el 1:8.2.2+ds-0ubuntu1 [2303 kB] 156s Fetched 2330 kB in 2s (1192 kB/s) 156s Selecting previously unselected package liburing2:ppc64el. 156s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 72787 files and directories currently installed.) 156s Preparing to unpack .../liburing2_2.6-1_ppc64el.deb ... 156s Unpacking liburing2:ppc64el (2.6-1) ... 156s Selecting previously unselected package qemu-utils. 156s Preparing to unpack .../qemu-utils_1%3a8.2.2+ds-0ubuntu1_ppc64el.deb ... 156s Unpacking qemu-utils (1:8.2.2+ds-0ubuntu1) ... 156s Selecting previously unselected package autopkgtest-satdep. 156s Preparing to unpack .../1-autopkgtest-satdep.deb ... 156s Unpacking autopkgtest-satdep (0) ... 156s Setting up liburing2:ppc64el (2.6-1) ... 156s Setting up qemu-utils (1:8.2.2+ds-0ubuntu1) ... 156s Setting up autopkgtest-satdep (0) ... 156s Processing triggers for man-db (2.12.1-2) ... 157s Processing triggers for libc-bin (2.39-0ubuntu9) ... 159s (Reading database ... 72807 files and directories currently installed.) 159s Removing autopkgtest-satdep (0) ... 160s autopkgtest [15:13:45]: test kpartx-file-loopback: [----------------------- 160s Formatting 'foo.img', fmt=raw size=20971520 161s Creating new GPT entries in memory. 161s Warning: The kernel is still using the old partition table. 161s The new table will be used at the next reboot or after you 161s run partprobe(8) or kpartx(8) 161s The operation has completed successfully. 161s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 161s standard_filename: OK 161s del devmap : loop0p1 161s No devices found 161s standard_filename_cleanup: OK 161s Formatting 'fou du FaFa.img', fmt=raw size=20971520 162s Creating new GPT entries in memory. 162s Warning: The kernel is still using the old partition table. 162s The new table will be used at the next reboot or after you 162s run partprobe(8) or kpartx(8) 162s The operation has completed successfully. 162s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 162s filename_with_spaces: OK 162s del devmap : loop0p1 162s No devices found 162s filename_with_spaces_cleanup: OK 163s autopkgtest [15:13:48]: test kpartx-file-loopback: -----------------------] 163s kpartx-file-loopback PASS 163s autopkgtest [15:13:48]: test kpartx-file-loopback: - - - - - - - - - - results - - - - - - - - - - 164s autopkgtest [15:13:49]: test tgtbasedmpaths: preparing testbed 270s autopkgtest [15:15:35]: testbed dpkg architecture: ppc64el 270s autopkgtest [15:15:35]: testbed apt version: 2.9.6 270s autopkgtest [15:15:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 271s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 272s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [66.5 kB] 272s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 272s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [5704 B] 272s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [392 kB] 272s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [116 kB] 272s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 272s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 272s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 272s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [531 kB] 272s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 272s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [2432 B] 272s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 275s Fetched 1261 kB in 1s (1387 kB/s) 275s Reading package lists... 277s Reading package lists... 277s Building dependency tree... 277s Reading state information... 278s Calculating upgrade... 278s The following packages will be upgraded: 278s dmsetup libdevmapper1.02.1 278s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 278s Need to get 273 kB of archives. 278s After this operation, 1024 B of additional disk space will be used. 278s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB] 278s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB] 278s Fetched 273 kB in 0s (581 kB/s) 279s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 72787 files and directories currently installed.) 279s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 279s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 279s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 279s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 279s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ... 279s Setting up dmsetup (2:1.02.196-1ubuntu1) ... 279s Processing triggers for libc-bin (2.39-0ubuntu9) ... 279s Processing triggers for man-db (2.12.1-2) ... 279s Processing triggers for initramfs-tools (0.142ubuntu30) ... 279s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 279s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 287s Reading package lists... 287s Building dependency tree... 287s Reading state information... 287s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 288s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 288s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 288s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 288s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 289s Reading package lists... 289s Reading package lists... 289s Building dependency tree... 289s Reading state information... 289s Calculating upgrade... 289s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 289s Reading package lists... 289s Building dependency tree... 289s Reading state information... 290s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 290s autopkgtest [15:15:55]: rebooting testbed after setup commands that affected boot 324s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 337s Reading package lists... 337s Building dependency tree... 337s Reading state information... 338s Starting pkgProblemResolver with broken count: 0 338s Starting 2 pkgProblemResolver with broken count: 0 338s Done 338s The following additional packages will be installed: 338s fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl 338s libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0 338s libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1 338s librdmacm1t64 lsscsi open-iscsi tgt 338s Suggested packages: 338s fio-examples gnuplot tgt-glusterfs tgt-rbd 338s Recommended packages: 338s finalrd 338s The following NEW packages will be installed: 338s autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0 338s libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 338s libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1 338s libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt 338s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 338s Need to get 10.7 MB/10.7 MB of archives. 338s After this operation, 49.0 MB of additional disk space will be used. 338s Get:1 /tmp/autopkgtest.G5BFpj/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [732 B] 338s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libopeniscsiusr ppc64el 2.1.10-1ubuntu1 [54.9 kB] 338s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libisns0t64 ppc64el 0.101-1 [117 kB] 338s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el open-iscsi ppc64el 2.1.10-1ubuntu1 [385 kB] 339s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el librdmacm1t64 ppc64el 52.0-2 [80.5 kB] 339s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB] 339s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el tgt ppc64el 1:1.0.85-1.2ubuntu1 [254 kB] 339s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfxdr0 ppc64el 11.1-4ubuntu1 [21.5 kB] 339s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libglusterfs0 ppc64el 11.1-4ubuntu1 [311 kB] 339s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfrpc0 ppc64el 11.1-4ubuntu1 [46.5 kB] 339s Get:11 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfapi0 ppc64el 11.1-4ubuntu1 [99.2 kB] 339s Get:12 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libnbd0 ppc64el 1.20.2-1 [97.4 kB] 339s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdaxctl1 ppc64el 77-2ubuntu2 [23.7 kB] 339s Get:14 http://ftpmaster.internal/ubuntu oracular/main ppc64el libndctl6 ppc64el 77-2ubuntu2 [74.9 kB] 339s Get:15 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB] 339s Get:16 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-3ubuntu1 [259 kB] 339s Get:17 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-3ubuntu1 [279 kB] 339s Get:18 http://ftpmaster.internal/ubuntu oracular/main ppc64el librados2 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [4026 kB] 341s Get:19 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB] 341s Get:20 http://ftpmaster.internal/ubuntu oracular/main ppc64el librbd1 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [3581 kB] 341s Get:21 http://ftpmaster.internal/ubuntu oracular/universe ppc64el fio ppc64el 3.37-1 [716 kB] 341s Get:22 http://ftpmaster.internal/ubuntu oracular/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB] 342s Preconfiguring packages ... 342s Fetched 10.7 MB in 3s (3089 kB/s) 342s Selecting previously unselected package libopeniscsiusr. 342s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 72787 files and directories currently installed.) 342s Preparing to unpack .../00-libopeniscsiusr_2.1.10-1ubuntu1_ppc64el.deb ... 342s Unpacking libopeniscsiusr (2.1.10-1ubuntu1) ... 342s Selecting previously unselected package libisns0t64:ppc64el. 342s Preparing to unpack .../01-libisns0t64_0.101-1_ppc64el.deb ... 342s Unpacking libisns0t64:ppc64el (0.101-1) ... 342s Selecting previously unselected package open-iscsi. 342s Preparing to unpack .../02-open-iscsi_2.1.10-1ubuntu1_ppc64el.deb ... 342s Unpacking open-iscsi (2.1.10-1ubuntu1) ... 342s Selecting previously unselected package librdmacm1t64:ppc64el. 342s Preparing to unpack .../03-librdmacm1t64_52.0-2_ppc64el.deb ... 342s Unpacking librdmacm1t64:ppc64el (52.0-2) ... 342s Selecting previously unselected package libconfig-general-perl. 342s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ... 342s Unpacking libconfig-general-perl (2.65-2) ... 342s Selecting previously unselected package tgt. 342s Preparing to unpack .../05-tgt_1%3a1.0.85-1.2ubuntu1_ppc64el.deb ... 342s Unpacking tgt (1:1.0.85-1.2ubuntu1) ... 342s Selecting previously unselected package libgfxdr0:ppc64el. 342s Preparing to unpack .../06-libgfxdr0_11.1-4ubuntu1_ppc64el.deb ... 342s Unpacking libgfxdr0:ppc64el (11.1-4ubuntu1) ... 342s Selecting previously unselected package libglusterfs0:ppc64el. 342s Preparing to unpack .../07-libglusterfs0_11.1-4ubuntu1_ppc64el.deb ... 342s Unpacking libglusterfs0:ppc64el (11.1-4ubuntu1) ... 342s Selecting previously unselected package libgfrpc0:ppc64el. 342s Preparing to unpack .../08-libgfrpc0_11.1-4ubuntu1_ppc64el.deb ... 342s Unpacking libgfrpc0:ppc64el (11.1-4ubuntu1) ... 342s Selecting previously unselected package libgfapi0:ppc64el. 342s Preparing to unpack .../09-libgfapi0_11.1-4ubuntu1_ppc64el.deb ... 342s Unpacking libgfapi0:ppc64el (11.1-4ubuntu1) ... 342s Selecting previously unselected package libnbd0. 342s Preparing to unpack .../10-libnbd0_1.20.2-1_ppc64el.deb ... 342s Unpacking libnbd0 (1.20.2-1) ... 342s Selecting previously unselected package libdaxctl1:ppc64el. 342s Preparing to unpack .../11-libdaxctl1_77-2ubuntu2_ppc64el.deb ... 342s Unpacking libdaxctl1:ppc64el (77-2ubuntu2) ... 342s Selecting previously unselected package libndctl6:ppc64el. 342s Preparing to unpack .../12-libndctl6_77-2ubuntu2_ppc64el.deb ... 342s Unpacking libndctl6:ppc64el (77-2ubuntu2) ... 342s Selecting previously unselected package libpmem1:ppc64el. 342s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ... 342s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 342s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el. 342s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-3ubuntu1_ppc64el.deb ... 342s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ... 342s Selecting previously unselected package libboost-thread1.83.0:ppc64el. 342s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-3ubuntu1_ppc64el.deb ... 342s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ... 342s Selecting previously unselected package librados2. 342s Preparing to unpack .../16-librados2_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ... 342s Unpacking librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 342s Selecting previously unselected package libpmemobj1:ppc64el. 342s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ... 342s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 342s Selecting previously unselected package librbd1. 342s Preparing to unpack .../18-librbd1_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ... 342s Unpacking librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 342s Selecting previously unselected package fio. 342s Preparing to unpack .../19-fio_3.37-1_ppc64el.deb ... 342s Unpacking fio (3.37-1) ... 343s Selecting previously unselected package lsscsi. 343s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ... 343s Unpacking lsscsi (0.32-1build1) ... 343s Selecting previously unselected package autopkgtest-satdep. 343s Preparing to unpack .../21-2-autopkgtest-satdep.deb ... 343s Unpacking autopkgtest-satdep (0) ... 343s Setting up libconfig-general-perl (2.65-2) ... 343s Setting up libisns0t64:ppc64el (0.101-1) ... 343s Setting up libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ... 343s Setting up libnbd0 (1.20.2-1) ... 343s Setting up libopeniscsiusr (2.1.10-1ubuntu1) ... 343s Setting up libglusterfs0:ppc64el (11.1-4ubuntu1) ... 343s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ... 343s Setting up lsscsi (0.32-1build1) ... 343s Setting up libdaxctl1:ppc64el (77-2ubuntu2) ... 343s Setting up libndctl6:ppc64el (77-2ubuntu2) ... 343s Setting up librdmacm1t64:ppc64el (52.0-2) ... 343s Setting up tgt (1:1.0.85-1.2ubuntu1) ... 343s Created symlink '/etc/systemd/system/multi-user.target.wants/tgt.service' → '/usr/lib/systemd/system/tgt.service'. 343s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 343s Setting up libgfxdr0:ppc64el (11.1-4ubuntu1) ... 343s Setting up librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 344s Setting up open-iscsi (2.1.10-1ubuntu1) ... 344s Created symlink '/etc/systemd/system/sockets.target.wants/iscsid.socket' → '/usr/lib/systemd/system/iscsid.socket'. 345s Created symlink '/etc/systemd/system/iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 345s Created symlink '/etc/systemd/system/sysinit.target.wants/open-iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 345s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 345s Setting up librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 345s Setting up libgfrpc0:ppc64el (11.1-4ubuntu1) ... 345s Setting up libgfapi0:ppc64el (11.1-4ubuntu1) ... 345s Setting up fio (3.37-1) ... 345s Setting up autopkgtest-satdep (0) ... 345s Processing triggers for man-db (2.12.1-2) ... 347s Processing triggers for initramfs-tools (0.142ubuntu30) ... 347s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 347s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 354s Processing triggers for libc-bin (2.39-0ubuntu9) ... 356s (Reading database ... 73028 files and directories currently installed.) 356s Removing autopkgtest-satdep (0) ... 358s autopkgtest [15:17:03]: test tgtbasedmpaths: [----------------------- 358s + targetname=iqn.2016-11.foo.com:target.iscsi 358s + pwd 358s + cwd=/tmp/autopkgtest.G5BFpj/build.qJV/src 358s + testdir=/mnt/tgtmpathtest 358s + localhost=127.0.0.1 358s + portal=127.0.0.1:3260 358s + maxpaths=4 358s + backfn=backingfile 358s + expectwwid=60000000000000000e00000000010001 358s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001 358s + bglog=/tmp/autopkgtest.G5BFpj/tgtbasedmpaths-artifacts/test-background.log 358s + fioprep=/tmp/autopkgtest.G5BFpj/tgtbasedmpaths-artifacts/path-change-prep.fio 358s + fiovrfy=/tmp/autopkgtest.G5BFpj/tgtbasedmpaths-artifacts/path-change-check.fio 358s + service tgt restart 358s + truncate --size 100M backingfile 358s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi 358s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL 358s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.G5BFpj/build.qJV/src/backingfile 358s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1 358s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi 358s login #1 358s + echo login #1 358s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login 358s + seq 2 4 358s + echo extra login #2 358s + iscsiadm --mode session -r 1 --op new 358s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 358s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 358s extra login #2 358s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 358s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 358s extra login #3 358s + echo extra login #3 358s + iscsiadm --mode session -r 1 --op new 358s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 358s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 358s + echo extra login #4 358s + iscsiadm --mode session -r 1 --op new 358s extra login #4 358s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 358s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 358s + udevadm settle 359s + sleep 5 364s Status after initial setup 364s + echo Status after initial setup 364s + tgtadm --lld iscsi --mode target --op show 364s Target 1: iqn.2016-11.foo.com:target.iscsi 364s System information: 364s Driver: iscsi 364s State: ready 364s I_T nexus information: 364s I_T nexus: 1 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 alias: autopkgtest 364s Connection: 0 364s IP Address: 127.0.0.1 364s I_T nexus: 2 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 alias: autopkgtest 364s Connection: 0 364s IP Address: 127.0.0.1 364s I_T nexus: 3 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 alias: autopkgtest 364s Connection: 0 364s IP Address: 127.0.0.1 364s I_T nexus: 4 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 alias: autopkgtest 364s Connection: 0 364s IP Address: 127.0.0.1 364s LUN information: 364s LUN: 0 364s Type: controller 364s SCSI ID: IET 00010000 364s SCSI SN: beaf10 364s Size: 0 MB, Block size: 1 364s Online: Yes 364s Removable media: No 364s Prevent removal: No 364s Readonly: No 364s SWP: No 364s Thin-provisioning: No 364s Backing store type: null 364s Backing store path: None 364s Backing store flags: 364s LUN: 1 364s Type: disk 364s SCSI ID: IET 00010001 364s SCSI SN: beaf11 364s Size: 105 MB, Block size: 512 364s Online: Yes 364s Removable media: No 364s Prevent removal: No 364s Readonly: No 364s SWP: No 364s Thin-provisioning: No 364s Backing store type: rdwr 364s Backing store path: /tmp/autopkgtest.G5BFpj/build.qJV/src/backingfile 364s Backing store flags: 364s Account information: 364s ACL information: 364s ALL 364s Session: 4 364s Connection: 0 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 364s IP Address: 127.0.0.1 364s Session: 3 364s Connection: 0 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 364s IP Address: 127.0.0.1 364s Session: 2 364s Connection: 0 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 364s IP Address: 127.0.0.1 364s Session: 1 364s Connection: 0 364s Initiator: iqn.2004-10.com.ubuntu:01:f94672e05675 364s IP Address: 127.0.0.1 364s + tgtadm --lld iscsi --op show --mode conn --tid 1 364s + iscsiadm --mode session -P 1 364s Target: iqn.2016-11.foo.com:target.iscsi (non-flash) 364s Current Portal: 127.0.0.1:3260,1 364s Persistent Portal: 127.0.0.1:3260,1 364s ********** 364s Interface: 364s ********** 364s Iface Name: default 364s Iface Transport: tcp 364s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:f94672e05675 364s Iface IPaddress: 127.0.0.1 364s Iface HWaddress: default 364s Iface Netdev: default 364s SID: 1 364s iSCSI Connection State: LOGGED IN 364s iSCSI Session State: LOGGED_IN 364s Internal iscsid Session State: NO CHANGE 364s 364s ********** 364s Interface: 364s ********** 364s Iface Name: default 364s Iface Transport: tcp 364s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:f94672e05675 364s Iface IPaddress: 127.0.0.1 364s Iface HWaddress: default 364s Iface Netdev: default 364s SID: 2 364s iSCSI Connection State: LOGGED IN 364s iSCSI Session State: LOGGED_IN 364s Internal iscsid Session State: NO CHANGE 364s 364s ********** 364s Interface: 364s ********** 364s Iface Name: default 364s Iface Transport: tcp 364s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:f94672e05675 364s Iface IPaddress: 127.0.0.1 364s Iface HWaddress: default 364s Iface Netdev: default 364s SID: 3 364s iSCSI Connection State: LOGGED IN 364s iSCSI Session State: LOGGED_IN 364s Internal iscsid Session State: NO CHANGE 364s 364s ********** 364s Interface: 364s ********** 364s Iface Name: default 364s Iface Transport: tcp 364s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:f94672e05675 364s Iface IPaddress: 127.0.0.1 364s Iface HWaddress: default 364s Iface Netdev: default 364s SID: 4 364s iSCSI Connection State: LOGGED IN 364s iSCSI Session State: LOGGED_IN 364s Internal iscsid Session State: NO CHANGE 364s + lsscsi -liv 364s list_ndevices: scandir: /sys/class/nvme/: No such file or directory 364s [0:0:0:0] disk QEMU QEMU HARDDISK 2.5+ /dev/sda 0QEMU_QEMU_HARDDISK_drive-scsi0-0-0-0 364s state=running queue_depth=128 scsi_level=6 type=0 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/0:0:0:0 [/sys/devices/pci0000:00/0000:00:02.0/virtio1/host0/target0:0:0/0:0:0:0] 364s [1:0:0:0] storage IET Controller 0001 - - 364s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/1:0:0:0 [/sys/devices/platform/host1/session1/target1:0:0/1:0:0:0] 364s [1:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdb 360000000000000000e00000000010001 364s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/1:0:0:1 [/sys/devices/platform/host1/session1/target1:0:0/1:0:0:1] 364s [2:0:0:0] storage IET Controller 0001 - - 364s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/2:0:0:0 [/sys/devices/platform/host2/session2/target2:0:0/2:0:0:0] 364s [2:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdc 360000000000000000e00000000010001 364s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/2:0:0:1 [/sys/devices/platform/host2/session2/target2:0:0/2:0:0:1] 364s [3:0:0:0] storage IET Controller 0001 - - 364s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/3:0:0:0 [/sys/devices/platform/host3/session3/target3:0:0/3:0:0:0] 364s [3:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdd 360000000000000000e00000000010001 364s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/3:0:0:1 [/sys/devices/platform/host3/session3/target3:0:0/3:0:0:1] 364s [4:0:0:0] storage IET Controller 0001 - - 364s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/4:0:0:0 [/sys/devices/platform/host4/session4/target4:0:0/4:0:0:0] 364s [4:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sde 360000000000000000e00000000010001 364s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 364s dir: /sys/bus/scsi/devices/4:0:0:1 [/sys/devices/platform/host4/session4/target4:0:0/4:0:0:1] 364s NVMe module may not be loaded 364s + multipath -v3 -ll 364s 49.646142 | set open fds limit to 1073741816/1073741816 364s 49.646210 | loading /lib/multipath/libchecktur.so checker 364s 49.646297 | checker tur: message table size = 3 364s 49.646322 | loading /lib/multipath/libprioconst.so prioritizer 364s 49.646418 | _init_foreign: foreign library "nvme" is not enabled 364s 49.651874 | sda: size = 167772160 364s 49.652067 | sda: vendor = QEMU 364s 49.652096 | sda: product = QEMU HARDDISK 364s 49.652119 | sda: rev = 2.5+ 364s 49.652744 | sda: h:b:t:l = 0:0:0:0 364s 49.653066 | sda: tgt_node_name = 364s 49.653086 | sda: uid_attribute = ID_SERIAL (setting: multipath internal) 364s 49.653089 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 364s 49.653236 | sda: 10443 cyl, 255 heads, 63 sectors/track, start at 0 364s 49.653257 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.653270 | __sysfs_attr_get_value: attribute '/sys/devices/pci0000:00/0000:00:02.0/virtio1/host0/target0:0:0/0:0:0:0/vpd_pg80' can not be opened: No such file or directory 364s 49.653274 | failed to read sysfs vpd pg80: No such file or directory 364s 49.653508 | sda: fail to get serial 364s 49.653528 | sda: detect_checker = yes (setting: multipath internal) 364s 49.653733 | sda: path_checker = tur (setting: multipath internal) 364s 49.653754 | sda: checker timeout = 30 s (setting: kernel sysfs) 364s 49.653925 | sda: tur state = up 364s 49.654239 | sdb: size = 204800 364s 49.654388 | sdb: vendor = IET 364s 49.654427 | sdb: product = VIRTUAL-DISK 364s 49.654448 | sdb: rev = 0001 364s 49.655079 | sdb: h:b:t:l = 1:0:0:1 364s 49.655441 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.655460 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal) 364s 49.655463 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 364s 49.655614 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.655634 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.655649 | sdb: serial = beaf11 364s 49.655651 | sdb: detect_checker = yes (setting: multipath internal) 364s 49.655994 | sdb: path_checker = tur (setting: multipath internal) 364s 49.656027 | sdb: checker timeout = 30 s (setting: kernel sysfs) 364s 49.656261 | sdb: tur state = up 364s 49.656400 | sdc: size = 204800 364s 49.656546 | sdc: vendor = IET 364s 49.656584 | sdc: product = VIRTUAL-DISK 364s 49.656604 | sdc: rev = 0001 364s 49.657277 | sdc: h:b:t:l = 2:0:0:1 364s 49.657631 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.657650 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal) 364s 49.657652 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 364s 49.657795 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.657816 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.657830 | sdc: serial = beaf11 364s 49.657832 | sdc: detect_checker = yes (setting: multipath internal) 364s 49.658330 | sdc: path_checker = tur (setting: multipath internal) 364s 49.658355 | sdc: checker timeout = 30 s (setting: kernel sysfs) 364s 49.658914 | sdc: tur state = up 364s 49.659113 | sdd: size = 204800 364s 49.659317 | sdd: vendor = IET 364s 49.659377 | sdd: product = VIRTUAL-DISK 364s 49.659409 | sdd: rev = 0001 364s 49.660240 | sdd: h:b:t:l = 3:0:0:1 364s 49.660608 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.660627 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal) 364s 49.660630 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 364s 49.660774 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.660794 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.660807 | sdd: serial = beaf11 364s 49.660810 | sdd: detect_checker = yes (setting: multipath internal) 364s 49.661034 | sdd: path_checker = tur (setting: multipath internal) 364s 49.661054 | sdd: checker timeout = 30 s (setting: kernel sysfs) 364s 49.661246 | sdd: tur state = up 364s 49.661386 | sde: size = 204800 364s 49.661533 | sde: vendor = IET 364s 49.661571 | sde: product = VIRTUAL-DISK 364s 49.661592 | sde: rev = 0001 364s 49.662289 | sde: h:b:t:l = 4:0:0:1 364s 49.662646 | sde: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.662666 | sde: uid_attribute = ID_SERIAL (setting: multipath internal) 364s 49.662668 | sde: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 364s 49.662813 | sde: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.662833 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.662847 | sde: serial = beaf11 364s 49.662849 | sde: detect_checker = yes (setting: multipath internal) 364s 49.663232 | sde: path_checker = tur (setting: multipath internal) 364s 49.663253 | sde: checker timeout = 30 s (setting: kernel sysfs) 364s 49.663478 | sde: tur state = up 364s 49.663585 | loop0: device node name blacklisted 364s 49.663687 | loop1: device node name blacklisted 364s 49.663786 | loop2: device node name blacklisted 364s 49.663885 | loop3: device node name blacklisted 364s 49.663984 | loop4: device node name blacklisted 364s 49.664108 | loop5: device node name blacklisted 364s 49.664211 | loop6: device node name blacklisted 364s 49.664313 | loop7: device node name blacklisted 364s 49.664416 | dm-0: device node name blacklisted 364s 49.665358 | multipath-tools v0.9.4 (12/19, 2022) 364s 49.665385 | libdevmapper version 1.02.196 364s 49.665519 | kernel device mapper v4.48.0 364s 49.665545 | DM multipath kernel driver v1.14.0 364s 49.665647 | sdb: size = 204800 364s 49.665668 | sdb: vendor = IET 364s 49.665670 | sdb: product = VIRTUAL-DISK 364s 49.665673 | sdb: rev = 0001 364s 49.666314 | sdb: h:b:t:l = 1:0:0:1 364s 49.666446 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.666482 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.666485 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.666497 | sdb: serial = beaf11 364s 49.666680 | sdb: tur state = up 364s 49.666700 | sdb: uid = 360000000000000000e00000000010001 (udev) 364s 49.666702 | sdb: detect_prio = yes (setting: multipath internal) 364s 49.666705 | sdb: prio = const (setting: multipath internal) 364s 49.666708 | sdb: prio args = "" (setting: multipath internal) 364s 49.666710 | sdb: const prio = 1 364s 49.666731 | sdc: size = 204800 364s 49.666736 | sdc: vendor = IET 364s 49.666739 | sdc: product = VIRTUAL-DISK 364s 49.666742 | sdc: rev = 0001 364s 49.667351 | sdc: h:b:t:l = 2:0:0:1 364s 49.667478 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.667513 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.667515 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.667527 | sdc: serial = beaf11 364s 49.667741 | sdc: tur state = up 364s 49.667761 | sdc: uid = 360000000000000000e00000000010001 (udev) 364s 49.667764 | sdc: detect_prio = yes (setting: multipath internal) 364s 49.667766 | sdc: prio = const (setting: multipath internal) 364s 49.667768 | sdc: prio args = "" (setting: multipath internal) 364s 49.667770 | sdc: const prio = 1 364s 49.667794 | sdd: size = 204800 364s 49.667798 | sdd: vendor = IET 364s 49.667801 | sdd: product = VIRTUAL-DISK 364s 49.667803 | sdd: rev = 0001 364s 49.668438 | sdd: h:b:t:l = 3:0:0:1 364s 49.668571 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.668607 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.668609 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.668621 | sdd: serial = beaf11 364s 49.668799 | sdd: tur state = up 364s 49.668818 | sdd: uid = 360000000000000000e00000000010001 (udev) 364s 49.668821 | sdd: detect_prio = yes (setting: multipath internal) 364s 49.668823 | sdd: prio = const (setting: multipath internal) 364s 49.668825 | sdd: prio args = "" (setting: multipath internal) 364s 49.668827 | sdd: const prio = 1 364s 49.668850 | sde: size = 204800 364s 49.668854 | sde: vendor = IET 364s 49.668857 | sde: product = VIRTUAL-DISK 364s 49.668859 | sde: rev = 0001 364s 49.669461 | sde: h:b:t:l = 4:0:0:1 364s 49.669590 | sde: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 364s 49.669625 | sde: 1024 cyl, 4 heads, 50 sectors/track, start at 0 364s 49.669628 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal) 364s 49.669639 | sde: serial = beaf11 364s 49.669849 | sde: tur state = up 364s 49.669869 | sde: uid = 360000000000000000e00000000010001 (udev) 364s 49.669871 | sde: detect_prio = yes (setting: multipath internal) 364s 49.669874 | sde: prio = const (setting: multipath internal) 364s 49.669876 | sde: prio args = "" (setting: multipath internal) 364s 49.669878 | sde: const prio = 1 364s 49.670685 | unloading tur checker 364s 49.670740 | unloading const prioritizer 364s + dmsetup table 364s + echo Test WWN should now point to DM 364s + readlink /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 364s + grep dm 364s ===== paths list ===== 364s uuid hcil dev dev_t pri dm_st chk_st vend/prod/rev dev_st 364s 0:0:0:0 sda 8:0 -1 undef undef QEMU,QEMU HARDDISK unknown 364s 1:0:0:1 sdb 8:16 -1 undef undef IET,VIRTUAL-DISK unknown 364s 2:0:0:1 sdc 8:32 -1 undef undef IET,VIRTUAL-DISK unknown 364s 3:0:0:1 sdd 8:48 -1 undef undef IET,VIRTUAL-DISK unknown 364s 4:0:0:1 sde 8:64 -1 undef undef IET,VIRTUAL-DISK unknown 364s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 364s size=100M features='0' hwhandler='0' wp=rw 364s |-+- policy='service-time 0' prio=1 status=active 364s | `- 1:0:0:1 sdb 8:16 active ready running 364s |-+- policy='service-time 0' prio=1 status=enabled 364s | `- 2:0:0:1 sdc 8:32 active ready running 364s |-+- policy='service-time 0' prio=1 status=enabled 364s | `- 3:0:0:1 sdd 8:48 active ready running 364s `-+- policy='service-time 0' prio=1 status=enabled 364s `- 4:0:0:1 sde 8:64 active ready running 364s mpatha: 0 204800 multipath 0 0 4 1 service-time 0 1 2 8:16 1 1 service-time 0 1 2 8:32 1 1 service-time 0 1 2 8:48 1 1 service-time 0 1 2 8:64 1 1 364s Test WWN should now point to DM 364s autopkgtest [15:17:09]: test tgtbasedmpaths: -----------------------] 364s autopkgtest [15:17:09]: test tgtbasedmpaths: - - - - - - - - - - results - - - - - - - - - - 364s tgtbasedmpaths FAIL non-zero exit status 1 365s autopkgtest [15:17:10]: @@@@@@@@@@@@@@@@@@@@ summary 365s kpartx-file-loopback PASS 365s tgtbasedmpaths FAIL non-zero exit status 1 376s nova [W] Using flock in scalingstack-bos02-ppc64el 376s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240723-151105-juju-7f2275-prod-proposed-migration-environment-3-fbcd5bf6-8c59-48da-9a22-44f00071356b from image adt/ubuntu-oracular-ppc64el-server-20240723.img (UUID 9504d3d5-930a-4a10-abf7-83fd7e43719c)... 376s nova [W] Using flock in scalingstack-bos02-ppc64el 376s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240723-151105-juju-7f2275-prod-proposed-migration-environment-3-fbcd5bf6-8c59-48da-9a22-44f00071356b from image adt/ubuntu-oracular-ppc64el-server-20240723.img (UUID 9504d3d5-930a-4a10-abf7-83fd7e43719c)...