0s autopkgtest [16:06:43]: starting date and time: 2024-07-16 16:06:43+0000
  0s autopkgtest [16:06:43]: git checkout: fd3bed09 nova: allow more retries for quota issues
  0s autopkgtest [16:06:43]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.u_s1mptl/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-2@bos01-ppc64el-18.secgroup --name adt-oracular-ppc64el-multipath-tools-20240716-160642-juju-7f2275-prod-proposed-migration-environment-2-597ef4cd-6bcc-449a-a5ce-feeacabf8ba6 --image adt/ubuntu-oracular-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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://us.ports.ubuntu.com/ubuntu-ports/
105s autopkgtest [16:08:28]: testbed dpkg architecture: ppc64el
105s autopkgtest [16:08:28]: testbed apt version: 2.9.6
105s autopkgtest [16:08:28]: @@@@@@@@@@@@@@@@@@@@ test bed setup
106s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB]
106s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [41.1 kB]
106s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B]
106s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B]
106s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [332 kB]
106s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [68.4 kB]
106s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B]
106s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B]
106s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B]
106s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [373 kB]
107s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B]
107s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1448 B]
107s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B]
108s Fetched 966 kB in 1s (977 kB/s)
108s Reading package lists...
111s Reading package lists...
111s Building dependency tree...
111s Reading state information...
111s Calculating upgrade...
111s The following packages will be upgraded:
111s   dmsetup libdevmapper1.02.1 libnftnl11 nano
112s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
112s Need to get 679 kB of archives.
112s After this operation, 7168 B of additional disk space will be used.
112s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB]
112s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB]
112s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libnftnl11 ppc64el 1.2.7-1 [77.3 kB]
112s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el nano ppc64el 8.1-1 [329 kB]
112s Fetched 679 kB in 1s (1081 kB/s)
113s (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.)
113s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ...
113s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ...
113s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ...
113s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ...
113s Preparing to unpack .../libnftnl11_1.2.7-1_ppc64el.deb ...
113s Unpacking libnftnl11:ppc64el (1.2.7-1) over (1.2.6-2build1) ...
113s Preparing to unpack .../nano_8.1-1_ppc64el.deb ...
113s Unpacking nano (8.1-1) over (8.0-1) ...
113s Setting up libnftnl11:ppc64el (1.2.7-1) ...
113s Setting up nano (8.1-1) ...
113s Installing new version of config file /etc/nanorc ...
113s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ...
113s Setting up dmsetup (2:1.02.196-1ubuntu1) ...
113s Processing triggers for initramfs-tools (0.142ubuntu30) ...
113s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic
113s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
121s Processing triggers for libc-bin (2.39-0ubuntu9) ...
121s Processing triggers for man-db (2.12.1-2) ...
122s Processing triggers for install-info (7.1-3build2) ...
122s Reading package lists...
123s Building dependency tree...
123s Reading state information...
123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
123s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease
123s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease
123s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease
123s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease
124s Reading package lists...
124s Reading package lists...
125s Building dependency tree...
125s Reading state information...
125s Calculating upgrade...
125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
125s Reading package lists...
125s Building dependency tree...
125s Reading state information...
125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
125s autopkgtest [16:08:48]: rebooting testbed after setup commands that affected boot
159s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
173s autopkgtest [16:09:36]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024
175s autopkgtest [16:09:38]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools
179s Get:1 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (dsc) [2770 B]
179s Get:2 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (tar) [554 kB]
179s Get:3 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.4-5ubuntu8 (diff) [42.7 kB]
179s gpgv: Signature made Sun Apr  7 00:31:08 2024 UTC
179s gpgv:                using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D
179s gpgv:                issuer "steve.langasek@ubuntu.com"
179s gpgv: Can't check signature: No public key
179s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.4-5ubuntu8.dsc: no acceptable signature found
179s autopkgtest [16:09:42]: testing package multipath-tools version 0.9.4-5ubuntu8
181s autopkgtest [16:09:44]: build not needed
186s autopkgtest [16:09:49]: test kpartx-file-loopback: preparing testbed
188s Reading package lists...
188s Building dependency tree...
188s Reading state information...
188s Starting pkgProblemResolver with broken count: 0
188s Starting 2 pkgProblemResolver with broken count: 0
188s Done
189s The following additional packages will be installed:
189s   liburing2 qemu-utils
189s Recommended packages:
189s   qemu-block-extra
189s The following NEW packages will be installed:
189s   autopkgtest-satdep liburing2 qemu-utils
189s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded.
189s Need to get 2330 kB/2331 kB of archives.
189s After this operation, 15.4 MB of additional disk space will be used.
189s Get:1 /tmp/autopkgtest.qsbjoJ/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B]
189s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el liburing2 ppc64el 2.6-1 [26.9 kB]
189s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el qemu-utils ppc64el 1:8.2.2+ds-0ubuntu1 [2303 kB]
190s Fetched 2330 kB in 1s (1886 kB/s)
190s Selecting previously unselected package liburing2:ppc64el.
191s (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.)
191s Preparing to unpack .../liburing2_2.6-1_ppc64el.deb ...
191s Unpacking liburing2:ppc64el (2.6-1) ...
191s Selecting previously unselected package qemu-utils.
191s Preparing to unpack .../qemu-utils_1%3a8.2.2+ds-0ubuntu1_ppc64el.deb ...
191s Unpacking qemu-utils (1:8.2.2+ds-0ubuntu1) ...
191s Selecting previously unselected package autopkgtest-satdep.
191s Preparing to unpack .../1-autopkgtest-satdep.deb ...
191s Unpacking autopkgtest-satdep (0) ...
191s Setting up liburing2:ppc64el (2.6-1) ...
191s Setting up qemu-utils (1:8.2.2+ds-0ubuntu1) ...
191s Setting up autopkgtest-satdep (0) ...
191s Processing triggers for man-db (2.12.1-2) ...
192s Processing triggers for libc-bin (2.39-0ubuntu9) ...
194s (Reading database ... 72784 files and directories currently installed.)
194s Removing autopkgtest-satdep (0) ...
195s autopkgtest [16:09:58]: test kpartx-file-loopback: [-----------------------
195s Formatting 'foo.img', fmt=raw size=20971520
196s Creating new GPT entries in memory.
196s Warning: The kernel is still using the old partition table.
196s The new table will be used at the next reboot or after you
196s run partprobe(8) or kpartx(8)
196s The operation has completed successfully.
196s add map loop0p1 (252:0): 0 38879 linear 7:0 2048
196s standard_filename: OK
196s del devmap : loop0p1
196s No devices found
196s standard_filename_cleanup: OK
196s Formatting 'fou du FaFa.img', fmt=raw size=20971520
197s Creating new GPT entries in memory.
197s Warning: The kernel is still using the old partition table.
197s The new table will be used at the next reboot or after you
197s run partprobe(8) or kpartx(8)
197s The operation has completed successfully.
197s add map loop0p1 (252:0): 0 38879 linear 7:0 2048
197s filename_with_spaces: OK
197s del devmap : loop0p1
197s No devices found
197s filename_with_spaces_cleanup: OK
198s autopkgtest [16:10:01]: test kpartx-file-loopback: -----------------------]
198s autopkgtest [16:10:01]: test kpartx-file-loopback:  - - - - - - - - - - results - - - - - - - - - -
198s kpartx-file-loopback PASS
199s autopkgtest [16:10:02]: test tgtbasedmpaths: preparing testbed
322s autopkgtest [16:12:05]: testbed dpkg architecture: ppc64el
322s autopkgtest [16:12:05]: testbed apt version: 2.9.6
322s autopkgtest [16:12:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup
323s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB]
324s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B]
324s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [332 kB]
324s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [41.1 kB]
324s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B]
324s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [68.4 kB]
324s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B]
324s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B]
324s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B]
324s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [373 kB]
324s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B]
324s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1448 B]
324s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B]
326s Fetched 966 kB in 1s (1122 kB/s)
326s Reading package lists...
328s Reading package lists...
329s Building dependency tree...
329s Reading state information...
329s Calculating upgrade...
329s The following packages will be upgraded:
329s   dmsetup libdevmapper1.02.1 libnftnl11 nano
329s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
329s Need to get 679 kB of archives.
329s After this operation, 7168 B of additional disk space will be used.
329s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdevmapper1.02.1 ppc64el 2:1.02.196-1ubuntu1 [181 kB]
330s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el dmsetup ppc64el 2:1.02.196-1ubuntu1 [91.6 kB]
330s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libnftnl11 ppc64el 1.2.7-1 [77.3 kB]
330s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el nano ppc64el 8.1-1 [329 kB]
330s Fetched 679 kB in 1s (1074 kB/s)
330s (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.)
330s Preparing to unpack .../libdevmapper1.02.1_2%3a1.02.196-1ubuntu1_ppc64el.deb ...
330s Unpacking libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ...
330s Preparing to unpack .../dmsetup_2%3a1.02.196-1ubuntu1_ppc64el.deb ...
330s Unpacking dmsetup (2:1.02.196-1ubuntu1) over (2:1.02.185-3ubuntu4) ...
330s Preparing to unpack .../libnftnl11_1.2.7-1_ppc64el.deb ...
330s Unpacking libnftnl11:ppc64el (1.2.7-1) over (1.2.6-2build1) ...
330s Preparing to unpack .../nano_8.1-1_ppc64el.deb ...
330s Unpacking nano (8.1-1) over (8.0-1) ...
330s Setting up libnftnl11:ppc64el (1.2.7-1) ...
330s Setting up nano (8.1-1) ...
330s Installing new version of config file /etc/nanorc ...
330s Setting up libdevmapper1.02.1:ppc64el (2:1.02.196-1ubuntu1) ...
330s Setting up dmsetup (2:1.02.196-1ubuntu1) ...
330s Processing triggers for initramfs-tools (0.142ubuntu30) ...
330s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic
330s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
339s Processing triggers for libc-bin (2.39-0ubuntu9) ...
339s Processing triggers for man-db (2.12.1-2) ...
340s Processing triggers for install-info (7.1-3build2) ...
341s Reading package lists...
341s Building dependency tree...
341s Reading state information...
341s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
342s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease
342s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease
342s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease
342s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease
343s Reading package lists...
343s Reading package lists...
343s Building dependency tree...
343s Reading state information...
344s Calculating upgrade...
344s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
344s Reading package lists...
344s Building dependency tree...
344s Reading state information...
344s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
344s autopkgtest [16:12:27]: rebooting testbed after setup commands that affected boot
348s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
381s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
396s Reading package lists...
397s Building dependency tree...
397s Reading state information...
397s Starting pkgProblemResolver with broken count: 0
397s Starting 2 pkgProblemResolver with broken count: 0
397s Done
397s The following additional packages will be installed:
397s   fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl
397s   libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0
397s   libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1
397s   librdmacm1t64 lsscsi open-iscsi tgt
397s Suggested packages:
397s   fio-examples gnuplot tgt-glusterfs tgt-rbd
397s Recommended packages:
397s   finalrd
398s The following NEW packages will be installed:
398s   autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0
398s   libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0
398s   libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1
398s   libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt
398s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded.
398s Need to get 10.7 MB/10.7 MB of archives.
398s After this operation, 49.0 MB of additional disk space will be used.
398s Get:1 /tmp/autopkgtest.qsbjoJ/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [736 B]
398s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libopeniscsiusr ppc64el 2.1.10-1ubuntu1 [54.9 kB]
398s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libisns0t64 ppc64el 0.101-1 [117 kB]
398s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el open-iscsi ppc64el 2.1.10-1ubuntu1 [385 kB]
398s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el librdmacm1t64 ppc64el 52.0-2 [80.5 kB]
398s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB]
398s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el tgt ppc64el 1:1.0.85-1.2ubuntu1 [254 kB]
398s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfxdr0 ppc64el 11.1-4ubuntu1 [21.5 kB]
398s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libglusterfs0 ppc64el 11.1-4ubuntu1 [311 kB]
398s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfrpc0 ppc64el 11.1-4ubuntu1 [46.5 kB]
398s Get:11 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfapi0 ppc64el 11.1-4ubuntu1 [99.2 kB]
398s Get:12 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libnbd0 ppc64el 1.20.2-1 [97.4 kB]
398s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdaxctl1 ppc64el 77-2ubuntu2 [23.7 kB]
398s Get:14 http://ftpmaster.internal/ubuntu oracular/main ppc64el libndctl6 ppc64el 77-2ubuntu2 [74.9 kB]
398s Get:15 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB]
398s Get:16 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-3ubuntu1 [259 kB]
398s Get:17 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-3ubuntu1 [279 kB]
399s Get:18 http://ftpmaster.internal/ubuntu oracular/main ppc64el librados2 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [4026 kB]
399s Get:19 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB]
399s Get:20 http://ftpmaster.internal/ubuntu oracular/main ppc64el librbd1 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu7 [3581 kB]
400s Get:21 http://ftpmaster.internal/ubuntu oracular/universe ppc64el fio ppc64el 3.37-1 [716 kB]
400s Get:22 http://ftpmaster.internal/ubuntu oracular/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB]
400s Preconfiguring packages ...
400s Fetched 10.7 MB in 2s (4908 kB/s)
400s Selecting previously unselected package libopeniscsiusr.
400s (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.)
400s Preparing to unpack .../00-libopeniscsiusr_2.1.10-1ubuntu1_ppc64el.deb ...
400s Unpacking libopeniscsiusr (2.1.10-1ubuntu1) ...
400s Selecting previously unselected package libisns0t64:ppc64el.
400s Preparing to unpack .../01-libisns0t64_0.101-1_ppc64el.deb ...
400s Unpacking libisns0t64:ppc64el (0.101-1) ...
400s Selecting previously unselected package open-iscsi.
400s Preparing to unpack .../02-open-iscsi_2.1.10-1ubuntu1_ppc64el.deb ...
400s Unpacking open-iscsi (2.1.10-1ubuntu1) ...
401s Selecting previously unselected package librdmacm1t64:ppc64el.
401s Preparing to unpack .../03-librdmacm1t64_52.0-2_ppc64el.deb ...
401s Unpacking librdmacm1t64:ppc64el (52.0-2) ...
401s Selecting previously unselected package libconfig-general-perl.
401s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ...
401s Unpacking libconfig-general-perl (2.65-2) ...
401s Selecting previously unselected package tgt.
401s Preparing to unpack .../05-tgt_1%3a1.0.85-1.2ubuntu1_ppc64el.deb ...
401s Unpacking tgt (1:1.0.85-1.2ubuntu1) ...
401s Selecting previously unselected package libgfxdr0:ppc64el.
401s Preparing to unpack .../06-libgfxdr0_11.1-4ubuntu1_ppc64el.deb ...
401s Unpacking libgfxdr0:ppc64el (11.1-4ubuntu1) ...
401s Selecting previously unselected package libglusterfs0:ppc64el.
401s Preparing to unpack .../07-libglusterfs0_11.1-4ubuntu1_ppc64el.deb ...
401s Unpacking libglusterfs0:ppc64el (11.1-4ubuntu1) ...
401s Selecting previously unselected package libgfrpc0:ppc64el.
401s Preparing to unpack .../08-libgfrpc0_11.1-4ubuntu1_ppc64el.deb ...
401s Unpacking libgfrpc0:ppc64el (11.1-4ubuntu1) ...
401s Selecting previously unselected package libgfapi0:ppc64el.
401s Preparing to unpack .../09-libgfapi0_11.1-4ubuntu1_ppc64el.deb ...
401s Unpacking libgfapi0:ppc64el (11.1-4ubuntu1) ...
401s Selecting previously unselected package libnbd0.
401s Preparing to unpack .../10-libnbd0_1.20.2-1_ppc64el.deb ...
401s Unpacking libnbd0 (1.20.2-1) ...
401s Selecting previously unselected package libdaxctl1:ppc64el.
401s Preparing to unpack .../11-libdaxctl1_77-2ubuntu2_ppc64el.deb ...
401s Unpacking libdaxctl1:ppc64el (77-2ubuntu2) ...
401s Selecting previously unselected package libndctl6:ppc64el.
401s Preparing to unpack .../12-libndctl6_77-2ubuntu2_ppc64el.deb ...
401s Unpacking libndctl6:ppc64el (77-2ubuntu2) ...
401s Selecting previously unselected package libpmem1:ppc64el.
401s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ...
401s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ...
401s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el.
401s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-3ubuntu1_ppc64el.deb ...
401s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ...
401s Selecting previously unselected package libboost-thread1.83.0:ppc64el.
401s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-3ubuntu1_ppc64el.deb ...
401s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ...
401s Selecting previously unselected package librados2.
401s Preparing to unpack .../16-librados2_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ...
401s Unpacking librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ...
401s Selecting previously unselected package libpmemobj1:ppc64el.
401s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ...
401s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ...
401s Selecting previously unselected package librbd1.
401s Preparing to unpack .../18-librbd1_19.2.0~git20240301.4c76c50-0ubuntu7_ppc64el.deb ...
401s Unpacking librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ...
401s Selecting previously unselected package fio.
401s Preparing to unpack .../19-fio_3.37-1_ppc64el.deb ...
401s Unpacking fio (3.37-1) ...
401s Selecting previously unselected package lsscsi.
401s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ...
401s Unpacking lsscsi (0.32-1build1) ...
401s Selecting previously unselected package autopkgtest-satdep.
401s Preparing to unpack .../21-2-autopkgtest-satdep.deb ...
401s Unpacking autopkgtest-satdep (0) ...
401s Setting up libconfig-general-perl (2.65-2) ...
401s Setting up libisns0t64:ppc64el (0.101-1) ...
401s Setting up libboost-thread1.83.0:ppc64el (1.83.0-3ubuntu1) ...
401s Setting up libnbd0 (1.20.2-1) ...
401s Setting up libopeniscsiusr (2.1.10-1ubuntu1) ...
401s Setting up libglusterfs0:ppc64el (11.1-4ubuntu1) ...
401s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-3ubuntu1) ...
401s Setting up lsscsi (0.32-1build1) ...
401s Setting up libdaxctl1:ppc64el (77-2ubuntu2) ...
401s Setting up libndctl6:ppc64el (77-2ubuntu2) ...
401s Setting up librdmacm1t64:ppc64el (52.0-2) ...
401s Setting up tgt (1:1.0.85-1.2ubuntu1) ...
402s Created symlink '/etc/systemd/system/multi-user.target.wants/tgt.service' → '/usr/lib/systemd/system/tgt.service'.

