1s autopkgtest [13:50:08]: starting date and time: 2024-07-23 13:50:08+0000 1s autopkgtest [13:50:08]: git checkout: fd3bed09 nova: allow more retries for quota issues 1s autopkgtest [13:50:08]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3qlr5cb5/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-2.secgroup --name adt-oracular-ppc64el-multipath-tools-20240723-135002-juju-7f2275-prod-proposed-migration-environment-3-8b964fc9-4021-4bc9-b711-0d001c94a2c6 --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/ 231s autopkgtest [13:53:58]: testbed dpkg architecture: ppc64el 231s autopkgtest [13:53:58]: testbed apt version: 2.9.6 231s autopkgtest [13:53:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 237s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 237s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [66.5 kB] 238s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 238s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [393 kB] 238s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [5704 B] 238s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [116 kB] 238s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 238s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 238s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 238s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [531 kB] 238s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 238s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [2432 B] 238s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 240s Fetched 1263 kB in 1s (1439 kB/s) 240s Reading package lists... 253s Reading package lists... 253s Building dependency tree... 253s Reading state information... 254s Calculating upgrade... 254s The following packages will be upgraded: 254s dmsetup libdevmapper1.02.1 254s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 254s Need to get 273 kB of archives. 254s After this operation, 1024 B of additional disk space will be used. 254s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB] 254s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB] 255s Fetched 273 kB in 0s (603 kB/s) 255s (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 ... 72765 files and directories currently installed.) 255s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 255s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 255s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 255s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 255s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ... 255s Setting up dmsetup (2:1.02.196-1ubuntu1) ... 255s Processing triggers for libc-bin (2.39-0ubuntu9) ... 255s Processing triggers for man-db (2.12.1-2) ... 256s Processing triggers for initramfs-tools (0.142ubuntu30) ... 256s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 256s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 264s Reading package lists... 264s Building dependency tree... 264s Reading state information... 264s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 265s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 265s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 265s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 265s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 266s Reading package lists... 266s Reading package lists... 266s Building dependency tree... 266s Reading state information... 267s Calculating upgrade... 267s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 267s Reading package lists... 267s Building dependency tree... 267s Reading state information... 267s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 268s autopkgtest [13:54:35]: rebooting testbed after setup commands that affected boot 323s autopkgtest [13:55:30]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 340s autopkgtest [13:55:47]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools 347s Get:1 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (dsc) [2770 B] 347s Get:2 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (tar) [554 kB] 347s Get:3 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (diff) [42.7 kB] 348s gpgv: Signature made Sun Apr 7 00:31:08 2024 UTC 348s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 348s gpgv: issuer "steve.langasek@ubuntu.com" 348s gpgv: Can't check signature: No public key 348s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.4-5ubuntu8.dsc: no acceptable signature found 348s autopkgtest [13:55:55]: testing package multipath-tools version 0.9.4-5ubuntu8 349s autopkgtest [13:55:56]: build not needed 349s autopkgtest [13:55:56]: test kpartx-file-loopback: preparing testbed 355s Reading package lists... 355s Building dependency tree... 355s Reading state information... 355s Starting pkgProblemResolver with broken count: 0 355s Starting 2 pkgProblemResolver with broken count: 0 355s Done 355s The following additional packages will be installed: 355s liburing2 qemu-utils 355s Recommended packages: 355s qemu-block-extra 355s The following NEW packages will be installed: 355s autopkgtest-satdep liburing2 qemu-utils 355s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 355s Need to get 2330 kB/2331 kB of archives. 355s After this operation, 15.4 MB of additional disk space will be used. 355s Get:1 /tmp/autopkgtest.fhsRd0/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 356s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el liburing2 ppc64el 2.6-1 [26.9 kB] 356s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el qemu-utils ppc64el 1:8.2.2+ds-0ubuntu1 [2303 kB] 357s Fetched 2330 kB in 1s (3305 kB/s) 357s Selecting previously unselected package liburing2:ppc64el. 357s (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 ... 72765 files and directories currently installed.) 357s Preparing to unpack .../liburing2_2.6-1_ppc64el.deb ... 357s Unpacking liburing2:ppc64el (2.6-1) ... 357s Selecting previously unselected package qemu-utils. 357s Preparing to unpack .../qemu-utils_1%3a8.2.2+ds-0ubuntu1_ppc64el.deb ... 357s Unpacking qemu-utils (1:8.2.2+ds-0ubuntu1) ... 357s Selecting previously unselected package autopkgtest-satdep. 357s Preparing to unpack .../1-autopkgtest-satdep.deb ... 357s Unpacking autopkgtest-satdep (0) ... 357s Setting up liburing2:ppc64el (2.6-1) ... 357s Setting up qemu-utils (1:8.2.2+ds-0ubuntu1) ... 357s Setting up autopkgtest-satdep (0) ... 357s Processing triggers for man-db (2.12.1-2) ... 359s Processing triggers for libc-bin (2.39-0ubuntu9) ... 363s (Reading database ... 72785 files and directories currently installed.) 363s Removing autopkgtest-satdep (0) ... 365s autopkgtest [13:56:12]: test kpartx-file-loopback: [----------------------- 366s Formatting 'foo.img', fmt=raw size=20971520 367s Creating new GPT entries in memory. 367s Warning: The kernel is still using the old partition table. 367s The new table will be used at the next reboot or after you 367s run partprobe(8) or kpartx(8) 367s The operation has completed successfully. 367s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 367s standard_filename: OK 367s del devmap : loop0p1 367s No devices found 367s standard_filename_cleanup: OK 367s Formatting 'fou du FaFa.img', fmt=raw size=20971520 368s Creating new GPT entries in memory. 368s Warning: The kernel is still using the old partition table. 368s The new table will be used at the next reboot or after you 368s run partprobe(8) or kpartx(8) 368s The operation has completed successfully. 368s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 368s filename_with_spaces: OK 368s del devmap : loop0p1 368s No devices found 368s filename_with_spaces_cleanup: OK 368s autopkgtest [13:56:15]: test kpartx-file-loopback: -----------------------] 369s autopkgtest [13:56:16]: test kpartx-file-loopback: - - - - - - - - - - results - - - - - - - - - - 369s kpartx-file-loopback PASS 371s autopkgtest [13:56:18]: test tgtbasedmpaths: preparing testbed 542s autopkgtest [13:59:09]: testbed dpkg architecture: ppc64el 542s autopkgtest [13:59:09]: testbed apt version: 2.9.6 542s autopkgtest [13:59:09]: @@@@@@@@@@@@@@@@@@@@ test bed setup 543s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 544s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 544s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [393 kB] 544s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [66.5 kB] 544s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [5704 B] 544s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [116 kB] 544s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 544s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 544s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 544s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [531 kB] 544s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 544s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [2432 B] 544s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 546s Fetched 1263 kB in 1s (1409 kB/s) 546s Reading package lists... 548s Reading package lists... 549s Building dependency tree... 549s Reading state information... 549s Calculating upgrade... 549s The following packages will be upgraded: 549s dmsetup libdevmapper1.02.1 549s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 549s Need to get 273 kB of archives. 549s After this operation, 1024 B of additional disk space will be used. 549s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB] 549s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB] 550s Fetched 273 kB in 0s (661 kB/s) 550s (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 ... 72765 files and directories currently installed.) 550s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 550s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 550s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ... 550s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ... 550s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ... 550s Setting up dmsetup (2:1.02.196-1ubuntu1) ... 550s Processing triggers for libc-bin (2.39-0ubuntu9) ... 550s Processing triggers for man-db (2.12.1-2) ... 551s Processing triggers for initramfs-tools (0.142ubuntu30) ... 551s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 551s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 559s Reading package lists... 559s Building dependency tree... 559s Reading state information... 560s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 560s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 560s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 560s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 560s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 561s Reading package lists... 562s Reading package lists... 562s Building dependency tree... 562s Reading state information... 562s Calculating upgrade... 562s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 562s Reading package lists... 562s Building dependency tree... 562s Reading state information... 563s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 563s autopkgtest [13:59:30]: rebooting testbed after setup commands that affected boot 600s Reading package lists... 600s Building dependency tree... 600s Reading state information... 600s Starting pkgProblemResolver with broken count: 0 600s Starting 2 pkgProblemResolver with broken count: 0 600s Done 600s The following additional packages will be installed: 600s fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl 600s libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0 600s libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1 600s librdmacm1t64 lsscsi open-iscsi tgt 600s Suggested packages: 600s fio-examples gnuplot tgt-glusterfs tgt-rbd 600s Recommended packages: 600s finalrd 600s The following NEW packages will be installed: 600s autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0 600s libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 600s libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1 600s libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt 600s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 600s Need to get 10.7 MB/10.7 MB of archives. 600s After this operation, 49.0 MB of additional disk space will be used. 600s Get:1 /tmp/autopkgtest.fhsRd0/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [728 B] 601s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libopeniscsiusr ppc64el 2.1.10-1ubuntu1 [54.9 kB] 601s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libisns0t64 ppc64el 0.101-1 [117 kB] 601s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el open-iscsi ppc64el 2.1.10-1ubuntu1 [385 kB] 601s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el librdmacm1t64 ppc64el 52.0-2 [80.5 kB] 601s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB] 601s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el tgt ppc64el 1:1.0.85-1.2ubuntu1 [254 kB] 601s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfxdr0 ppc64el 11.1-4ubuntu1 [21.5 kB] 601s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libglusterfs0 ppc64el 11.1-4ubuntu1 [311 kB] 601s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfrpc0 ppc64el 11.1-4ubuntu1 [46.5 kB] 601s Get:11 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfapi0 ppc64el 11.1-4ubuntu1 [99.2 kB] 601s Get:12 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libnbd0 ppc64el 1.20.2-1 [97.4 kB] 601s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdaxctl1 ppc64el 77-2ubuntu2 [23.7 kB] 601s Get:14 http://ftpmaster.internal/ubuntu oracular/main ppc64el libndctl6 ppc64el 77-2ubuntu2 [74.9 kB] 601s Get:15 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB] 601s Get:16 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-3ubuntu1 [259 kB] 601s Get:17 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-3ubuntu1 [279 kB] 601s Get:18 http://ftpmaster.internal/ubuntu oracular/main ppc64el librados2 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [4026 kB] 601s Get:19 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB] 601s Get:20 http://ftpmaster.internal/ubuntu oracular/main ppc64el librbd1 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [3581 kB] 601s Get:21 http://ftpmaster.internal/ubuntu oracular/universe ppc64el fio ppc64el 3.37-1 [716 kB] 601s Get:22 http://ftpmaster.internal/ubuntu oracular/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB] 602s Preconfiguring packages ... 602s Fetched 10.7 MB in 1s (11.2 MB/s) 602s Selecting previously unselected package libopeniscsiusr. 602s (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 ... 72765 files and directories currently installed.) 602s Preparing to unpack .../00-libopeniscsiusr_2.1.10-1ubuntu1_ppc64el.deb ... 602s Unpacking libopeniscsiusr (2.1.10-1ubuntu1) ... 602s Selecting previously unselected package libisns0t64:ppc64el. 602s Preparing to unpack .../01-libisns0t64_0.101-1_ppc64el.deb ... 602s Unpacking libisns0t64:ppc64el (0.101-1) ... 602s Selecting previously unselected package open-iscsi. 602s Preparing to unpack .../02-open-iscsi_2.1.10-1ubuntu1_ppc64el.deb ... 602s Unpacking open-iscsi (2.1.10-1ubuntu1) ... 602s Selecting previously unselected package librdmacm1t64:ppc64el. 602s Preparing to unpack .../03-librdmacm1t64_52.0-2_ppc64el.deb ... 602s Unpacking librdmacm1t64:ppc64el (52.0-2) ... 602s Selecting previously unselected package libconfig-general-perl. 602s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ... 602s Unpacking libconfig-general-perl (2.65-2) ... 602s Selecting previously unselected package tgt. 602s Preparing to unpack .../05-tgt_1%3a1.0.85-1.2ubuntu1_ppc64el.deb ... 602s Unpacking tgt (1:1.0.85-1.2ubuntu1) ... 602s Selecting previously unselected package libgfxdr0:ppc64el. 602s Preparing to unpack .../06-libgfxdr0_11.1-4ubuntu1_ppc64el.deb ... 602s Unpacking libgfxdr0:ppc64el (11.1-4ubuntu1) ... 602s Selecting previously unselected package libglusterfs0:ppc64el. 602s Preparing to unpack .../07-libglusterfs0_11.1-4ubuntu1_ppc64el.deb ... 602s Unpacking libglusterfs0:ppc64el (11.1-4ubuntu1) ... 602s Selecting previously unselected package libgfrpc0:ppc64el. 602s Preparing to unpack .../08-libgfrpc0_11.1-4ubuntu1_ppc64el.deb ... 602s Unpacking libgfrpc0:ppc64el (11.1-4ubuntu1) ... 602s Selecting previously unselected package libgfapi0:ppc64el. 602s Preparing to unpack .../09-libgfapi0_11.1-4ubuntu1_ppc64el.deb ... 602s Unpacking libgfapi0:ppc64el (11.1-4ubuntu1) ... 602s Selecting previously unselected package libnbd0. 602s Preparing to unpack .../10-libnbd0_1.20.2-1_ppc64el.deb ... 602s Unpacking libnbd0 (1.20.2-1) ... 602s Selecting previously unselected package libdaxctl1:ppc64el. 602s Preparing to unpack .../11-libdaxctl1_77-2ubuntu2_ppc64el.deb ... 602s Unpacking libdaxctl1:ppc64el (77-2ubuntu2) ... 602s Selecting previously unselected package libndctl6:ppc64el. 602s Preparing to unpack .../12-libndctl6_77-2ubuntu2_ppc64el.deb ... 602s Unpacking libndctl6:ppc64el (77-2ubuntu2) ... 602s Selecting previously unselected package libpmem1:ppc64el. 602s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ... 602s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 603s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el. 603s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-3ubuntu1_ppc64el.deb ... 603s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ... 603s Selecting previously unselected package libboost-thread1.83.0:ppc64el. 603s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-3ubuntu1_ppc64el.deb ... 603s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ... 603s Selecting previously unselected package librados2. 603s Preparing to unpack .../16-librados2_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ... 603s Unpacking librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 603s Selecting previously unselected package libpmemobj1:ppc64el. 603s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ... 603s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 603s Selecting previously unselected package librbd1. 603s Preparing to unpack .../18-librbd1_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ... 603s Unpacking librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 603s Selecting previously unselected package fio. 603s Preparing to unpack .../19-fio_3.37-1_ppc64el.deb ... 603s Unpacking fio (3.37-1) ... 603s Selecting previously unselected package lsscsi. 603s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ... 603s Unpacking lsscsi (0.32-1build1) ... 603s Selecting previously unselected package autopkgtest-satdep. 603s Preparing to unpack .../21-2-autopkgtest-satdep.deb ... 603s Unpacking autopkgtest-satdep (0) ... 603s Setting up libconfig-general-perl (2.65-2) ... 603s Setting up libisns0t64:ppc64el (0.101-1) ... 603s Setting up libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ... 603s Setting up libnbd0 (1.20.2-1) ... 603s Setting up libopeniscsiusr (2.1.10-1ubuntu1) ... 603s Setting up libglusterfs0:ppc64el (11.1-4ubuntu1) ... 603s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ... 603s Setting up lsscsi (0.32-1build1) ... 603s Setting up libdaxctl1:ppc64el (77-2ubuntu2) ... 603s Setting up libndctl6:ppc64el (77-2ubuntu2) ... 603s Setting up librdmacm1t64:ppc64el (52.0-2) ... 603s Setting up tgt (1:1.0.85-1.2ubuntu1) ... 603s Created symlink '/etc/systemd/system/multi-user.target.wants/tgt.service' → '/usr/lib/systemd/system/tgt.service'. 604s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 604s Setting up libgfxdr0:ppc64el (11.1-4ubuntu1) ... 604s Setting up librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 604s Setting up open-iscsi (2.1.10-1ubuntu1) ... 605s Created symlink '/etc/systemd/system/sockets.target.wants/iscsid.socket' → '/usr/lib/systemd/system/iscsid.socket'. 605s Created symlink '/etc/systemd/system/iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 605s Created symlink '/etc/systemd/system/sysinit.target.wants/open-iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 605s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 605s Setting up librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ... 605s Setting up libgfrpc0:ppc64el (11.1-4ubuntu1) ... 605s Setting up libgfapi0:ppc64el (11.1-4ubuntu1) ... 605s Setting up fio (3.37-1) ... 606s Setting up autopkgtest-satdep (0) ... 606s Processing triggers for man-db (2.12.1-2) ... 608s Processing triggers for initramfs-tools (0.142ubuntu30) ... 608s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 608s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 617s Processing triggers for libc-bin (2.39-0ubuntu9) ... 620s (Reading database ... 73006 files and directories currently installed.) 620s Removing autopkgtest-satdep (0) ... 622s autopkgtest [14:00:29]: test tgtbasedmpaths: [----------------------- 622s + targetname=iqn.2016-11.foo.com:target.iscsi 622s + pwd 622s + cwd=/tmp/autopkgtest.fhsRd0/build.T86/src 622s + testdir=/mnt/tgtmpathtest 622s + localhost=127.0.0.1 622s + portal=127.0.0.1:3260 622s + maxpaths=4 622s + backfn=backingfile 622s + expectwwid=60000000000000000e00000000010001 622s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001 622s + bglog=/tmp/autopkgtest.fhsRd0/tgtbasedmpaths-artifacts/test-background.log 622s + fioprep=/tmp/autopkgtest.fhsRd0/tgtbasedmpaths-artifacts/path-change-prep.fio 622s + fiovrfy=/tmp/autopkgtest.fhsRd0/tgtbasedmpaths-artifacts/path-change-check.fio 622s + service tgt restart 622s + truncate --size 100M backingfile 622s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi 622s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL 622s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.fhsRd0/build.T86/src/backingfile 622s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1 623s + echo login #1 623s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login 623s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi 623s login #1 623s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 623s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 623s + seq 2 4 623s + echo extra login #2 623s + iscsiadm --mode session -r 1 --op new 623s extra login #2 623s + echo extra login #3 623s + iscsiadm --mode session -r 1 --op new 623s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 623s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 623s extra login #3 623s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 623s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 623s extra login #4 623s + echo extra login #4 623s + iscsiadm --mode session -r 1 --op new 623s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 623s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 623s + udevadm settle 623s + sleep 5 628s + echo Status after initial setup 628s + tgtadm --lld iscsi --mode target --op show 628s Status after initial setup 628s + tgtadm --lld iscsi --op show --mode conn --tid 1 628s Target 1: iqn.2016-11.foo.com:target.iscsi 628s System information: 628s Driver: iscsi 628s State: ready 628s I_T nexus information: 628s I_T nexus: 1 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 alias: autopkgtest 628s Connection: 0 628s IP Address: 127.0.0.1 628s I_T nexus: 2 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 alias: autopkgtest 628s Connection: 0 628s IP Address: 127.0.0.1 628s I_T nexus: 3 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 alias: autopkgtest 628s Connection: 0 628s IP Address: 127.0.0.1 628s I_T nexus: 4 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 alias: autopkgtest 628s Connection: 0 628s IP Address: 127.0.0.1 628s LUN information: 628s LUN: 0 628s Type: controller 628s SCSI ID: IET 00010000 628s SCSI SN: beaf10 628s Size: 0 MB, Block size: 1 628s Online: Yes 628s Removable media: No 628s Prevent removal: No 628s Readonly: No 628s SWP: No 628s Thin-provisioning: No 628s Backing store type: null 628s Backing store path: None 628s Backing store flags: 628s LUN: 1 628s Type: disk 628s SCSI ID: IET 00010001 628s SCSI SN: beaf11 628s Size: 105 MB, Block size: 512 628s Online: Yes 628s Removable media: No 628s Prevent removal: No 628s Readonly: No 628s SWP: No 628s Thin-provisioning: No 628s Backing store type: rdwr 628s Backing store path: /tmp/autopkgtest.fhsRd0/build.T86/src/backingfile 628s Backing store flags: 628s Account information: 628s ACL information: 628s ALL 628s + iscsiadm --mode session -P 1 628s Session: 4 628s Connection: 0 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s IP Address: 127.0.0.1 628s Session: 3 628s Connection: 0 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s IP Address: 127.0.0.1 628s Session: 2 628s Connection: 0 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s IP Address: 127.0.0.1 628s Session: 1 628s Connection: 0 628s Initiator: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s IP Address: 127.0.0.1 628s + lsscsi -liv 628s Target: iqn.2016-11.foo.com:target.iscsi (non-flash) 628s Current Portal: 127.0.0.1:3260,1 628s Persistent Portal: 127.0.0.1:3260,1 628s ********** 628s Interface: 628s ********** 628s Iface Name: default 628s Iface Transport: tcp 628s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s Iface IPaddress: 127.0.0.1 628s Iface HWaddress: default 628s Iface Netdev: default 628s SID: 1 628s iSCSI Connection State: LOGGED IN 628s iSCSI Session State: LOGGED_IN 628s Internal iscsid Session State: NO CHANGE 628s 628s ********** 628s Interface: 628s ********** 628s Iface Name: default 628s Iface Transport: tcp 628s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s Iface IPaddress: 127.0.0.1 628s Iface HWaddress: default 628s Iface Netdev: default 628s SID: 2 628s iSCSI Connection State: LOGGED IN 628s iSCSI Session State: LOGGED_IN 628s Internal iscsid Session State: NO CHANGE 628s 628s ********** 628s Interface: 628s ********** 628s Iface Name: default 628s Iface Transport: tcp 628s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s Iface IPaddress: 127.0.0.1 628s Iface HWaddress: default 628s Iface Netdev: default 628s SID: 3 628s iSCSI Connection State: LOGGED IN 628s iSCSI Session State: LOGGED_IN 628s Internal iscsid Session State: NO CHANGE 628s 628s ********** 628s Interface: 628s ********** 628s Iface Name: default 628s Iface Transport: tcp 628s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:cdb737a32b36 628s Iface IPaddress: 127.0.0.1 628s Iface HWaddress: default 628s Iface Netdev: default 628s SID: 4 628s iSCSI Connection State: LOGGED IN 628s iSCSI Session State: LOGGED_IN 628s Internal iscsid Session State: NO CHANGE 628s list_ndevices: scandir: /sys/class/nvme/: No such file or directory 628s + multipath -v3 -ll 628s 51.749550 | set open fds limit to 1073741816/1073741816 628s 51.749598 | loading /lib/multipath/libchecktur.so checker 628s 51.749671 | checker tur: message table size = 3 628s 51.749679 | loading /lib/multipath/libprioconst.so prioritizer 628s 51.749766 | _init_foreign: foreign library "nvme" is not enabled 628s 51.754508 | vda: device node name blacklisted 628s 51.754772 | sda: size = 204800 628s 51.754900 | sda: vendor = IET 628s 51.754921 | sda: product = VIRTUAL-DISK 628s 51.754941 | sda: rev = 0001 628s 51.755618 | sda: h:b:t:l = 0:0:0:1 628s 51.755949 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.755954 | sda: uid_attribute = ID_SERIAL (setting: multipath internal) 628s 51.755956 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 628s 51.756133 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.756141 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.756155 | sda: serial = beaf11 628s 51.756158 | sda: detect_checker = yes (setting: multipath internal) 628s 51.756329 | sda: path_checker = tur (setting: multipath internal) 628s 51.756334 | sda: checker timeout = 30 s (setting: kernel sysfs) 628s 51.756396 | sda: tur state = up 628s 51.756519 | sdb: size = 204800 628s 51.756647 | sdb: vendor = IET 628s 51.756668 | sdb: product = VIRTUAL-DISK 628s 51.756688 | sdb: rev = 0001 628s 51.757342 | sdb: h:b:t:l = 1:0:0:1 628s 51.757674 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.757677 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal) 628s 51.757679 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 628s 51.757819 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.757822 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.757834 | sdb: serial = beaf11 628s 51.757837 | sdb: detect_checker = yes (setting: multipath internal) 628s 51.757963 | sdb: path_checker = tur (setting: multipath internal) 628s 51.757967 | sdb: checker timeout = 30 s (setting: kernel sysfs) 628s 51.758033 | sdb: tur state = up 628s 51.758151 | sdc: size = 204800 628s 51.758278 | sdc: vendor = IET 628s 51.758299 | sdc: product = VIRTUAL-DISK 628s 51.758319 | sdc: rev = 0001 628s 51.758978 | sdc: h:b:t:l = 2:0:0:1 628s 51.759327 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.759332 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal) 628s 51.759334 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 628s [0:0:0:0] storage IET Controller 0001 - - 628s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/0:0:0:0 [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:0] 628s [0:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sda 360000000000000000e00000000010001 628s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/0:0:0:1 [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:1] 628s [1:0:0:0] storage IET Controller 0001 - - 628s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/1:0:0:0 [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:0] 628s [1:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdb 360000000000000000e00000000010001 628s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/1:0:0:1 [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:1] 628s [2:0:0:0] storage IET Controller 0001 - - 628s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/2:0:0:0 [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:0] 628s [2:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdc 360000000000000000e00000000010001 628s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/2:0:0:1 [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:1] 628s [3:0:0:0] storage IET Controller 0001 - - 628s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/3:0:0:0 [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:0] 628s [3:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdd 360000000000000000e00000000010001 628s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 628s dir: /sys/bus/scsi/devices/3:0:0:1 [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:1] 628s NVMe module may not be loaded 628s 51.759473 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.759477 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.759489 | sdc: serial = beaf11 628s 51.759491 | sdc: detect_checker = yes (setting: multipath internal) 628s 51.759619 | sdc: path_checker = tur (setting: multipath internal) 628s 51.759624 | sdc: checker timeout = 30 s (setting: kernel sysfs) 628s 51.759673 | sdc: tur state = up 628s 51.759796 | sdd: size = 204800 628s 51.759924 | sdd: vendor = IET 628s 51.759945 | sdd: product = VIRTUAL-DISK 628s 51.759965 | sdd: rev = 0001 628s 51.760677 | sdd: h:b:t:l = 3:0:0:1 628s 51.761017 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.761021 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal) 628s 51.761023 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 628s 51.761161 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.761165 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.761177 | sdd: serial = beaf11 628s 51.761179 | sdd: detect_checker = yes (setting: multipath internal) 628s 51.761281 | sdd: path_checker = tur (setting: multipath internal) 628s 51.761286 | sdd: checker timeout = 30 s (setting: kernel sysfs) 628s 51.761339 | sdd: tur state = up 628s 51.761429 | loop0: device node name blacklisted 628s 51.761513 | loop1: device node name blacklisted 628s 51.761594 | loop2: device node name blacklisted 628s 51.761674 | loop3: device node name blacklisted 628s 51.761754 | loop4: device node name blacklisted 628s 51.761834 | loop5: device node name blacklisted 628s 51.761914 | loop6: device node name blacklisted 628s 51.761993 | loop7: device node name blacklisted 628s 51.762076 | dm-0: device node name blacklisted 628s 51.762964 | multipath-tools v0.9.4 (12/19, 2022) 628s 51.762974 | libdevmapper version 1.02.196 628s 51.763107 | kernel device mapper v4.48.0 628s 51.763118 | DM multipath kernel driver v1.14.0 628s 51.763217 | sda: size = 204800 628s 51.763222 | sda: vendor = IET 628s 51.763224 | sda: product = VIRTUAL-DISK 628s 51.763227 | sda: rev = 0001 628s 51.763833 | sda: h:b:t:l = 0:0:0:1 628s 51.763942 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.763960 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.763963 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.763974 | sda: serial = beaf11 628s 51.764050 | sda: tur state = up 628s 51.764059 | sda: uid = 360000000000000000e00000000010001 (udev) 628s 51.764062 | sda: detect_prio = yes (setting: multipath internal) 628s 51.764065 | sda: prio = const (setting: multipath internal) 628s 51.764067 | sda: prio args = "" (setting: multipath internal) 628s 51.764069 | sda: const prio = 1 628s 51.764090 | sdb: size = 204800 628s 51.764095 | sdb: vendor = IET 628s 51.764097 | sdb: product = VIRTUAL-DISK 628s 51.764099 | sdb: rev = 0001 628s 51.764691 | sdb: h:b:t:l = 1:0:0:1 628s 51.764798 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.764817 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.764819 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.764830 | sdb: serial = beaf11 628s 51.764895 | sdb: tur state = up 628s 51.764901 | sdb: uid = 360000000000000000e00000000010001 (udev) 628s 51.764903 | sdb: detect_prio = yes (setting: multipath internal) 628s 51.764906 | sdb: prio = const (setting: multipath internal) 628s 51.764908 | sdb: prio args = "" (setting: multipath internal) 628s 51.764910 | sdb: const prio = 1 628s 51.764930 | sdc: size = 204800 628s 51.764935 | sdc: vendor = IET 628s 51.764937 | sdc: product = VIRTUAL-DISK 628s 51.764940 | sdc: rev = 0001 628s 51.765543 | sdc: h:b:t:l = 2:0:0:1 628s 51.765653 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.765673 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.765675 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.765686 | sdc: serial = beaf11 628s 51.765744 | sdc: tur state = up 628s 51.765749 | sdc: uid = 360000000000000000e00000000010001 (udev) 628s 51.765751 | sdc: detect_prio = yes (setting: multipath internal) 628s 51.765754 | sdc: prio = const (setting: multipath internal) 628s 51.765756 | sdc: prio args = "" (setting: multipath internal) 628s 51.765758 | sdc: const prio = 1 628s 51.765780 | sdd: size = 204800 628s 51.765785 | sdd: vendor = IET 628s 51.765787 | sdd: product = VIRTUAL-DISK 628s 51.765790 | sdd: rev = 0001 628s 51.766404 | sdd: h:b:t:l = 3:0:0:1 628s ===== paths list ===== 628s uuid hcil dev dev_t pri dm_st chk_st vend/prod/rev dev_st 628s 0:0:0:1 sda 8:0 -1 undef undef IET,VIRTUAL-DISK unknown 628s 1:0:0:1 sdb 8:16 -1 undef undef IET,VIRTUAL-DISK unknown 628s 2:0:0:1 sdc 8:32 -1 undef undef IET,VIRTUAL-DISK unknown 628s 3:0:0:1 sdd 8:48 -1 undef undef IET,VIRTUAL-DISK unknown 628s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 628s size=100M features='0' hwhandler='0' wp=rw 628s |-+- policy='service-time 0' prio=1 status=active 628s | `- 0:0:0:1 sda 8:0 active ready running 628s |-+- policy='service-time 0' prio=1 status=enabled 628s | `- 1:0:0:1 sdb 8:16 active ready running 628s |-+- policy='service-time 0' prio=1 status=enabled 628s | `- 2:0:0:1 sdc 8:32 active ready running 628s `-+- policy='service-time 0' prio=1 status=enabled 628s `- 3:0:0:1 sdd 8:48 active ready running 628s 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 628s Test WWN should now point to DM 628s 51.766516 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 628s 51.766536 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 628s 51.766539 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 628s 51.766550 | sdd: serial = beaf11 628s 51.766656 | sdd: tur state = up 628s 51.766662 | sdd: uid = 360000000000000000e00000000010001 (udev) 628s 51.766664 | sdd: detect_prio = yes (setting: multipath internal) 628s 51.766666 | sdd: prio = const (setting: multipath internal) 628s 51.766668 | sdd: prio args = "" (setting: multipath internal) 628s 51.766671 | sdd: const prio = 1 628s 51.767369 | unloading tur checker 628s 51.767403 | unloading const prioritizer 628s + dmsetup table 628s + echo Test WWN should now point to DM 628s + readlink /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 628s + grep dm 628s autopkgtest [14:00:35]: test tgtbasedmpaths: -----------------------] 629s autopkgtest [14:00:36]: test tgtbasedmpaths: - - - - - - - - - - results - - - - - - - - - - 629s tgtbasedmpaths FAIL non-zero exit status 1 629s autopkgtest [14:00:36]: @@@@@@@@@@@@@@@@@@@@ summary 629s kpartx-file-loopback PASS 629s tgtbasedmpaths FAIL non-zero exit status 1 634s nova [W] Using flock in prodstack6-ppc64el 634s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240723-135002-juju-7f2275-prod-proposed-migration-environment-3-8b964fc9-4021-4bc9-b711-0d001c94a2c6 from image adt/ubuntu-oracular-ppc64el-server-20240723.img (UUID e9495f50-4e55-4015-9ab7-0401c7da2b9f)... 634s nova [W] Using flock in prodstack6-ppc64el 634s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240723-135002-juju-7f2275-prod-proposed-migration-environment-3-8b964fc9-4021-4bc9-b711-0d001c94a2c6 from image adt/ubuntu-oracular-ppc64el-server-20240723.img (UUID e9495f50-4e55-4015-9ab7-0401c7da2b9f)...