0s autopkgtest [12:42:23]: starting date and time: 2024-07-16 12:42:23+0000 0s autopkgtest [12:42:23]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [12:42:23]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.apqjg8mw/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-ppc64el-16.secgroup --name adt-oracular-ppc64el-multipath-tools-20240716-120652-juju-7f2275-prod-proposed-migration-environment-3-2a4b67e6-8b2c-436d-bd56-fab7e8286052 --image adt/ubuntu-oracular-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration-ppc64el -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/ 58s autopkgtest [12:43:21]: testbed dpkg architecture: ppc64el 58s autopkgtest [12:43:21]: testbed apt version: 2.9.6 58s autopkgtest [12:43:21]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 59s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 59s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [41.6 kB] 59s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 59s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [334 kB] 59s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [67.3 kB] 59s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 59s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 59s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 59s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [291 kB] 59s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 59s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1448 B] 59s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 62s Fetched 886 kB in 1s (1044 kB/s) 62s Reading package lists... 64s Reading package lists... 64s Building dependency tree... 64s Reading state information... 64s Calculating upgrade... 65s The following packages will be upgraded: 65s dmsetup libdevmapper1.02.1 nano 65s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 65s Need to get 601 kB of archives. 65s After this operation, 5120 B of additional disk space will be used. 65s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB] 65s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB] 65s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el nano ppc64el 8.1-1 [329 kB] 65s Fetched 601 kB in 1s (1034 kB/s) 67s (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 ... 72764 files and directories currently installed.) 67s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 67s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 67s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 67s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 67s Preparing to unpack .../nano_8.1-1_ppc64el.deb ... 67s Unpacking nano (8.1-1) over (8.0-1) ... 67s Setting up nano (8.1-1) ... 67s Installing new version of config file /etc/nanorc ... 67s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ... 67s Setting up dmsetup (2:1.02.196-1ubuntu1) ... 67s Processing triggers for initramfs-tools (0.142ubuntu30) ... 67s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 67s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 77s Processing triggers for libc-bin (2.39-0ubuntu9) ... 77s Processing triggers for man-db (2.12.1-2) ... 80s Processing triggers for install-info (7.1-3build2) ... 80s Reading package lists... 80s Building dependency tree... 80s Reading state information... 81s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 81s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 81s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 81s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 81s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 82s Reading package lists... 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 83s Calculating upgrade... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s autopkgtest [12:43:46]: rebooting testbed after setup commands that affected boot 87s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 117s autopkgtest [12:44:20]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 120s autopkgtest [12:44:23]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools 124s Get:1 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (dsc) [2770 B] 124s Get:2 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (tar) [554 kB] 124s Get:3 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (diff) [42.7 kB] 125s gpgv: Signature made Sun Apr 7 00:31:08 2024 UTC 125s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 125s gpgv: issuer "steve.langasek@ubuntu.com" 125s gpgv: Can't check signature: No public key 125s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.4-5ubuntu8.dsc: no acceptable signature found 125s autopkgtest [12:44:28]: testing package multipath-tools version 0.9.4-5ubuntu8 126s autopkgtest [12:44:29]: build not needed 126s autopkgtest [12:44:29]: test kpartx-file-loopback: preparing testbed 127s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s Starting pkgProblemResolver with broken count: 0 127s Starting 2 pkgProblemResolver with broken count: 0 127s Done 128s The following additional packages will be installed: 128s liburing2 qemu-utils 128s Recommended packages: 128s qemu-block-extra 128s The following NEW packages will be installed: 128s autopkgtest-satdep liburing2 qemu-utils 128s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 128s Need to get 2330 kB/2331 kB of archives. 128s After this operation, 15.4 MB of additional disk space will be used. 128s Get:1 /tmp/autopkgtest.XoACNB/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 128s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el liburing2 ppc64el 2.6-1 [26.9 kB] 128s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el qemu-utils ppc64el 1:8.2.2+ds-0ubuntu1 [2303 kB] 129s Fetched 2330 kB in 1s (3289 kB/s) 129s Selecting previously unselected package liburing2:ppc64el. 130s (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 ... 72764 files and directories currently installed.) 130s Preparing to unpack .../liburing2_2.6-1_ppc64el.deb ... 130s Unpacking liburing2:ppc64el (2.6-1) ... 130s Selecting previously unselected package qemu-utils. 130s Preparing to unpack .../qemu-utils_1%3a8.2.2+ds-0ubuntu1_ppc64el.deb ... 130s Unpacking qemu-utils (1:8.2.2+ds-0ubuntu1) ... 130s Selecting previously unselected package autopkgtest-satdep. 130s Preparing to unpack .../1-autopkgtest-satdep.deb ... 130s Unpacking autopkgtest-satdep (0) ... 130s Setting up liburing2:ppc64el (2.6-1) ... 130s Setting up qemu-utils (1:8.2.2+ds-0ubuntu1) ... 130s Setting up autopkgtest-satdep (0) ... 130s Processing triggers for man-db (2.12.1-2) ... 133s Processing triggers for libc-bin (2.39-0ubuntu9) ... 136s (Reading database ... 72784 files and directories currently installed.) 136s Removing autopkgtest-satdep (0) ... 137s autopkgtest [12:44:40]: test kpartx-file-loopback: [----------------------- 137s Formatting 'foo.img', fmt=raw size=20971520 139s Creating new GPT entries in memory. 139s Warning: The kernel is still using the old partition table. 139s The new table will be used at the next reboot or after you 139s run partprobe(8) or kpartx(8) 139s The operation has completed successfully. 139s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 139s standard_filename: OK 139s del devmap : loop0p1 139s No devices found 139s standard_filename_cleanup: OK 139s Formatting 'fou du FaFa.img', fmt=raw size=20971520 140s Creating new GPT entries in memory. 140s Warning: The kernel is still using the old partition table. 140s The new table will be used at the next reboot or after you 140s run partprobe(8) or kpartx(8) 140s The operation has completed successfully. 140s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 140s filename_with_spaces: OK 140s del devmap : loop0p1 140s No devices found 140s filename_with_spaces_cleanup: OK 140s autopkgtest [12:44:43]: test kpartx-file-loopback: -----------------------] 141s autopkgtest [12:44:44]: test kpartx-file-loopback: - - - - - - - - - - results - - - - - - - - - - 141s kpartx-file-loopback PASS 141s autopkgtest [12:44:44]: test tgtbasedmpaths: preparing testbed 514s autopkgtest [12:50:57]: testbed dpkg architecture: ppc64el 514s autopkgtest [12:50:57]: testbed apt version: 2.9.6 514s autopkgtest [12:50:57]: @@@@@@@@@@@@@@@@@@@@ test bed setup 515s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 515s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 515s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [41.6 kB] 515s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [334 kB] 515s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 515s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [67.3 kB] 515s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 515s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 515s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 515s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [291 kB] 515s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 515s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1448 B] 515s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 517s Fetched 886 kB in 1s (1153 kB/s) 517s Reading package lists... 520s Reading package lists... 520s Building dependency tree... 520s Reading state information... 520s Calculating upgrade... 520s The following packages will be upgraded: 520s dmsetup libdevmapper1.02.1 nano 520s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 520s Need to get 601 kB of archives. 520s After this operation, 5120 B of additional disk space will be used. 520s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB] 520s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB] 520s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el nano ppc64el 8.1-1 [329 kB] 521s Fetched 601 kB in 1s (1183 kB/s) 521s (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 ... 72764 files and directories currently installed.) 521s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 521s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 521s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 521s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 521s Preparing to unpack .../nano_8.1-1_ppc64el.deb ... 521s Unpacking nano (8.1-1) over (8.0-1) ... 521s Setting up nano (8.1-1) ... 521s Installing new version of config file /etc/nanorc ... 521s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ... 521s Setting up dmsetup (2:1.02.196-1ubuntu1) ... 521s Processing triggers for initramfs-tools (0.142ubuntu30) ... 521s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 521s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 530s Processing triggers for libc-bin (2.39-0ubuntu9) ... 530s Processing triggers for man-db (2.12.1-2) ... 531s Processing triggers for install-info (7.1-3build2) ... 532s Reading package lists... 532s Building dependency tree... 532s Reading state information... 532s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 532s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 532s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 533s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 533s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 534s Reading package lists... 534s Reading package lists... 534s Building dependency tree... 534s Reading state information... 534s Calculating upgrade... 534s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 534s Reading package lists... 534s Building dependency tree... 534s Reading state information... 535s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 535s autopkgtest [12:51:18]: rebooting testbed after setup commands that affected boot 567s Reading package lists... 567s Building dependency tree... 567s Reading state information... 568s Starting pkgProblemResolver with broken count: 0 568s Starting 2 pkgProblemResolver with broken count: 0 568s Done 568s The following additional packages will be installed: 568s fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl 568s libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0 568s libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1 568s librdmacm1t64 lsscsi open-iscsi tgt 568s Suggested packages: 568s fio-examples gnuplot tgt-glusterfs tgt-rbd 568s Recommended packages: 568s finalrd 568s The following NEW packages will be installed: 568s autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0 568s libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 568s libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1 568s libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt 568s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 568s Need to get 10.7 MB/10.7 MB of archives. 568s After this operation, 49.0 MB of additional disk space will be used. 568s Get:1 /tmp/autopkgtest.XoACNB/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [732 B] 568s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libopeniscsiusr ppc64el 2.1.10-1ubuntu1 [54.9 kB] 568s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libisns0t64 ppc64el 0.101-1 [117 kB] 568s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el open-iscsi ppc64el 2.1.10-1ubuntu1 [385 kB] 568s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el librdmacm1t64 ppc64el 52.0-2 [80.5 kB] 568s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB] 568s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el tgt ppc64el 1:1.0.85-1.2ubuntu1 [254 kB] 568s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfxdr0 ppc64el 11.1-4ubuntu1 [21.5 kB] 568s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libglusterfs0 ppc64el 11.1-4ubuntu1 [311 kB] 569s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfrpc0 ppc64el 11.1-4ubuntu1 [46.5 kB] 569s Get:11 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfapi0 ppc64el 11.1-4ubuntu1 [99.2 kB] 569s Get:12 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libnbd0 ppc64el 1.20.2-1 [97.4 kB] 569s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdaxctl1 ppc64el 77-2ubuntu2 [23.7 kB] 569s Get:14 http://ftpmaster.internal/ubuntu oracular/main ppc64el libndctl6 ppc64el 77-2ubuntu2 [74.9 kB] 569s Get:15 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB] 569s Get:16 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-3ubuntu1 [259 kB] 569s Get:17 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-3ubuntu1 [279 kB] 569s Get:18 http://ftpmaster.internal/ubuntu oracular/main ppc64el librados2 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [4026 kB] 569s Get:19 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB] 569s Get:20 http://ftpmaster.internal/ubuntu oracular/main ppc64el librbd1 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [3581 kB] 569s Get:21 http://ftpmaster.internal/ubuntu oracular/universe ppc64el fio ppc64el 3.37-1 [716 kB] 569s Get:22 http://ftpmaster.internal/ubuntu oracular/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB] 569s Preconfiguring packages ... 569s Fetched 10.7 MB in 1s (11.1 MB/s) 569s Selecting previously unselected package libopeniscsiusr. 570s (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 ... 72764 files and directories currently installed.) 570s Preparing to unpack .../00-libopeniscsiusr_2.1.10-1ubuntu1_ppc64el.deb ... 570s Unpacking libopeniscsiusr (2.1.10-1ubuntu1) ... 570s Selecting previously unselected package libisns0t64:ppc64el. 570s Preparing to unpack .../01-libisns0t64_0.101-1_ppc64el.deb ... 570s Unpacking libisns0t64:ppc64el (0.101-1) ... 570s Selecting previously unselected package open-iscsi. 570s Preparing to unpack .../02-open-iscsi_2.1.10-1ubuntu1_ppc64el.deb ... 570s Unpacking open-iscsi (2.1.10-1ubuntu1) ... 570s Selecting previously unselected package librdmacm1t64:ppc64el. 570s Preparing to unpack .../03-librdmacm1t64_52.0-2_ppc64el.deb ... 570s Unpacking librdmacm1t64:ppc64el (52.0-2) ... 570s Selecting previously unselected package libconfig-general-perl. 570s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ... 570s Unpacking libconfig-general-perl (2.65-2) ... 570s Selecting previously unselected package tgt. 570s Preparing to unpack .../05-tgt_1%3a1.0.85-1.2ubuntu1_ppc64el.deb ... 570s Unpacking tgt (1:1.0.85-1.2ubuntu1) ... 570s Selecting previously unselected package libgfxdr0:ppc64el. 570s Preparing to unpack .../06-libgfxdr0_11.1-4ubuntu1_ppc64el.deb ... 570s Unpacking libgfxdr0:ppc64el (11.1-4ubuntu1) ... 570s Selecting previously unselected package libglusterfs0:ppc64el. 570s Preparing to unpack .../07-libglusterfs0_11.1-4ubuntu1_ppc64el.deb ... 570s Unpacking libglusterfs0:ppc64el (11.1-4ubuntu1) ... 570s Selecting previously unselected package libgfrpc0:ppc64el. 570s Preparing to unpack .../08-libgfrpc0_11.1-4ubuntu1_ppc64el.deb ... 570s Unpacking libgfrpc0:ppc64el (11.1-4ubuntu1) ... 570s Selecting previously unselected package libgfapi0:ppc64el. 570s Preparing to unpack .../09-libgfapi0_11.1-4ubuntu1_ppc64el.deb ... 570s Unpacking libgfapi0:ppc64el (11.1-4ubuntu1) ... 570s Selecting previously unselected package libnbd0. 570s Preparing to unpack .../10-libnbd0_1.20.2-1_ppc64el.deb ... 570s Unpacking libnbd0 (1.20.2-1) ... 570s Selecting previously unselected package libdaxctl1:ppc64el. 570s Preparing to unpack .../11-libdaxctl1_77-2ubuntu2_ppc64el.deb ... 570s Unpacking libdaxctl1:ppc64el (77-2ubuntu2) ... 570s Selecting previously unselected package libndctl6:ppc64el. 570s Preparing to unpack .../12-libndctl6_77-2ubuntu2_ppc64el.deb ... 570s Unpacking libndctl6:ppc64el (77-2ubuntu2) ... 570s Selecting previously unselected package libpmem1:ppc64el. 570s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ... 570s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 570s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el. 570s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-3ubuntu1_ppc64el.deb ... 570s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ... 570s Selecting previously unselected package libboost-thread1.83.0:ppc64el. 570s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-3ubuntu1_ppc64el.deb ... 570s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ... 570s Selecting previously unselected package librados2. 570s Preparing to unpack .../16-librados2_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ... 570s Unpacking librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 570s Selecting previously unselected package libpmemobj1:ppc64el. 570s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ... 570s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 570s Selecting previously unselected package librbd1. 570s Preparing to unpack .../18-librbd1_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ... 570s Unpacking librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 571s Selecting previously unselected package fio. 571s Preparing to unpack .../19-fio_3.37-1_ppc64el.deb ... 571s Unpacking fio (3.37-1) ... 571s Selecting previously unselected package lsscsi. 571s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ... 571s Unpacking lsscsi (0.32-1build1) ... 571s Selecting previously unselected package autopkgtest-satdep. 571s Preparing to unpack .../21-2-autopkgtest-satdep.deb ... 571s Unpacking autopkgtest-satdep (0) ... 571s Setting up libconfig-general-perl (2.65-2) ... 571s Setting up libisns0t64:ppc64el (0.101-1) ... 571s Setting up libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ... 571s Setting up libnbd0 (1.20.2-1) ... 571s Setting up libopeniscsiusr (2.1.10-1ubuntu1) ... 571s Setting up libglusterfs0:ppc64el (11.1-4ubuntu1) ... 571s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ... 571s Setting up lsscsi (0.32-1build1) ... 571s Setting up libdaxctl1:ppc64el (77-2ubuntu2) ... 571s Setting up libndctl6:ppc64el (77-2ubuntu2) ... 571s Setting up librdmacm1t64:ppc64el (52.0-2) ... 571s Setting up tgt (1:1.0.85-1.2ubuntu1) ... 571s Created symlink '/etc/systemd/system/multi-user.target.wants/tgt.service' → '/usr/lib/systemd/system/tgt.service'. 572s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 572s Setting up libgfxdr0:ppc64el (11.1-4ubuntu1) ... 572s Setting up librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 572s Setting up open-iscsi (2.1.10-1ubuntu1) ... 572s Created symlink '/etc/systemd/system/sockets.target.wants/iscsid.socket' → '/usr/lib/systemd/system/iscsid.socket'. 573s Created symlink '/etc/systemd/system/iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 573s Created symlink '/etc/systemd/system/sysinit.target.wants/open-iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 573s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 573s Setting up librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 573s Setting up libgfrpc0:ppc64el (11.1-4ubuntu1) ... 573s Setting up libgfapi0:ppc64el (11.1-4ubuntu1) ... 573s Setting up fio (3.37-1) ... 574s Setting up autopkgtest-satdep (0) ... 574s Processing triggers for man-db (2.12.1-2) ... 576s Processing triggers for initramfs-tools (0.142ubuntu30) ... 576s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 576s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 586s Processing triggers for libc-bin (2.39-0ubuntu9) ... 589s (Reading database ... 73005 files and directories currently installed.) 589s Removing autopkgtest-satdep (0) ... 591s autopkgtest [12:52:14]: test tgtbasedmpaths: [----------------------- 591s + targetname=iqn.2016-11.foo.com:target.iscsi 591s + pwd 591s + cwd=/tmp/autopkgtest.XoACNB/build.74j/src 591s + testdir=/mnt/tgtmpathtest 591s + localhost=127.0.0.1 591s + portal=127.0.0.1:3260 591s + maxpaths=4 591s + backfn=backingfile 591s + expectwwid=60000000000000000e00000000010001 591s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001 591s + bglog=/tmp/autopkgtest.XoACNB/tgtbasedmpaths-artifacts/test-background.log 591s + fioprep=/tmp/autopkgtest.XoACNB/tgtbasedmpaths-artifacts/path-change-prep.fio 591s + fiovrfy=/tmp/autopkgtest.XoACNB/tgtbasedmpaths-artifacts/path-change-check.fio 591s + service tgt restart 591s + truncate --size 100M backingfile 591s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi 591s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL 591s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.XoACNB/build.74j/src/backingfile 591s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1 591s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi 591s login #1 591s + echo login #1 591s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login 591s + seq 2 4 591s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 591s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 591s + echo extra login #2 591s + iscsiadm --mode session -r 1 --op new 591s extra login #2 591s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 591s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 591s extra login #3 591s + echo extra login #3 591s + iscsiadm --mode session -r 1 --op new 591s + echo extra login #4 591s + iscsiadm --mode session -r 1 --op new 591s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 591s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 591s extra login #4 591s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 591s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 591s + udevadm settle 591s + sleep 5 596s Status after initial setup 596s + echo Status after initial setup 596s + tgtadm --lld iscsi --mode target --op show 596s Target 1: iqn.2016-11.foo.com:target.iscsi 596s System information: 596s Driver: iscsi 596s State: ready 596s I_T nexus information: 596s I_T nexus: 1 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a alias: autopkgtest 596s Connection: 0 596s IP Address: 127.0.0.1 596s I_T nexus: 2 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a alias: autopkgtest 596s Connection: 0 596s IP Address: 127.0.0.1 596s I_T nexus: 3 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a alias: autopkgtest 596s Connection: 0 596s IP Address: 127.0.0.1 596s I_T nexus: 4 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a alias: autopkgtest 596s Connection: 0 596s IP Address: 127.0.0.1 596s LUN information: 596s LUN: 0 596s Type: controller 596s SCSI ID: IET 00010000 596s SCSI SN: beaf10 596s Size: 0 MB, Block size: 1 596s Online: Yes 596s Removable media: No 596s Prevent removal: No 596s Readonly: No 596s SWP: No 596s Thin-provisioning: No 596s Backing store type: null 596s Backing store path: None 596s Backing store flags: 596s LUN: 1 596s Type: disk 596s SCSI ID: IET 00010001 596s SCSI SN: beaf11 596s Size: 105 MB, Block size: 512 596s Online: Yes 596s Removable media: No 596s Prevent removal: No 596s Readonly: No 596s SWP: No 596s Thin-provisioning: No 596s Backing store type: rdwr 596s Backing store path: /tmp/autopkgtest.XoACNB/build.74j/src/backingfile 596s Backing store flags: 596s Account information: 596s ACL information: 596s ALL 596s + tgtadm --lld iscsi --op show --mode conn --tid 1 596s Session: 4 596s Connection: 0 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s IP Address: 127.0.0.1 596s Session: 3 596s Connection: 0 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s IP Address: 127.0.0.1 596s Session: 2 596s Connection: 0 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s IP Address: 127.0.0.1 596s Session: 1 596s Connection: 0 596s Initiator: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s IP Address: 127.0.0.1 596s + iscsiadm --mode session -P 1 596s Target: iqn.2016-11.foo.com:target.iscsi (non-flash) 596s Current Portal: 127.0.0.1:3260,1 596s Persistent Portal: 127.0.0.1:3260,1 596s ********** 596s Interface: 596s ********** 596s Iface Name: default 596s Iface Transport: tcp 596s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s Iface IPaddress: 127.0.0.1 596s Iface HWaddress: default 596s Iface Netdev: default 596s SID: 1 596s iSCSI Connection State: LOGGED IN 596s iSCSI Session State: LOGGED_IN 596s Internal iscsid Session State: NO CHANGE 596s 596s ********** 596s Interface: 596s ********** 596s Iface Name: default 596s Iface Transport: tcp 596s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s Iface IPaddress: 127.0.0.1 596s Iface HWaddress: default 596s Iface Netdev: default 596s SID: 2 596s iSCSI Connection State: LOGGED IN 596s iSCSI Session State: LOGGED_IN 596s Internal iscsid Session State: NO CHANGE 596s 596s ********** 596s Interface: 596s ********** 596s Iface Name: default 596s Iface Transport: tcp 596s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s Iface IPaddress: 127.0.0.1 596s Iface HWaddress: default 596s Iface Netdev: default 596s SID: 3 596s iSCSI Connection State: LOGGED IN 596s iSCSI Session State: LOGGED_IN 596s Internal iscsid Session State: NO CHANGE 596s 596s ********** 596s Interface: 596s ********** 596s Iface Name: default 596s Iface Transport: tcp 596s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:bd72686e6b5a 596s Iface IPaddress: 127.0.0.1 596s Iface HWaddress: default 596s Iface Netdev: default 596s SID: 4 596s iSCSI Connection State: LOGGED IN 596s iSCSI Session State: LOGGED_IN 596s Internal iscsid Session State: NO CHANGE 596s + lsscsi -liv 596s list_ndevices: scandir: /sys/class/nvme/: No such file or directory 597s [0:0:0:0] storage IET Controller 0001 - - 597s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/0:0:0:0 [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:0] 597s [0:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sda 360000000000000000e00000000010001 597s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/0:0:0:1 [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:1] 597s [1:0:0:0] storage IET Controller 0001 - - 597s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/1:0:0:0 [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:0] 597s [1:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdb 360000000000000000e00000000010001 597s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/1:0:0:1 [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:1] 597s [2:0:0:0] storage IET Controller 0001 - - 597s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/2:0:0:0 [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:0] 597s [2:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdc 360000000000000000e00000000010001 597s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/2:0:0:1 [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:1] 597s [3:0:0:0] storage IET Controller 0001 - - 597s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/3:0:0:0 [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:0] 597s [3:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdd 33000000100000001 597s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 597s dir: /sys/bus/scsi/devices/3:0:0:1 [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:1] 597s NVMe module may not be loaded 597s + multipath -v3 -ll 597s 48.282598 | set open fds limit to 1073741816/1073741816 597s 48.282669 | loading /lib/multipath/libchecktur.so checker 597s 48.282775 | checker tur: message table size = 3 597s 48.282787 | loading /lib/multipath/libprioconst.so prioritizer 597s 48.282917 | _init_foreign: foreign library "nvme" is not enabled 597s 48.289870 | vda: device node name blacklisted 597s 48.290256 | sda: size = 204800 597s 48.290439 | sda: vendor = IET 597s 48.290470 | sda: product = VIRTUAL-DISK 597s 48.290501 | sda: rev = 0001 597s 48.291486 | sda: h:b:t:l = 0:0:0:1 597s 48.291965 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.291970 | sda: uid_attribute = ID_SERIAL (setting: multipath internal) 597s 48.291973 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 597s 48.292173 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.292182 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.292197 | sda: serial = beaf11 597s 48.292200 | sda: detect_checker = yes (setting: multipath internal) 597s 48.292485 | sda: path_checker = tur (setting: multipath internal) 597s 48.292490 | sda: checker timeout = 30 s (setting: kernel sysfs) 597s 48.292603 | sda: tur state = up 597s 48.292731 | sdb: size = 204800 597s 48.292860 | sdb: vendor = IET 597s 48.292882 | sdb: product = VIRTUAL-DISK 597s 48.292903 | sdb: rev = 0001 597s 48.293522 | sdb: h:b:t:l = 1:0:0:1 597s 48.293857 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.293859 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal) 597s 48.293861 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 597s 48.294002 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.294005 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.294018 | sdb: serial = beaf11 597s 48.294020 | sdb: detect_checker = yes (setting: multipath internal) 597s 48.294891 | sdb: path_checker = tur (setting: multipath internal) 597s 48.294897 | sdb: checker timeout = 30 s (setting: kernel sysfs) 597s 48.295009 | sdb: tur state = up 597s 48.295137 | sdc: size = 204800 597s 48.295267 | sdc: vendor = IET 597s 48.295288 | sdc: product = VIRTUAL-DISK 597s 48.295310 | sdc: rev = 0001 597s 48.295984 | sdc: h:b:t:l = 2:0:0:1 597s 48.296380 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.296389 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal) 597s 48.296391 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 597s 48.296538 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.296542 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.296555 | sdc: serial = beaf11 597s 48.296558 | sdc: detect_checker = yes (setting: multipath internal) 597s 48.296731 | sdc: path_checker = tur (setting: multipath internal) 597s 48.296736 | sdc: checker timeout = 30 s (setting: kernel sysfs) 597s 48.296870 | sdc: tur state = up 597s 48.296999 | sdd: size = 204800 597s 48.297128 | sdd: vendor = IET 597s 48.297149 | sdd: product = VIRTUAL-DISK 597s 48.297170 | sdd: rev = 0001 597s 48.297826 | sdd: h:b:t:l = 3:0:0:1 597s 48.298159 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.298163 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal) 597s 48.298165 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 597s 48.298311 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.298315 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.298328 | sdd: serial = beaf11 597s 48.298330 | sdd: detect_checker = yes (setting: multipath internal) 597s 48.298500 | sdd: path_checker = tur (setting: multipath internal) 597s 48.298505 | sdd: checker timeout = 30 s (setting: kernel sysfs) 597s 48.298580 | sdd: tur state = up 597s 48.298672 | loop0: device node name blacklisted 597s 48.298756 | loop1: device node name blacklisted 597s 48.298838 | loop2: device node name blacklisted 597s 48.298919 | loop3: device node name blacklisted 597s 48.299001 | loop4: device node name blacklisted 597s 48.299081 | loop5: device node name blacklisted 597s 48.299162 | loop6: device node name blacklisted 597s 48.299244 | loop7: device node name blacklisted 597s 48.299334 | dm-0: device node name blacklisted 597s 48.300398 | multipath-tools v0.9.4 (12/19, 2022) 597s 48.300418 | libdevmapper version 1.02.196 597s 48.300591 | kernel device mapper v4.48.0 597s 48.300605 | DM multipath kernel driver v1.14.0 597s ===== paths list ===== 597s uuid hcil dev dev_t pri dm_st chk_st vend/prod/rev dev_st 597s 0:0:0:1 sda 8:0 -1 undef undef IET,VIRTUAL-DISK unknown 597s 1:0:0:1 sdb 8:16 -1 undef undef IET,VIRTUAL-DISK unknown 597s 2:0:0:1 sdc 8:32 -1 undef undef IET,VIRTUAL-DISK unknown 597s 3:0:0:1 sdd 8:48 -1 undef undef IET,VIRTUAL-DISK unknown 597s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 597s size=100M features='0' hwhandler='0' wp=rw 597s |-+- policy='service-time 0' prio=1 status=active 597s | `- 0:0:0:1 sda 8:0 active ready running 597s |-+- policy='service-time 0' prio=1 status=enabled 597s | `- 1:0:0:1 sdb 8:16 active ready running 597s |-+- policy='service-time 0' prio=1 status=enabled 597s | `- 2:0:0:1 sdc 8:32 active ready running 597s `-+- policy='service-time 0' prio=1 status=enabled 597s `- 3:0:0:1 sdd 8:48 active ready running 597s mpatha: 0 204800 multipath 0 0 4 1 service-time 0 1 2 8:0 1 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 597s Test WWN should now point to DM 597s 48.300736 | sda: size = 204800 597s 48.300744 | sda: vendor = IET 597s 48.300747 | sda: product = VIRTUAL-DISK 597s 48.300751 | sda: rev = 0001 597s 48.301573 | sda: h:b:t:l = 0:0:0:1 597s 48.301723 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.301749 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.301753 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.301769 | sda: serial = beaf11 597s 48.301873 | sda: tur state = up 597s 48.301880 | sda: uid = 360000000000000000e00000000010001 (udev) 597s 48.301884 | sda: detect_prio = yes (setting: multipath internal) 597s 48.301888 | sda: prio = const (setting: multipath internal) 597s 48.301890 | sda: prio args = "" (setting: multipath internal) 597s 48.301893 | sda: const prio = 1 597s 48.301922 | sdb: size = 204800 597s 48.301928 | sdb: vendor = IET 597s 48.301932 | sdb: product = VIRTUAL-DISK 597s 48.301935 | sdb: rev = 0001 597s 48.302739 | sdb: h:b:t:l = 1:0:0:1 597s 48.302885 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.302909 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.302913 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.302928 | sdb: serial = beaf11 597s 48.303037 | sdb: tur state = up 597s 48.303044 | sdb: uid = 360000000000000000e00000000010001 (udev) 597s 48.303047 | sdb: detect_prio = yes (setting: multipath internal) 597s 48.303050 | sdb: prio = const (setting: multipath internal) 597s 48.303053 | sdb: prio args = "" (setting: multipath internal) 597s 48.303055 | sdb: const prio = 1 597s 48.303085 | sdc: size = 204800 597s 48.303092 | sdc: vendor = IET 597s 48.303095 | sdc: product = VIRTUAL-DISK 597s 48.303099 | sdc: rev = 0001 597s 48.303906 | sdc: h:b:t:l = 2:0:0:1 597s 48.304024 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.304048 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.304051 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.304063 | sdc: serial = beaf11 597s 48.304133 | sdc: tur state = up 597s 48.304139 | sdc: uid = 360000000000000000e00000000010001 (udev) 597s 48.304141 | sdc: detect_prio = yes (setting: multipath internal) 597s 48.304144 | sdc: prio = const (setting: multipath internal) 597s 48.304146 | sdc: prio args = "" (setting: multipath internal) 597s 48.304148 | sdc: const prio = 1 597s 48.304169 | sdd: size = 204800 597s 48.304174 | sdd: vendor = IET 597s 48.304176 | sdd: product = VIRTUAL-DISK 597s 48.304179 | sdd: rev = 0001 597s 48.304764 | sdd: h:b:t:l = 3:0:0:1 597s 48.304871 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 597s 48.304890 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 597s 48.304892 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 597s 48.304903 | sdd: serial = beaf11 597s 48.304993 | sdd: tur state = up 597s 48.304999 | sdd: uid = 360000000000000000e00000000010001 (udev) 597s 48.305001 | sdd: detect_prio = yes (setting: multipath internal) 597s 48.305003 | sdd: prio = const (setting: multipath internal) 597s 48.305005 | sdd: prio args = "" (setting: multipath internal) 597s 48.305007 | sdd: const prio = 1 597s 48.305766 | unloading tur checker 597s 48.305813 | unloading const prioritizer 597s + dmsetup table 597s + echo Test WWN should now point to DM 597s + readlink /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 597s + grep dm 597s autopkgtest [12:52:20]: test tgtbasedmpaths: -----------------------] 597s tgtbasedmpaths FAIL non-zero exit status 1 597s autopkgtest [12:52:20]: test tgtbasedmpaths: - - - - - - - - - - results - - - - - - - - - - 598s autopkgtest [12:52:21]: @@@@@@@@@@@@@@@@@@@@ summary 598s kpartx-file-loopback PASS 598s tgtbasedmpaths FAIL non-zero exit status 1 602s nova [W] Using flock in prodstack6-ppc64el 602s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240716-120652-juju-7f2275-prod-proposed-migration-environment-3-2a4b67e6-8b2c-436d-bd56-fab7e8286052 from image adt/ubuntu-oracular-ppc64el-server-20240715.img (UUID 6a9323f6-ff11-4f03-bff9-aa3df5172622)... 602s nova [W] Using flock in prodstack6-ppc64el 602s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240716-120652-juju-7f2275-prod-proposed-migration-environment-3-2a4b67e6-8b2c-436d-bd56-fab7e8286052 from image adt/ubuntu-oracular-ppc64el-server-20240715.img (UUID 6a9323f6-ff11-4f03-bff9-aa3df5172622)... 602s nova [W] nova quota exceeded (attempt #0)