402s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ...
402s Setting up libgfxdr0:ppc64el (11.1-4ubuntu1) ...
402s Setting up librados2 (19.2.0~git20240301.4c76c50-0ubuntu7) ...
402s Setting up open-iscsi (2.1.10-1ubuntu1) ...
403s Created symlink '/etc/systemd/system/sockets.target.wants/iscsid.socket' → '/usr/lib/systemd/system/iscsid.socket'.

404s Created symlink '/etc/systemd/system/iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'.

404s Created symlink '/etc/systemd/system/sysinit.target.wants/open-iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'.

404s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ...
404s Setting up librbd1 (19.2.0~git20240301.4c76c50-0ubuntu7) ...
404s Setting up libgfrpc0:ppc64el (11.1-4ubuntu1) ...
404s Setting up libgfapi0:ppc64el (11.1-4ubuntu1) ...
404s Setting up fio (3.37-1) ...
404s Setting up autopkgtest-satdep (0) ...
404s Processing triggers for man-db (2.12.1-2) ...
406s Processing triggers for initramfs-tools (0.142ubuntu30) ...
406s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic
406s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
415s Processing triggers for libc-bin (2.39-0ubuntu9) ...
417s (Reading database ... 73005 files and directories currently installed.)
417s Removing autopkgtest-satdep (0) ...
422s autopkgtest [16:13:45]: test tgtbasedmpaths: [-----------------------
422s + targetname=iqn.2016-11.foo.com:target.iscsi
422s + pwd
422s + cwd=/tmp/autopkgtest.qsbjoJ/build.FmU/src
422s + testdir=/mnt/tgtmpathtest
422s + localhost=127.0.0.1
422s + portal=127.0.0.1:3260
422s + maxpaths=4
422s + backfn=backingfile
422s + expectwwid=60000000000000000e00000000010001
422s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001
422s + bglog=/tmp/autopkgtest.qsbjoJ/tgtbasedmpaths-artifacts/test-background.log
422s + fioprep=/tmp/autopkgtest.qsbjoJ/tgtbasedmpaths-artifacts/path-change-prep.fio
422s + fiovrfy=/tmp/autopkgtest.qsbjoJ/tgtbasedmpaths-artifacts/path-change-check.fio
422s + service tgt restart
423s + truncate --size 100M backingfile
423s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi
423s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL
423s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.qsbjoJ/build.FmU/src/backingfile
423s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1
423s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi
423s login #1
423s + echo login #1
423s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login
423s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260]
423s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
423s + seq 2 4
423s extra login #2
423s + echo extra login #2
423s + iscsiadm --mode session -r 1 --op new
423s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple)
423s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
423s + echo extra login #3
423s + iscsiadm --mode session -r 1 --op new
423s extra login #3
423s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple)
423s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
423s extra login #4
423s + echo extra login #4
423s + iscsiadm --mode session -r 1 --op new
423s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple)
423s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
423s + udevadm settle
423s + sleep 5
428s + echo Status after initial setup
428s + tgtadm --lld iscsi --mode target --op show
428s Status after initial setup
428s Target 1: iqn.2016-11.foo.com:target.iscsi
428s     System information:
428s         Driver: iscsi
428s         State: ready
428s     I_T nexus information:
428s         I_T nexus: 1
428s             Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e alias: autopkgtest
428s             Connection: 0
428s                 IP Address: 127.0.0.1
428s         I_T nexus: 2
428s             Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e alias: autopkgtest
428s             Connection: 0
428s                 IP Address: 127.0.0.1
428s         I_T nexus: 3
428s             Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e alias: autopkgtest
428s             Connection: 0
428s                 IP Address: 127.0.0.1
428s         I_T nexus: 4
428s             Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e alias: autopkgtest
428s             Connection: 0
428s                 IP Address: 127.0.0.1
428s     LUN information:
428s         LUN: 0
428s             Type: controller
428s             SCSI ID: IET     00010000
428s             SCSI SN: beaf10
428s             Size: 0 MB, Block size: 1
428s             Online: Yes
428s             Removable media: No
428s             Prevent removal: No
428s             Readonly: No
428s             SWP: No
428s             Thin-provisioning: No
428s             Backing store type: null
428s             Backing store path: None
428s             Backing store flags: 
428s         LUN: 1
428s             Type: disk
428s             SCSI ID: IET     00010001
428s             SCSI SN: beaf11
428s             Size: 105 MB, Block size: 512
428s             Online: Yes
428s             Removable media: No
428s             Prevent removal: No
428s             Readonly: No
428s             SWP: No
428s             Thin-provisioning: No
428s             Backing store type: rdwr
428s             Backing store path: /tmp/autopkgtest.qsbjoJ/build.FmU/src/backingfile
428s             Backing store flags: 
428s     Account information:
428s     ACL information:
428s         ALL
428s + tgtadm --lld iscsi --op show --mode conn --tid 1
428s Session: 4
428s     Connection: 0
428s         Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s         IP Address: 127.0.0.1
428s Session: 3
428s     Connection: 0
428s         Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s         IP Address: 127.0.0.1
428s Session: 2
428s     Connection: 0
428s         Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s         IP Address: 127.0.0.1
428s Session: 1
428s     Connection: 0
428s         Initiator: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s         IP Address: 127.0.0.1
428s + iscsiadm --mode session -P 1
428s Target: iqn.2016-11.foo.com:target.iscsi (non-flash)
428s 	Current Portal: 127.0.0.1:3260,1
428s 	Persistent Portal: 127.0.0.1:3260,1
428s 		**********
428s 		Interface:
428s 		**********
428s 		Iface Name: default
428s 		Iface Transport: tcp
428s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s 		Iface IPaddress: 127.0.0.1
428s 		Iface HWaddress: default
428s 		Iface Netdev: default
428s 		SID: 1
428s 		iSCSI Connection State: LOGGED IN
428s 		iSCSI Session State: LOGGED_IN
428s 		Internal iscsid Session State: NO CHANGE
428s 
428s 		**********
428s 		Interface:
428s 		**********
428s 		Iface Name: default
428s 		Iface Transport: tcp
428s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s 		Iface IPaddress: 127.0.0.1
428s 		Iface HWaddress: default
428s 		Iface Netdev: default
428s 		SID: 2
428s 		iSCSI Connection State: LOGGED IN
428s 		iSCSI Session State: LOGGED_IN
428s 		Internal iscsid Session State: NO CHANGE
428s 
428s 		**********
428s 		Interface:
428s 		**********
428s 		Iface Name: default
428s 		Iface Transport: tcp
428s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s 		Iface IPaddress: 127.0.0.1
428s 		Iface HWaddress: default
428s 		Iface Netdev: default
428s 		SID: 3
428s 		iSCSI Connection State: LOGGED IN
428s 		iSCSI Session State: LOGGED_IN
428s 		Internal iscsid Session State: NO CHANGE
428s 
428s 		**********
428s 		Interface:
428s 		**********
428s 		Iface Name: default
428s 		Iface Transport: tcp
428s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:4276d07c295e
428s 		Iface IPaddress: 127.0.0.1
428s 		Iface HWaddress: default
428s 		Iface Netdev: default
428s 		SID: 4
428s 		iSCSI Connection State: LOGGED IN
428s 		iSCSI Session State: LOGGED_IN
428s 		Internal iscsid Session State: NO CHANGE
428s + lsscsi -liv
428s list_ndevices: scandir: /sys/class/nvme/: No such file or directory
428s + multipath -v3 -ll
428s 59.739650 | set open fds limit to 1073741816/1073741816
428s [0:0:0:0]    disk    QEMU     QEMU HARDDISK    2.5+  /dev/sda   0QEMU_QEMU_HARDDISK_drive-scsi0-0-0-0
428s   state=running queue_depth=128 scsi_level=6 type=0 device_blocked=0 timeout=30
428s   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]
428s [1:0:0:0]    storage IET      Controller       0001  -          -
428s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/1:0:0:0  [/sys/devices/platform/host1/session1/target1:0:0/1:0:0:0]
428s [1:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sdb   360000000000000000e00000000010001
428s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/1:0:0:1  [/sys/devices/platform/host1/session1/target1:0:0/1:0:0:1]
428s [2:0:0:0]    storage IET      Controller       0001  -          -
428s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/2:0:0:0  [/sys/devices/platform/host2/session2/target2:0:0/2:0:0:0]
428s [2:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sdc   360000000000000000e00000000010001
428s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/2:0:0:1  [/sys/devices/platform/host2/session2/target2:0:0/2:0:0:1]
428s [3:0:0:0]    storage IET      Controller       0001  -          -
428s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/3:0:0:0  [/sys/devices/platform/host3/session3/target3:0:0/3:0:0:0]
428s [3:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sdd   33000000100000001
428s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/3:0:0:1  [/sys/devices/platform/host3/session3/target3:0:0/3:0:0:1]
428s [4:0:0:0]    storage IET      Controller       0001  -          -
428s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/4:0:0:0  [/sys/devices/platform/host4/session4/target4:0:0/4:0:0:0]
428s [4:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sde   360000000000000000e00000000010001
428s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
428s   dir: /sys/bus/scsi/devices/4:0:0:1  [/sys/devices/platform/host4/session4/target4:0:0/4:0:0:1]
428s NVMe module may not be loaded
428s 59.739790 | loading /lib/multipath/libchecktur.so checker
428s 59.739914 | checker tur: message table size = 3
428s 59.739939 | loading /lib/multipath/libprioconst.so prioritizer
428s 59.740094 | _init_foreign: foreign library "nvme" is not enabled
428s 59.745749 | sda: size = 167772160
428s 59.745926 | sda: vendor = QEMU
428s 59.745970 | sda: product = QEMU HARDDISK
428s 59.745996 | sda: rev = 2.5+
428s 59.746621 | sda: h:b:t:l = 0:0:0:0
428s 59.746956 | sda: tgt_node_name = 
428s 59.746978 | sda: uid_attribute = ID_SERIAL (setting: multipath internal)
428s 59.746981 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
428s 59.747152 | sda: 10443 cyl, 255 heads, 63 sectors/track, start at 0
428s 59.747173 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.747187 | __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
428s 59.747193 | failed to read sysfs vpd pg80: No such file or directory
428s 59.747447 | sda: fail to get serial
428s 59.747468 | sda: detect_checker = yes (setting: multipath internal)
428s 59.747625 | sda: path_checker = tur (setting: multipath internal)
428s 59.747646 | sda: checker timeout = 30 s (setting: kernel sysfs)
428s 59.747736 | sda: tur state = up
428s 59.748070 | sdb: size = 204800
428s 59.748232 | sdb: vendor = IET
428s 59.748274 | sdb: product = VIRTUAL-DISK
428s 59.748297 | sdb: rev = 0001
428s 59.748917 | sdb: h:b:t:l = 1:0:0:1
428s 59.749303 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.749323 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal)
428s 59.749326 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
428s 59.749488 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.749508 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.749526 | sdb: serial = beaf11
428s 59.749529 | sdb: detect_checker = yes (setting: multipath internal)
428s 59.749902 | sdb: path_checker = tur (setting: multipath internal)
428s 59.749924 | sdb: checker timeout = 30 s (setting: kernel sysfs)
428s 59.750114 | sdb: tur state = up
428s 59.750265 | sdc: size = 204800
428s 59.750423 | sdc: vendor = IET
428s 59.750464 | sdc: product = VIRTUAL-DISK
428s 59.750489 | sdc: rev = 0001
428s 59.751206 | sdc: h:b:t:l = 2:0:0:1
428s 59.751582 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.751603 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal)
428s 59.751605 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
428s 59.751761 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.751781 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.751796 | sdc: serial = beaf11
428s 59.751799 | sdc: detect_checker = yes (setting: multipath internal)
428s 59.751965 | sdc: path_checker = tur (setting: multipath internal)
428s 59.751970 | sdc: checker timeout = 30 s (setting: kernel sysfs)
428s 59.752092 | sdc: tur state = up
428s 59.752239 | sdd: size = 204800
428s 59.752399 | sdd: vendor = IET
428s 59.752441 | sdd: product = VIRTUAL-DISK
428s 59.752465 | sdd: rev = 0001
428s 59.753146 | sdd: h:b:t:l = 3:0:0:1
428s 59.753521 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.753541 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal)
428s 59.753543 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
428s 59.753701 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.753722 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.753736 | sdd: serial = beaf11
428s 59.753739 | sdd: detect_checker = yes (setting: multipath internal)
428s 59.753963 | sdd: path_checker = tur (setting: multipath internal)
428s 59.753985 | sdd: checker timeout = 30 s (setting: kernel sysfs)
428s 59.754128 | sdd: tur state = up
428s 59.754283 | sde: size = 204800
428s 59.754440 | sde: vendor = IET
428s 59.754481 | sde: product = VIRTUAL-DISK
428s 59.754505 | sde: rev = 0001
428s 59.755198 | sde: h:b:t:l = 4:0:0:1
428s 59.755572 | sde: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.755592 | sde: uid_attribute = ID_SERIAL (setting: multipath internal)
428s 59.755594 | sde: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
428s 59.755748 | sde: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.755769 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.755784 | sde: serial = beaf11
428s 59.755786 | sde: detect_checker = yes (setting: multipath internal)
428s 59.755950 | sde: path_checker = tur (setting: multipath internal)
428s 59.755972 | sde: checker timeout = 30 s (setting: kernel sysfs)
428s 59.756125 | sde: tur state = up
428s 59.756250 | loop0: device node name blacklisted
428s 59.756363 | loop1: device node name blacklisted
428s 59.756474 | loop2: device node name blacklisted
428s 59.756582 | loop3: device node name blacklisted
428s 59.756691 | loop4: device node name blacklisted
428s 59.756799 | loop5: device node name blacklisted
428s 59.756906 | loop6: device node name blacklisted
428s 59.757014 | loop7: device node name blacklisted
428s 59.757133 | dm-0: device node name blacklisted
428s 59.758160 | multipath-tools v0.9.4 (12/19, 2022)
428s 59.758194 | libdevmapper version 1.02.196
428s 59.758351 | kernel device mapper v4.48.0
428s 59.758384 | DM multipath kernel driver v1.14.0
428s 59.758519 | sdb: size = 204800
428s 59.758541 | sdb: vendor = IET
428s 59.758544 | sdb: product = VIRTUAL-DISK
428s 59.758547 | sdb: rev = 0001
428s 59.759197 | sdb: h:b:t:l = 1:0:0:1
428s 59.759342 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.759383 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.759386 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.759401 | sdb: serial = beaf11
428s 59.759584 | sdb: tur state = up
428s 59.764109 | sdb: uid = 360000000000000000e00000000010001 (udev)
428s 59.764113 | sdb: detect_prio = yes (setting: multipath internal)
428s 59.764118 | sdb: prio = const (setting: multipath internal)
428s 59.764121 | sdb: prio args = "" (setting: multipath internal)
428s 59.764123 | sdb: const prio = 1
428s 59.764169 | sdc: size = 204800
428s 59.764193 | sdc: vendor = IET
428s 59.764196 | sdc: product = VIRTUAL-DISK
428s 59.764199 | sdc: rev = 0001
428s 59.764846 | sdc: h:b:t:l = 2:0:0:1
428s 59.765008 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.765051 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.765054 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.765070 | sdc: serial = beaf11
428s 59.765279 | sdc: tur state = up
428s 59.765301 | sdc: uid = 360000000000000000e00000000010001 (udev)
428s 59.765303 | sdc: detect_prio = yes (setting: multipath internal)
428s 59.765306 | sdc: prio = const (setting: multipath internal)
428s 59.765308 | sdc: prio args = "" (setting: multipath internal)
428s 59.765310 | sdc: const prio = 1
428s 59.765343 | sdd: size = 204800
428s 59.765363 | sdd: vendor = IET
428s 59.765365 | sdd: product = VIRTUAL-DISK
428s 59.765368 | sdd: rev = 0001
428s 59.765980 | sdd: h:b:t:l = 3:0:0:1
428s 59.766119 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.766159 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.766162 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.766176 | sdd: serial = beaf11
428s 59.766362 | sdd: tur state = up
428s 59.767552 | sdd: uid = 360000000000000000e00000000010001 (udev)
428s 59.767557 | sdd: detect_prio = yes (setting: multipath internal)
428s 59.767561 | sdd: prio = const (setting: multipath internal)
428s 59.767563 | sdd: prio args = "" (setting: multipath internal)
428s 59.767567 | sdd: const prio = 1
428s 59.767625 | sde: size = 204800
428s 59.767666 | sde: vendor = IET
428s 59.767669 | sde: product = VIRTUAL-DISK
428s 59.767672 | sde: rev = 0001
428s 59.768358 | sde: h:b:t:l = 4:0:0:1
428s 59.768547 | sde: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
428s 59.768592 | sde: 1024 cyl, 4 heads, 50 sectors/track, start at 0
428s 59.768595 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal)
428s 59.768612 | sde: serial = beaf11
428s 59.768850 | sde: tur state = up
428s 59.768873 | sde: uid = 360000000000000000e00000000010001 (udev)
428s 59.768876 | sde: detect_prio = yes (setting: multipath internal)
428s 59.768879 | sde: prio = const (setting: multipath internal)
428s 59.768881 | sde: prio args = "" (setting: multipath internal)
428s 59.768884 | sde: const prio = 1
428s 59.769965 | unloading tur checker
428s 59.770058 | unloading const prioritizer
428s + dmsetup table
428s + echo Test WWN should now point to DM
428s + readlink /dev/disk/by-id/wwn-0x60000000000000000e00000000010001
428s + grep dm
428s ===== paths list =====
428s uuid hcil    dev dev_t pri dm_st chk_st vend/prod/rev      dev_st 
428s      0:0:0:0 sda 8:0   -1  undef undef  QEMU,QEMU HARDDISK unknown
428s      1:0:0:1 sdb 8:16  -1  undef undef  IET,VIRTUAL-DISK   unknown
428s      2:0:0:1 sdc 8:32  -1  undef undef  IET,VIRTUAL-DISK   unknown
428s      3:0:0:1 sdd 8:48  -1  undef undef  IET,VIRTUAL-DISK   unknown
428s      4:0:0:1 sde 8:64  -1  undef undef  IET,VIRTUAL-DISK   unknown
428s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK
428s size=100M features='0' hwhandler='0' wp=rw
428s |-+- policy='service-time 0' prio=1 status=active
428s | `- 1:0:0:1 sdb 8:16 active ready running
428s |-+- policy='service-time 0' prio=1 status=enabled
428s | `- 2:0:0:1 sdc 8:32 active ready running
428s |-+- policy='service-time 0' prio=1 status=enabled
428s | `- 3:0:0:1 sdd 8:48 active ready running
428s `-+- policy='service-time 0' prio=1 status=enabled
428s   `- 4:0:0:1 sde 8:64 active ready running
428s 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 
428s Test WWN should now point to DM
428s autopkgtest [16:13:51]: test tgtbasedmpaths: -----------------------]
429s autopkgtest [16:13:52]: test tgtbasedmpaths:  - - - - - - - - - - results - - - - - - - - - -
429s tgtbasedmpaths       FAIL non-zero exit status 1
429s autopkgtest [16:13:52]: @@@@@@@@@@@@@@@@@@@@ summary
429s kpartx-file-loopback PASS
429s tgtbasedmpaths       FAIL non-zero exit status 1
443s nova [W] Using flock in scalingstack-bos01-ppc64el
443s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240716-160642-juju-7f2275-prod-proposed-migration-environment-2-597ef4cd-6bcc-449a-a5ce-feeacabf8ba6 from image adt/ubuntu-oracular-ppc64el-server-20240716.img (UUID f585636a-b98f-4308-a664-a1825608e570)...
443s nova [W] Using flock in scalingstack-bos01-ppc64el
443s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240716-160642-juju-7f2275-prod-proposed-migration-environment-2-597ef4cd-6bcc-449a-a5ce-feeacabf8ba6 from image adt/ubuntu-oracular-ppc64el-server-20240716.img (UUID f585636a-b98f-4308-a664-a1825608e570)...