0s autopkgtest [15:52:54]: starting date and time: 2024-08-28 15:52:54+0000
  0s autopkgtest [15:52:54]: git checkout: fd3bed09 nova: allow more retries for quota issues
  0s autopkgtest [15:52:54]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ga6u6flz/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:linux-meta,src:linux --apt-upgrade multipath-tools --timeout-short=300 --timeout-copy=20000 --timeout-test=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=linux-meta/6.11.0-5.5 linux/6.11.0-5.5' --setup-commands 'apt-get install -y ^kernel-testing--linux--full--preferred$ || apt-get install -y ^linux-image$ ^linux-headers$ || apt-get install -y ^linux-image-generic$ ^linux-headers-generic$' --setup-commands 'apt-get install -y ^kernel-testing--linux--modules-extra--preferred$ || apt-get install -y ^linux-modules-extra$ || :' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-big-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-ppc64el-25.secgroup --name adt-oracular-ppc64el-multipath-tools-20240828-155249-juju-7f2275-prod-proposed-migration-environment-3-977cdf1a-ecd6-4fa0-bd2e-fd3feac44f1c --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/
223s autopkgtest [15:56:37]: testbed dpkg architecture: ppc64el
223s autopkgtest [15:56:37]: testbed apt version: 2.9.8
223s autopkgtest [15:56:37]: @@@@@@@@@@@@@@@@@@@@ test bed setup
224s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB]
224s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8372 B]
224s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [749 kB]
225s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [11.1 kB]
225s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [50.7 kB]
225s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [91.8 kB]
225s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [1548 B]
225s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1376 B]
225s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B]
225s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [679 kB]
226s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [18.6 kB]
226s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [5956 B]
226s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [372 B]
229s Fetched 1744 kB in 3s (638 kB/s)
229s Reading package lists...
232s Reading package lists...
232s Building dependency tree...
232s Reading state information...
232s Calculating upgrade...
232s The following NEW packages will be installed:
232s   libpython3.12t64 linux-headers-6.11.0-5 linux-headers-6.11.0-5-generic
232s   linux-image-6.11.0-5-generic linux-modules-6.11.0-5-generic
232s   linux-modules-extra-6.11.0-5-generic linux-tools-6.11.0-5
232s   linux-tools-6.11.0-5-generic
232s The following packages will be upgraded:
232s   linux-generic linux-headers-generic linux-headers-virtual
232s   linux-image-generic linux-image-virtual linux-libc-dev linux-tools-common
232s   linux-virtual
233s 8 upgraded, 8 newly installed, 0 to remove and 0 not upgraded.
233s Need to get 228 MB of archives.
233s After this operation, 343 MB of additional disk space will be used.
233s Get:1 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpython3.12t64 ppc64el 3.12.5-4 [2560 kB]
237s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-modules-6.11.0-5-generic ppc64el 6.11.0-5.5 [32.8 MB]
305s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-image-6.11.0-5-generic ppc64el 6.11.0-5.5 [63.9 MB]
415s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-modules-extra-6.11.0-5-generic ppc64el 6.11.0-5.5 [105 MB]
571s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-generic ppc64el 6.11.0-5.5 [1730 B]
571s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-image-generic ppc64el 6.11.0-5.5 [10.6 kB]
571s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-virtual ppc64el 6.11.0-5.5 [1718 B]
571s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-image-virtual ppc64el 6.11.0-5.5 [10.6 kB]
571s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-virtual ppc64el 6.11.0-5.5 [1638 B]
571s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-6.11.0-5 all 6.11.0-5.5 [13.9 MB]
589s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-6.11.0-5-generic ppc64el 6.11.0-5.5 [3948 kB]
593s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-generic ppc64el 6.11.0-5.5 [10.5 kB]
593s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-libc-dev ppc64el 6.11.0-5.5 [1649 kB]
595s Get:14 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-tools-common all 6.11.0-5.5 [479 kB]
596s Get:15 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-tools-6.11.0-5 ppc64el 6.11.0-5.5 [4201 kB]
601s Get:16 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-tools-6.11.0-5-generic ppc64el 6.11.0-5.5 [1736 B]
601s Fetched 228 MB in 6min 8s (620 kB/s)
601s Selecting previously unselected package libpython3.12t64:ppc64el.
601s (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 ... 72412 files and directories currently installed.)
601s Preparing to unpack .../00-libpython3.12t64_3.12.5-4_ppc64el.deb ...
601s Unpacking libpython3.12t64:ppc64el (3.12.5-4) ...
602s Selecting previously unselected package linux-modules-6.11.0-5-generic.
602s Preparing to unpack .../01-linux-modules-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
602s Unpacking linux-modules-6.11.0-5-generic (6.11.0-5.5) ...
602s Selecting previously unselected package linux-image-6.11.0-5-generic.
602s Preparing to unpack .../02-linux-image-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
602s Unpacking linux-image-6.11.0-5-generic (6.11.0-5.5) ...
602s Selecting previously unselected package linux-modules-extra-6.11.0-5-generic.
602s Preparing to unpack .../03-linux-modules-extra-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
602s Unpacking linux-modules-extra-6.11.0-5-generic (6.11.0-5.5) ...
603s Preparing to unpack .../04-linux-generic_6.11.0-5.5_ppc64el.deb ...
603s Unpacking linux-generic (6.11.0-5.5) over (6.8.0-31.31) ...
603s Preparing to unpack .../05-linux-image-generic_6.11.0-5.5_ppc64el.deb ...
603s Unpacking linux-image-generic (6.11.0-5.5) over (6.8.0-31.31) ...
603s Preparing to unpack .../06-linux-virtual_6.11.0-5.5_ppc64el.deb ...
603s Unpacking linux-virtual (6.11.0-5.5) over (6.8.0-31.31) ...
603s Preparing to unpack .../07-linux-image-virtual_6.11.0-5.5_ppc64el.deb ...
603s Unpacking linux-image-virtual (6.11.0-5.5) over (6.8.0-31.31) ...
603s Preparing to unpack .../08-linux-headers-virtual_6.11.0-5.5_ppc64el.deb ...
603s Unpacking linux-headers-virtual (6.11.0-5.5) over (6.8.0-31.31) ...
603s Selecting previously unselected package linux-headers-6.11.0-5.
603s Preparing to unpack .../09-linux-headers-6.11.0-5_6.11.0-5.5_all.deb ...
603s Unpacking linux-headers-6.11.0-5 (6.11.0-5.5) ...
606s Selecting previously unselected package linux-headers-6.11.0-5-generic.
606s Preparing to unpack .../10-linux-headers-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
606s Unpacking linux-headers-6.11.0-5-generic (6.11.0-5.5) ...
607s Preparing to unpack .../11-linux-headers-generic_6.11.0-5.5_ppc64el.deb ...
607s Unpacking linux-headers-generic (6.11.0-5.5) over (6.8.0-31.31) ...
607s Preparing to unpack .../12-linux-libc-dev_6.11.0-5.5_ppc64el.deb ...
607s Unpacking linux-libc-dev:ppc64el (6.11.0-5.5) over (6.8.0-31.31) ...
607s Preparing to unpack .../13-linux-tools-common_6.11.0-5.5_all.deb ...
607s Unpacking linux-tools-common (6.11.0-5.5) over (6.8.0-31.31) ...
607s Selecting previously unselected package linux-tools-6.11.0-5.
607s Preparing to unpack .../14-linux-tools-6.11.0-5_6.11.0-5.5_ppc64el.deb ...
607s Unpacking linux-tools-6.11.0-5 (6.11.0-5.5) ...
607s Selecting previously unselected package linux-tools-6.11.0-5-generic.
607s Preparing to unpack .../15-linux-tools-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
607s Unpacking linux-tools-6.11.0-5-generic (6.11.0-5.5) ...
607s Setting up linux-libc-dev:ppc64el (6.11.0-5.5) ...
607s Setting up libpython3.12t64:ppc64el (3.12.5-4) ...
607s Setting up linux-modules-6.11.0-5-generic (6.11.0-5.5) ...
610s Setting up linux-modules-extra-6.11.0-5-generic (6.11.0-5.5) ...
612s Setting up linux-headers-6.11.0-5 (6.11.0-5.5) ...
612s Setting up linux-tools-common (6.11.0-5.5) ...
612s Setting up linux-image-6.11.0-5-generic (6.11.0-5.5) ...
614s I: /boot/vmlinux is now a symlink to vmlinux-6.11.0-5-generic
614s I: /boot/initrd.img is now a symlink to initrd.img-6.11.0-5-generic
614s Setting up linux-headers-6.11.0-5-generic (6.11.0-5.5) ...
614s Setting up linux-headers-generic (6.11.0-5.5) ...
614s Setting up linux-image-virtual (6.11.0-5.5) ...
614s Setting up linux-tools-6.11.0-5 (6.11.0-5.5) ...
614s Setting up linux-image-generic (6.11.0-5.5) ...
614s Setting up linux-headers-virtual (6.11.0-5.5) ...
614s Setting up linux-generic (6.11.0-5.5) ...
614s Setting up linux-tools-6.11.0-5-generic (6.11.0-5.5) ...
614s Setting up linux-virtual (6.11.0-5.5) ...
614s Processing triggers for man-db (2.12.1-3) ...
615s Processing triggers for libc-bin (2.40-1ubuntu1) ...
615s Processing triggers for linux-image-6.11.0-5-generic (6.11.0-5.5) ...
615s /etc/kernel/postinst.d/initramfs-tools:
615s update-initramfs: Generating /boot/initrd.img-6.11.0-5-generic
615s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
624s /etc/kernel/postinst.d/zz-update-grub:
624s Sourcing file `/etc/default/grub'
624s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
624s Generating grub configuration file ...
624s Found linux image: /boot/vmlinux-6.11.0-5-generic
624s Found initrd image: /boot/initrd.img-6.11.0-5-generic
624s Found linux image: /boot/vmlinux-6.8.0-31-generic
624s Found initrd image: /boot/initrd.img-6.8.0-31-generic
624s Warning: os-prober will not be executed to detect other bootable partitions.
624s Systems on them will not be added to the GRUB boot configuration.
624s Check GRUB_DISABLE_OS_PROBER documentation entry.
624s Adding boot menu entry for UEFI Firmware Settings ...
624s done
624s Reading package lists...
624s Building dependency tree...
624s Reading state information...
625s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
625s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease
625s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease
625s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease
625s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease
626s Reading package lists...
626s Reading package lists...
627s Building dependency tree...
627s Reading state information...
627s Calculating upgrade...
627s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
627s Reading package lists...
627s Building dependency tree...
627s Reading state information...
627s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
628s Reading package lists...
628s Building dependency tree...
628s Reading state information...
628s linux-generic is already the newest version (6.11.0-5.5).
628s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
628s Reading package lists...
629s Building dependency tree...
629s Reading state information...
629s E: Unable to locate package ^kernel-testing--linux--modules-extra--preferred$
629s E: Couldn't find any package by regex '^kernel-testing--linux--modules-extra--preferred$'
629s Reading package lists...
629s Building dependency tree...
629s Reading state information...
629s E: Unable to locate package ^linux-modules-extra$
629s E: Couldn't find any package by regex '^linux-modules-extra$'
629s autopkgtest [16:03:23]: rebooting testbed after setup commands that affected boot
633s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
659s autopkgtest [16:03:53]: testbed running kernel: Linux 6.11.0-5-generic #5-Ubuntu SMP Mon Aug 26 21:16:43 UTC 2024
662s autopkgtest [16:03:56]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools
666s Get:1 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.9-1ubuntu2 (dsc) [2772 B]
666s Get:2 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.9-1ubuntu2 (tar) [588 kB]
666s Get:3 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.9-1ubuntu2 (diff) [42.4 kB]
666s gpgv: Signature made Tue Jul 23 21:24:22 2024 UTC
666s gpgv:                using RSA key D09F8A854F1055BCFC482C4B23566B906047AFC8
666s gpgv: Can't check signature: No public key
666s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.9-1ubuntu2.dsc: no acceptable signature found
666s autopkgtest [16:04:00]: testing package multipath-tools version 0.9.9-1ubuntu2
666s initramfs            SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed.
666s autopkgtest [16:04:00]: build not needed
667s initramfs            SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed.
667s autopkgtest [16:04:01]: test kpartx-file-loopback: preparing testbed
668s Reading package lists...
669s Building dependency tree...
669s Reading state information...
669s Starting pkgProblemResolver with broken count: 0
669s Starting 2 pkgProblemResolver with broken count: 0
669s Done
669s The following additional packages will be installed:
669s   liburing2 qemu-utils
669s Recommended packages:
669s   qemu-block-extra
669s The following NEW packages will be installed:
669s   autopkgtest-satdep liburing2 qemu-utils
669s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded.
669s Need to get 2432 kB/2433 kB of archives.
669s After this operation, 16.9 MB of additional disk space will be used.
669s Get:1 /tmp/autopkgtest.QiPd0W/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B]
669s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el liburing2 ppc64el 2.6-1 [26.9 kB]
669s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el qemu-utils ppc64el 1:9.0.2+ds-4ubuntu2 [2405 kB]
672s Fetched 2432 kB in 3s (842 kB/s)
672s Selecting previously unselected package liburing2:ppc64el.
673s (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 ... 109815 files and directories currently installed.)
673s Preparing to unpack .../liburing2_2.6-1_ppc64el.deb ...
673s Unpacking liburing2:ppc64el (2.6-1) ...
673s Selecting previously unselected package qemu-utils.
673s Preparing to unpack .../qemu-utils_1%3a9.0.2+ds-4ubuntu2_ppc64el.deb ...
673s Unpacking qemu-utils (1:9.0.2+ds-4ubuntu2) ...
673s Selecting previously unselected package autopkgtest-satdep.
673s Preparing to unpack .../1-autopkgtest-satdep.deb ...
673s Unpacking autopkgtest-satdep (0) ...
673s Setting up liburing2:ppc64el (2.6-1) ...
673s Setting up qemu-utils (1:9.0.2+ds-4ubuntu2) ...
673s Setting up autopkgtest-satdep (0) ...
673s Processing triggers for man-db (2.12.1-3) ...
674s Processing triggers for libc-bin (2.40-1ubuntu1) ...
677s (Reading database ... 109839 files and directories currently installed.)
677s Removing autopkgtest-satdep (0) ...
677s autopkgtest [16:04:11]: test kpartx-file-loopback: [-----------------------
678s Formatting 'foo.img', fmt=raw size=20971520
679s Creating new GPT entries in memory.
679s Warning: The kernel is still using the old partition table.
679s The new table will be used at the next reboot or after you
679s run partprobe(8) or kpartx(8)
679s The operation has completed successfully.
679s add map loop0p1 (252:0): 0 38879 linear 7:0 2048
679s standard_filename: OK
679s del devmap : loop0p1
679s No devices found
679s standard_filename_cleanup: OK
679s Formatting 'fou du FaFa.img', fmt=raw size=20971520
680s Creating new GPT entries in memory.
680s Warning: The kernel is still using the old partition table.
680s The new table will be used at the next reboot or after you
680s run partprobe(8) or kpartx(8)
680s The operation has completed successfully.
680s add map loop0p1 (252:0): 0 38879 linear 7:0 2048
680s filename_with_spaces: OK
680s del devmap : loop0p1
680s No devices found
680s filename_with_spaces_cleanup: OK
680s autopkgtest [16:04:14]: test kpartx-file-loopback: -----------------------]
681s kpartx-file-loopback PASS
681s autopkgtest [16:04:15]: test kpartx-file-loopback:  - - - - - - - - - - results - - - - - - - - - -
681s autopkgtest [16:04:15]: test tgtbasedmpaths: preparing testbed
750s autopkgtest [16:05:24]: testbed dpkg architecture: ppc64el
751s autopkgtest [16:05:25]: testbed apt version: 2.9.8
751s autopkgtest [16:05:25]: @@@@@@@@@@@@@@@@@@@@ test bed setup
751s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB]
752s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8372 B]
752s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [749 kB]
752s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [11.1 kB]
752s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [50.7 kB]
752s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [91.8 kB]
752s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [1548 B]
752s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1376 B]
752s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B]
752s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [679 kB]
752s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [18.6 kB]
752s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [5956 B]
752s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [372 B]
755s Fetched 1744 kB in 1s (1502 kB/s)
755s Reading package lists...
758s Reading package lists...
758s Building dependency tree...
758s Reading state information...
758s Calculating upgrade...
758s The following NEW packages will be installed:
758s   libpython3.12t64 linux-headers-6.11.0-5 linux-headers-6.11.0-5-generic
758s   linux-image-6.11.0-5-generic linux-modules-6.11.0-5-generic
758s   linux-modules-extra-6.11.0-5-generic linux-tools-6.11.0-5
758s   linux-tools-6.11.0-5-generic
758s The following packages will be upgraded:
758s   linux-generic linux-headers-generic linux-headers-virtual
758s   linux-image-generic linux-image-virtual linux-libc-dev linux-tools-common
758s   linux-virtual
759s 8 upgraded, 8 newly installed, 0 to remove and 0 not upgraded.
759s Need to get 228 MB of archives.
759s After this operation, 343 MB of additional disk space will be used.
759s Get:1 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpython3.12t64 ppc64el 3.12.5-4 [2560 kB]
760s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-modules-6.11.0-5-generic ppc64el 6.11.0-5.5 [32.8 MB]
773s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-image-6.11.0-5-generic ppc64el 6.11.0-5.5 [63.9 MB]
782s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-modules-extra-6.11.0-5-generic ppc64el 6.11.0-5.5 [105 MB]
792s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-generic ppc64el 6.11.0-5.5 [1730 B]
792s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-image-generic ppc64el 6.11.0-5.5 [10.6 kB]
792s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-virtual ppc64el 6.11.0-5.5 [1718 B]
792s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-image-virtual ppc64el 6.11.0-5.5 [10.6 kB]
792s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-virtual ppc64el 6.11.0-5.5 [1638 B]
792s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-6.11.0-5 all 6.11.0-5.5 [13.9 MB]
793s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-6.11.0-5-generic ppc64el 6.11.0-5.5 [3948 kB]
793s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-headers-generic ppc64el 6.11.0-5.5 [10.5 kB]
793s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-libc-dev ppc64el 6.11.0-5.5 [1649 kB]
793s Get:14 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-tools-common all 6.11.0-5.5 [479 kB]
793s Get:15 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-tools-6.11.0-5 ppc64el 6.11.0-5.5 [4201 kB]
794s Get:16 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el linux-tools-6.11.0-5-generic ppc64el 6.11.0-5.5 [1736 B]
794s Fetched 228 MB in 35s (6503 kB/s)
794s Selecting previously unselected package libpython3.12t64:ppc64el.
794s (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 ... 72412 files and directories currently installed.)
794s Preparing to unpack .../00-libpython3.12t64_3.12.5-4_ppc64el.deb ...
794s Unpacking libpython3.12t64:ppc64el (3.12.5-4) ...
794s Selecting previously unselected package linux-modules-6.11.0-5-generic.
794s Preparing to unpack .../01-linux-modules-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
794s Unpacking linux-modules-6.11.0-5-generic (6.11.0-5.5) ...
795s Selecting previously unselected package linux-image-6.11.0-5-generic.
795s Preparing to unpack .../02-linux-image-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
795s Unpacking linux-image-6.11.0-5-generic (6.11.0-5.5) ...
795s Selecting previously unselected package linux-modules-extra-6.11.0-5-generic.
795s Preparing to unpack .../03-linux-modules-extra-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
795s Unpacking linux-modules-extra-6.11.0-5-generic (6.11.0-5.5) ...
796s Preparing to unpack .../04-linux-generic_6.11.0-5.5_ppc64el.deb ...
796s Unpacking linux-generic (6.11.0-5.5) over (6.8.0-31.31) ...
796s Preparing to unpack .../05-linux-image-generic_6.11.0-5.5_ppc64el.deb ...
796s Unpacking linux-image-generic (6.11.0-5.5) over (6.8.0-31.31) ...
796s Preparing to unpack .../06-linux-virtual_6.11.0-5.5_ppc64el.deb ...
796s Unpacking linux-virtual (6.11.0-5.5) over (6.8.0-31.31) ...
796s Preparing to unpack .../07-linux-image-virtual_6.11.0-5.5_ppc64el.deb ...
796s Unpacking linux-image-virtual (6.11.0-5.5) over (6.8.0-31.31) ...
796s Preparing to unpack .../08-linux-headers-virtual_6.11.0-5.5_ppc64el.deb ...
796s Unpacking linux-headers-virtual (6.11.0-5.5) over (6.8.0-31.31) ...
796s Selecting previously unselected package linux-headers-6.11.0-5.
796s Preparing to unpack .../09-linux-headers-6.11.0-5_6.11.0-5.5_all.deb ...
796s Unpacking linux-headers-6.11.0-5 (6.11.0-5.5) ...
799s Selecting previously unselected package linux-headers-6.11.0-5-generic.
799s Preparing to unpack .../10-linux-headers-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
799s Unpacking linux-headers-6.11.0-5-generic (6.11.0-5.5) ...
800s Preparing to unpack .../11-linux-headers-generic_6.11.0-5.5_ppc64el.deb ...
800s Unpacking linux-headers-generic (6.11.0-5.5) over (6.8.0-31.31) ...
800s Preparing to unpack .../12-linux-libc-dev_6.11.0-5.5_ppc64el.deb ...
800s Unpacking linux-libc-dev:ppc64el (6.11.0-5.5) over (6.8.0-31.31) ...
800s Preparing to unpack .../13-linux-tools-common_6.11.0-5.5_all.deb ...
800s Unpacking linux-tools-common (6.11.0-5.5) over (6.8.0-31.31) ...
800s Selecting previously unselected package linux-tools-6.11.0-5.
800s Preparing to unpack .../14-linux-tools-6.11.0-5_6.11.0-5.5_ppc64el.deb ...
800s Unpacking linux-tools-6.11.0-5 (6.11.0-5.5) ...
800s Selecting previously unselected package linux-tools-6.11.0-5-generic.
800s Preparing to unpack .../15-linux-tools-6.11.0-5-generic_6.11.0-5.5_ppc64el.deb ...
800s Unpacking linux-tools-6.11.0-5-generic (6.11.0-5.5) ...
800s Setting up linux-libc-dev:ppc64el (6.11.0-5.5) ...
800s Setting up libpython3.12t64:ppc64el (3.12.5-4) ...
800s Setting up linux-modules-6.11.0-5-generic (6.11.0-5.5) ...
802s Setting up linux-modules-extra-6.11.0-5-generic (6.11.0-5.5) ...
804s Setting up linux-headers-6.11.0-5 (6.11.0-5.5) ...
804s Setting up linux-tools-common (6.11.0-5.5) ...
804s Setting up linux-image-6.11.0-5-generic (6.11.0-5.5) ...
806s I: /boot/vmlinux is now a symlink to vmlinux-6.11.0-5-generic
806s I: /boot/initrd.img is now a symlink to initrd.img-6.11.0-5-generic
806s Setting up linux-headers-6.11.0-5-generic (6.11.0-5.5) ...
806s Setting up linux-headers-generic (6.11.0-5.5) ...
806s Setting up linux-image-virtual (6.11.0-5.5) ...
806s Setting up linux-tools-6.11.0-5 (6.11.0-5.5) ...
806s Setting up linux-image-generic (6.11.0-5.5) ...
806s Setting up linux-headers-virtual (6.11.0-5.5) ...
807s Setting up linux-generic (6.11.0-5.5) ...
807s Setting up linux-tools-6.11.0-5-generic (6.11.0-5.5) ...
807s Setting up linux-virtual (6.11.0-5.5) ...
807s Processing triggers for man-db (2.12.1-3) ...
808s Processing triggers for libc-bin (2.40-1ubuntu1) ...
808s Processing triggers for linux-image-6.11.0-5-generic (6.11.0-5.5) ...
808s /etc/kernel/postinst.d/initramfs-tools:
808s update-initramfs: Generating /boot/initrd.img-6.11.0-5-generic
808s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
816s /etc/kernel/postinst.d/zz-update-grub:
816s Sourcing file `/etc/default/grub'
816s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
816s Generating grub configuration file ...
817s Found linux image: /boot/vmlinux-6.11.0-5-generic
817s Found initrd image: /boot/initrd.img-6.11.0-5-generic
817s Found linux image: /boot/vmlinux-6.8.0-31-generic
817s Found initrd image: /boot/initrd.img-6.8.0-31-generic
817s Warning: os-prober will not be executed to detect other bootable partitions.
817s Systems on them will not be added to the GRUB boot configuration.
817s Check GRUB_DISABLE_OS_PROBER documentation entry.
817s Adding boot menu entry for UEFI Firmware Settings ...
817s done
817s Reading package lists...
817s Building dependency tree...
817s Reading state information...
818s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
818s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease
818s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease
818s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease
818s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease
819s Reading package lists...
819s Reading package lists...
820s Building dependency tree...
820s Reading state information...
820s Calculating upgrade...
820s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
820s Reading package lists...
820s Building dependency tree...
820s Reading state information...
821s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
821s Reading package lists...
821s Building dependency tree...
821s Reading state information...
822s linux-generic is already the newest version (6.11.0-5.5).
822s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
822s Reading package lists...
822s Building dependency tree...
822s Reading state information...
822s E: Unable to locate package ^kernel-testing--linux--modules-extra--preferred$
822s E: Couldn't find any package by regex '^kernel-testing--linux--modules-extra--preferred$'
822s Reading package lists...
822s Building dependency tree...
822s Reading state information...
823s E: Unable to locate package ^linux-modules-extra$
823s E: Couldn't find any package by regex '^linux-modules-extra$'
823s autopkgtest [16:06:37]: rebooting testbed after setup commands that affected boot
827s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
860s Reading package lists...
860s Building dependency tree...
860s Reading state information...
860s Starting pkgProblemResolver with broken count: 0
860s Starting 2 pkgProblemResolver with broken count: 0
860s Done
860s The following additional packages will be installed:
860s   fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl
860s   libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0
860s   libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1
860s   librdmacm1t64 lsscsi open-iscsi tgt
860s Suggested packages:
860s   fio-examples gnuplot tgt-glusterfs tgt-rbd
860s Recommended packages:
860s   finalrd
860s The following NEW packages will be installed:
860s   autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0
860s   libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0
860s   libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1
860s   libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt
860s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded.
860s Need to get 10.9 MB/11.0 MB of archives.
860s After this operation, 49.6 MB of additional disk space will be used.
860s Get:1 /tmp/autopkgtest.QiPd0W/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [732 B]
860s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libopeniscsiusr ppc64el 2.1.10-1ubuntu1 [54.9 kB]
861s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libisns0t64 ppc64el 0.101-1 [117 kB]
861s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el open-iscsi ppc64el 2.1.10-1ubuntu1 [385 kB]
861s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el librdmacm1t64 ppc64el 52.0-2ubuntu1 [80.6 kB]
861s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB]
861s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el tgt ppc64el 1:1.0.85-1.2ubuntu1 [254 kB]
861s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfxdr0 ppc64el 11.1-5ubuntu1 [21.7 kB]
861s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libglusterfs0 ppc64el 11.1-5ubuntu1 [308 kB]
861s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfrpc0 ppc64el 11.1-5ubuntu1 [46.3 kB]
861s Get:11 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfapi0 ppc64el 11.1-5ubuntu1 [99.1 kB]
861s Get:12 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libnbd0 ppc64el 1.20.2-2 [98.5 kB]
861s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdaxctl1 ppc64el 77-2.2ubuntu1 [23.7 kB]
861s Get:14 http://ftpmaster.internal/ubuntu oracular/main ppc64el libndctl6 ppc64el 77-2.2ubuntu1 [73.4 kB]
861s Get:15 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB]
861s Get:16 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-3.1ubuntu2 [259 kB]
861s Get:17 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-3.1ubuntu2 [281 kB]
861s Get:18 http://ftpmaster.internal/ubuntu oracular/main ppc64el librados2 ppc64el 19.2.0~is.really.19.1.0-0ubuntu2 [4151 kB]
861s Get:19 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB]
861s Get:20 http://ftpmaster.internal/ubuntu oracular/main ppc64el librbd1 ppc64el 19.2.0~is.really.19.1.0-0ubuntu2 [3684 kB]
861s Get:21 http://ftpmaster.internal/ubuntu oracular/universe ppc64el fio ppc64el 3.37-1 [716 kB]
861s Get:22 http://ftpmaster.internal/ubuntu oracular/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB]
862s Preconfiguring packages ...
862s Fetched 10.9 MB in 1s (9100 kB/s)
862s Selecting previously unselected package libopeniscsiusr.
862s (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 ... 109815 files and directories currently installed.)
862s Preparing to unpack .../00-libopeniscsiusr_2.1.10-1ubuntu1_ppc64el.deb ...
862s Unpacking libopeniscsiusr (2.1.10-1ubuntu1) ...
862s Selecting previously unselected package libisns0t64:ppc64el.
862s Preparing to unpack .../01-libisns0t64_0.101-1_ppc64el.deb ...
862s Unpacking libisns0t64:ppc64el (0.101-1) ...
862s Selecting previously unselected package open-iscsi.
862s Preparing to unpack .../02-open-iscsi_2.1.10-1ubuntu1_ppc64el.deb ...
863s Unpacking open-iscsi (2.1.10-1ubuntu1) ...
863s Selecting previously unselected package librdmacm1t64:ppc64el.
863s Preparing to unpack .../03-librdmacm1t64_52.0-2ubuntu1_ppc64el.deb ...
863s Unpacking librdmacm1t64:ppc64el (52.0-2ubuntu1) ...
863s Selecting previously unselected package libconfig-general-perl.
863s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ...
863s Unpacking libconfig-general-perl (2.65-2) ...
863s Selecting previously unselected package tgt.
863s Preparing to unpack .../05-tgt_1%3a1.0.85-1.2ubuntu1_ppc64el.deb ...
863s Unpacking tgt (1:1.0.85-1.2ubuntu1) ...
863s Selecting previously unselected package libgfxdr0:ppc64el.
863s Preparing to unpack .../06-libgfxdr0_11.1-5ubuntu1_ppc64el.deb ...
863s Unpacking libgfxdr0:ppc64el (11.1-5ubuntu1) ...
863s Selecting previously unselected package libglusterfs0:ppc64el.
863s Preparing to unpack .../07-libglusterfs0_11.1-5ubuntu1_ppc64el.deb ...
863s Unpacking libglusterfs0:ppc64el (11.1-5ubuntu1) ...
863s Selecting previously unselected package libgfrpc0:ppc64el.
863s Preparing to unpack .../08-libgfrpc0_11.1-5ubuntu1_ppc64el.deb ...
863s Unpacking libgfrpc0:ppc64el (11.1-5ubuntu1) ...
863s Selecting previously unselected package libgfapi0:ppc64el.
863s Preparing to unpack .../09-libgfapi0_11.1-5ubuntu1_ppc64el.deb ...
863s Unpacking libgfapi0:ppc64el (11.1-5ubuntu1) ...
863s Selecting previously unselected package libnbd0.
863s Preparing to unpack .../10-libnbd0_1.20.2-2_ppc64el.deb ...
863s Unpacking libnbd0 (1.20.2-2) ...
863s Selecting previously unselected package libdaxctl1:ppc64el.
863s Preparing to unpack .../11-libdaxctl1_77-2.2ubuntu1_ppc64el.deb ...
863s Unpacking libdaxctl1:ppc64el (77-2.2ubuntu1) ...
863s Selecting previously unselected package libndctl6:ppc64el.
863s Preparing to unpack .../12-libndctl6_77-2.2ubuntu1_ppc64el.deb ...
863s Unpacking libndctl6:ppc64el (77-2.2ubuntu1) ...
863s Selecting previously unselected package libpmem1:ppc64el.
863s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ...
863s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ...
863s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el.
863s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-3.1ubuntu2_ppc64el.deb ...
863s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-3.1ubuntu2) ...
863s Selecting previously unselected package libboost-thread1.83.0:ppc64el.
863s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-3.1ubuntu2_ppc64el.deb ...
863s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-3.1ubuntu2) ...
863s Selecting previously unselected package librados2.
863s Preparing to unpack .../16-librados2_19.2.0~is.really.19.1.0-0ubuntu2_ppc64el.deb ...
863s Unpacking librados2 (19.2.0~is.really.19.1.0-0ubuntu2) ...
863s Selecting previously unselected package libpmemobj1:ppc64el.
863s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ...
863s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ...
863s Selecting previously unselected package librbd1.
863s Preparing to unpack .../18-librbd1_19.2.0~is.really.19.1.0-0ubuntu2_ppc64el.deb ...
863s Unpacking librbd1 (19.2.0~is.really.19.1.0-0ubuntu2) ...
863s Selecting previously unselected package fio.
863s Preparing to unpack .../19-fio_3.37-1_ppc64el.deb ...
863s Unpacking fio (3.37-1) ...
863s Selecting previously unselected package lsscsi.
863s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ...
863s Unpacking lsscsi (0.32-1build1) ...
863s Selecting previously unselected package autopkgtest-satdep.
863s Preparing to unpack .../21-2-autopkgtest-satdep.deb ...
863s Unpacking autopkgtest-satdep (0) ...
863s Setting up libconfig-general-perl (2.65-2) ...
863s Setting up libisns0t64:ppc64el (0.101-1) ...
863s Setting up libboost-thread1.83.0:ppc64el (1.83.0-3.1ubuntu2) ...
863s Setting up libnbd0 (1.20.2-2) ...
863s Setting up libopeniscsiusr (2.1.10-1ubuntu1) ...
863s Setting up libglusterfs0:ppc64el (11.1-5ubuntu1) ...
863s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-3.1ubuntu2) ...
863s Setting up lsscsi (0.32-1build1) ...
863s Setting up libdaxctl1:ppc64el (77-2.2ubuntu1) ...
863s Setting up libndctl6:ppc64el (77-2.2ubuntu1) ...
863s Setting up librdmacm1t64:ppc64el (52.0-2ubuntu1) ...
863s Setting up tgt (1:1.0.85-1.2ubuntu1) ...
864s Created symlink '/etc/systemd/system/multi-user.target.wants/tgt.service' → '/usr/lib/systemd/system/tgt.service'.

864s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ...
864s Setting up libgfxdr0:ppc64el (11.1-5ubuntu1) ...
864s Setting up librados2 (19.2.0~is.really.19.1.0-0ubuntu2) ...
864s Setting up open-iscsi (2.1.10-1ubuntu1) ...
865s Created symlink '/etc/systemd/system/sockets.target.wants/iscsid.socket' → '/usr/lib/systemd/system/iscsid.socket'.

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

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

866s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ...
866s Setting up librbd1 (19.2.0~is.really.19.1.0-0ubuntu2) ...
866s Setting up libgfrpc0:ppc64el (11.1-5ubuntu1) ...
866s Setting up libgfapi0:ppc64el (11.1-5ubuntu1) ...
866s Setting up fio (3.37-1) ...
866s Setting up autopkgtest-satdep (0) ...
866s Processing triggers for man-db (2.12.1-3) ...
868s Processing triggers for initramfs-tools (0.142ubuntu32) ...
868s update-initramfs: Generating /boot/initrd.img-6.11.0-5-generic
868s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
877s Processing triggers for libc-bin (2.40-1ubuntu1) ...
880s (Reading database ... 110056 files and directories currently installed.)
880s Removing autopkgtest-satdep (0) ...
882s autopkgtest [16:07:36]: test tgtbasedmpaths: [-----------------------
882s + targetname=iqn.2016-11.foo.com:target.iscsi
882s + pwd
882s + cwd=/tmp/autopkgtest.QiPd0W/build.eBt/src
882s + testdir=/mnt/tgtmpathtest
882s + localhost=127.0.0.1
882s + portal=127.0.0.1:3260
882s + maxpaths=4
882s + backfn=backingfile
882s + expectwwid=60000000000000000e00000000010001
882s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001
882s + bglog=/tmp/autopkgtest.QiPd0W/tgtbasedmpaths-artifacts/test-background.log
882s + fioprep=/tmp/autopkgtest.QiPd0W/tgtbasedmpaths-artifacts/path-change-prep.fio
882s + fiovrfy=/tmp/autopkgtest.QiPd0W/tgtbasedmpaths-artifacts/path-change-check.fio
882s + mkdir -p /etc/multipath
882s + echo /360000000000000000e00000000010001/
882s + service tgt restart
882s + truncate --size 100M backingfile
882s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi
882s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL
882s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.QiPd0W/build.eBt/src/backingfile
882s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1
882s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi
882s + echo login #1
882s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login
882s login #1
882s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260]
882s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
882s + seq 2 4
882s + echo extra login #2
882s + iscsiadm --mode session -r 1 --op new
882s extra login #2
882s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple)
882s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
882s + echo extra login #3
882s + iscsiadm --mode session -r 1 --op new
882s extra login #3
882s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple)
882s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
882s + echo extra login #4
882s + iscsiadm --mode session -r 1 --op new
882s extra login #4
882s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple)
882s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful.
882s + udevadm settle
883s + sleep 5
888s Status after initial setup
888s Target 1: iqn.2016-11.foo.com:target.iscsi
888s     System information:
888s         Driver: iscsi
888s         State: ready
888s     I_T nexus information:
888s         I_T nexus: 1
888s             Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f alias: autopkgtest
888s             Connection: 0
888s                 IP Address: 127.0.0.1
888s         I_T nexus: 2
888s             Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f alias: autopkgtest
888s             Connection: 0
888s                 IP Address: 127.0.0.1
888s         I_T nexus: 3
888s             Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f alias: autopkgtest
888s             Connection: 0
888s                 IP Address: 127.0.0.1
888s         I_T nexus: 4
888s             Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f alias: autopkgtest
888s             Connection: 0
888s                 IP Address: 127.0.0.1
888s     LUN information:
888s         LUN: 0
888s             Type: controller
888s             SCSI ID: IET     00010000
888s             SCSI SN: beaf10
888s             Size: 0 MB, Block size: 1
888s             Online: Yes
888s             Removable media: No
888s             Prevent removal: No
888s             Readonly: No
888s             SWP: No
888s             Thin-provisioning: No
888s             Backing store type: null
888s             Backing store path: None
888s             Backing store flags: 
888s         LUN: 1
888s             Type: disk
888s             SCSI ID: IET     00010001
888s             SCSI SN: beaf11
888s             Size: 105 MB, Block size: 512
888s             Online: Yes
888s             Removable media: No
888s             Prevent removal: No
888s             Readonly: No
888s             SWP: No
888s             Thin-provisioning: No
888s             Backing store type: rdwr
888s             Backing store path: /tmp/autopkgtest.QiPd0W/build.eBt/src/backingfile
888s             Backing store flags: 
888s     Account information:
888s     ACL information:
888s         ALL
888s + echo Status after initial setup
888s + tgtadm --lld iscsi --mode target --op show
888s + tgtadm --lld iscsi --op show --mode conn --tid 1
888s + iscsiadm --mode session -P 1
888s Session: 4
888s     Connection: 0
888s         Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s         IP Address: 127.0.0.1
888s Session: 3
888s     Connection: 0
888s         Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s         IP Address: 127.0.0.1
888s Session: 2
888s     Connection: 0
888s         Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s         IP Address: 127.0.0.1
888s Session: 1
888s     Connection: 0
888s         Initiator: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s         IP Address: 127.0.0.1
888s Target: iqn.2016-11.foo.com:target.iscsi (non-flash)
888s 	Current Portal: 127.0.0.1:3260,1
888s 	Persistent Portal: 127.0.0.1:3260,1
888s 		**********
888s 		Interface:
888s 		**********
888s 		Iface Name: default
888s 		Iface Transport: tcp
888s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s 		Iface IPaddress: 127.0.0.1
888s 		Iface HWaddress: default
888s 		Iface Netdev: default
888s 		SID: 1
888s 		iSCSI Connection State: LOGGED IN
888s 		iSCSI Session State: LOGGED_IN
888s 		Internal iscsid Session State: NO CHANGE
888s 
888s 		**********
888s 		Interface:
888s 		**********
888s 		Iface Name: default
888s 		Iface Transport: tcp
888s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s 		Iface IPaddress: 127.0.0.1
888s 		Iface HWaddress: default
888s 		Iface Netdev: default
888s 		SID: 2
888s 		iSCSI Connection State: LOGGED IN
888s 		iSCSI Session State: LOGGED_IN
888s 		Internal iscsid Session State: NO CHANGE
888s 
888s 		**********
888s 		Interface:
888s 		**********
888s 		Iface Name: default
888s 		Iface Transport: tcp
888s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s 		Iface IPaddress: 127.0.0.1
888s 		Iface HWaddress: default
888s 		Iface Netdev: default
888s 		SID: 3
888s 		iSCSI Connection State: LOGGED IN
888s 		iSCSI Session State: LOGGED_IN
888s 		Internal iscsid Session State: NO CHANGE
888s 
888s 		**********
888s 		Interface:
888s 		**********
888s 		Iface Name: default
888s 		Iface Transport: tcp
888s 		Iface Initiatorname: iqn.2004-10.com.ubuntu:01:55125e96cd3f
888s 		Iface IPaddress: 127.0.0.1
888s 		Iface HWaddress: default
888s 		Iface Netdev: default
888s 		SID: 4
888s 		iSCSI Connection State: LOGGED IN
888s 		iSCSI Session State: LOGGED_IN
888s 		Internal iscsid Session State: NO CHANGE
888s + lsscsi -liv
888s list_ndevices: scandir: /sys/class/nvme/: No such file or directory
888s + multipath -v3 -ll
888s 49.580669 | set open fds limit to 1073741816/1073741816
888s 49.580719 | _read_bindings_file: reading /etc/multipath/bindings
888s 49.580745 | loading /usr/lib/multipath/libchecktur.so checker
888s 49.580838 | checker tur: message table size = 4
888s 49.580846 | loading /usr/lib/multipath/libprioconst.so prioritizer
888s 49.580955 | _init_foreign: foreign library "nvme" is not enabled
888s 49.586498 | vda: device node name blacklisted
888s 49.586812 | sda: size = 204800
888s 49.586951 | sda: vendor = IET
888s 49.586976 | sda: product = VIRTUAL-DISK
888s 49.587001 | sda: rev = 0001
888s 49.588040 | sda: h:b:t:l = 0:0:0:1
888s 49.588420 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.588425 | sda: uid_attribute = ID_SERIAL (setting: multipath internal)
888s 49.588428 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
888s 49.588610 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.588616 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.588634 | sda: serial = beaf11
888s 49.588638 | sda: detect_checker = yes (setting: multipath internal)
888s 49.588668 | sda checker timeout = 30 s (setting: kernel sysfs)
888s 49.588949 | sda: path_checker = tur (setting: multipath internal)
888s 49.589058 | sda: tur state = up
888s 49.589196 | sdb: size = 204800
888s 49.589337 | sdb: vendor = IET
888s 49.589363 | sdb: product = VIRTUAL-DISK
888s 49.589387 | sdb: rev = 0001
888s 49.590050 | sdb: h:b:t:l = 1:0:0:1
888s 49.590417 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.590421 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal)
888s 49.590423 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
888s 49.590574 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.590578 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.590592 | sdb: serial = beaf11
888s 49.590595 | sdb: detect_checker = yes (setting: multipath internal)
888s 49.590623 | sdb checker timeout = 30 s (setting: kernel sysfs)
888s 49.590775 | sdb: path_checker = tur (setting: multipath internal)
888s 49.590870 | sdb: tur state = up
888s 49.591000 | sdc: size = 204800
888s 49.591156 | sdc: vendor = IET
888s 49.591182 | sdc: product = VIRTUAL-DISK
888s 49.591205 | sdc: rev = 0001
888s 49.591929 | sdc: h:b:t:l = 2:0:0:1
888s 49.592375 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.592387 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal)
888s 49.592389 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
888s 49.592544 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.592548 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.592662 | sdc: serial = beaf11
888s 49.592665 | sdc: detect_checker = yes (setting: multipath internal)
888s 49.592696 | sdc checker timeout = 30 s (setting: kernel sysfs)
888s 49.593596 | sdc: path_checker = tur (setting: multipath internal)
888s 49.593694 | sdc: tur state = up
888s 49.593837 | sdd: size = 204800
888s 49.593980 | sdd: vendor = IET
888s 49.594006 | sdd: product = VIRTUAL-DISK
888s 49.594030 | sdd: rev = 0001
888s 49.594702 | sdd: h:b:t:l = 3:0:0:1
888s [0:0:0:0]    storage IET      Controller       0001  -          -
888s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/0:0:0:0  [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:0]
888s [0:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sda   360000000000000000e00000000010001
888s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/0:0:0:1  [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:1]
888s [1:0:0:0]    storage IET      Controller       0001  -          -
888s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/1:0:0:0  [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:0]
888s [1:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sdb   360000000000000000e00000000010001
888s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/1:0:0:1  [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:1]
888s [2:0:0:0]    storage IET      Controller       0001  -          -
888s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/2:0:0:0  [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:0]
888s [2:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sdc   360000000000000000e00000000010001
888s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/2:0:0:1  [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:1]
888s [3:0:0:0]    storage IET      Controller       0001  -          -
888s   state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/3:0:0:0  [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:0]
888s [3:0:0:1]    disk    IET      VIRTUAL-DISK     0001  /dev/sdd   33000000100000001
888s   state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30
888s   dir: /sys/bus/scsi/devices/3:0:0:1  [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:1]
888s NVMe module may not be loaded
888s 49.595090 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.595095 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal)
888s 49.595098 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
888s 49.595256 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.595261 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.595275 | sdd: serial = beaf11
888s 49.595277 | sdd: detect_checker = yes (setting: multipath internal)
888s 49.595304 | sdd checker timeout = 30 s (setting: kernel sysfs)
888s 49.595619 | sdd: path_checker = tur (setting: multipath internal)
888s 49.595712 | sdd: tur state = up
888s 49.595815 | loop0: device node name blacklisted
888s 49.595910 | loop1: device node name blacklisted
888s 49.596002 | loop2: device node name blacklisted
888s 49.596116 | loop3: device node name blacklisted
888s 49.596210 | loop4: device node name blacklisted
888s 49.596302 | loop5: device node name blacklisted
888s 49.596395 | loop6: device node name blacklisted
888s 49.596485 | loop7: device node name blacklisted
888s 49.596584 | dm-0: device node name blacklisted
888s 49.597912 | multipath-tools v0.9.9 (05/03, 2024)
888s 49.597924 | libdevmapper version 1.02.196
888s 49.598068 | kernel device mapper v4.48.0
888s 49.598082 | DM multipath kernel driver v1.14.0
888s 49.598200 | sda: size = 204800
888s 49.598206 | sda: vendor = IET
888s 49.598209 | sda: product = VIRTUAL-DISK
888s 49.598212 | sda: rev = 0001
888s 49.598827 | sda: h:b:t:l = 0:0:0:1
888s 49.598953 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.598976 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.598979 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.598993 | sda: serial = beaf11
888s 49.599167 | sda: tur state = up
888s 49.599175 | sda: uid = 360000000000000000e00000000010001 (udev)
888s 49.599178 | sda: detect_prio = yes (setting: multipath internal)
888s 49.599181 | sda: prio = const (setting: multipath internal)
888s 49.599183 | sda: prio args = "" (setting: multipath internal)
888s 49.599186 | sda: const prio = 1
888s 49.599210 | sdb: size = 204800
888s 49.599217 | sdb: vendor = IET
888s 49.599219 | sdb: product = VIRTUAL-DISK
888s 49.599222 | sdb: rev = 0001
888s 49.599817 | sdb: h:b:t:l = 1:0:0:1
888s 49.599939 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.599961 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.599965 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.599978 | sdb: serial = beaf11
888s 49.600098 | sdb: tur state = up
888s 49.600119 | sdb: uid = 360000000000000000e00000000010001 (udev)
888s 49.600122 | sdb: detect_prio = yes (setting: multipath internal)
888s 49.600124 | sdb: prio = const (setting: multipath internal)
888s 49.600128 | sdb: prio args = "" (setting: multipath internal)
888s 49.600131 | sdb: const prio = 1
888s 49.600154 | sdc: size = 204800
888s 49.600160 | sdc: vendor = IET
888s 49.600163 | sdc: product = VIRTUAL-DISK
888s 49.600166 | sdc: rev = 0001
888s 49.600759 | sdc: h:b:t:l = 2:0:0:1
888s 49.600882 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.600904 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.600907 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.600922 | sdc: serial = beaf11
888s 49.601034 | sdc: tur state = up
888s 49.601041 | sdc: uid = 360000000000000000e00000000010001 (udev)
888s 49.601044 | sdc: detect_prio = yes (setting: multipath internal)
888s 49.601046 | sdc: prio = const (setting: multipath internal)
888s 49.601049 | sdc: prio args = "" (setting: multipath internal)
888s 49.601051 | sdc: const prio = 1
888s 49.601075 | sdd: size = 204800
888s 49.601081 | sdd: vendor = IET
888s 49.601083 | sdd: product = VIRTUAL-DISK
888s 49.601086 | sdd: rev = 0001
888s 49.601679 | sdd: h:b:t:l = 3:0:0:1
888s 49.601799 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi
888s 49.601820 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0
888s 49.601823 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal)
888s 49.601836 | sdd: serial = beaf11
888s 49.602012 | sdd: tur state = up
888s 49.602019 | sdd: uid = 360000000000000000e00000000010001 (udev)
888s 49.602022 | sdd: detect_prio = yes (setting: multipath internal)
888s 49.602024 | sdd: prio = const (setting: multipath internal)
888s 49.602027 | sdd: prio args = "" (setting: multipath internal)
888s 49.602029 | sdd: const prio = 1
888s 49.602863 | unloading tur checker
888s 49.602919 | unloading const prioritizer
888s + dmsetup table
888s + grep . /etc/multipath/bindings /etc/multipath/wwids
888s + systemctl status multipathd.service
888s + systemctl status multipathd.socket
888s ===== paths list =====
888s uuid hcil    dev dev_t pri dm_st chk_st vend/prod/rev         dev_st 
888s      0:0:0:1 sda 8:0   -1  undef undef  IET,VIRTUAL-DISK,0001 unknown
888s      1:0:0:1 sdb 8:16  -1  undef undef  IET,VIRTUAL-DISK,0001 unknown
888s      2:0:0:1 sdc 8:32  -1  undef undef  IET,VIRTUAL-DISK,0001 unknown
888s      3:0:0:1 sdd 8:48  -1  undef undef  IET,VIRTUAL-DISK,0001 unknown
888s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK
888s size=100M features='0' hwhandler='0' wp=rw
888s |-+- policy='service-time 0' prio=1 status=active
888s | `- 0:0:0:1 sda 8:0  active ready running
888s |-+- policy='service-time 0' prio=1 status=enabled
888s | `- 1:0:0:1 sdb 8:16 active ready running
888s |-+- policy='service-time 0' prio=1 status=enabled
888s | `- 2:0:0:1 sdc 8:32 active ready running
888s `-+- policy='service-time 0' prio=1 status=enabled
888s   `- 3:0:0:1 sdd 8:48 active ready running
888s 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 
888s /etc/multipath/bindings:# Multipath bindings, Version : 1.0
888s /etc/multipath/bindings:# NOTE: this file is automatically maintained by the multipath program.
888s /etc/multipath/bindings:# You should not need to edit this file in normal circumstances.
888s /etc/multipath/bindings:#
888s /etc/multipath/bindings:# Format:
888s /etc/multipath/bindings:# alias wwid
888s /etc/multipath/bindings:#
888s /etc/multipath/bindings:mpatha 360000000000000000e00000000010001
888s /etc/multipath/wwids:/360000000000000000e00000000010001/
888s ● multipathd.service - Device-Mapper Multipath Device Controller
888s      Loaded: loaded (/usr/lib/systemd/system/multipathd.service; enabled; preset: enabled)
888s      Active: active (running) since Wed 2024-08-28 16:06:55 UTC; 46s ago
888s  Invocation: b183352949a0460c86fd21466bb6c204
888s TriggeredBy: ○ multipathd.socket
888s    Main PID: 315 (multipathd)
888s      Status: "up"
888s       Tasks: 7
888s      Memory: 27.8M (peak: 37.3M)
888s         CPU: 41ms
888s      CGroup: /system.slice/multipathd.service
888s              └─315 /sbin/multipathd -d -s
888s 
888s Aug 28 16:06:55 autopkgtest multipathd[315]: multipathd v0.9.9: start up
888s Aug 28 16:06:55 autopkgtest multipathd[315]: reconfigure: setting up paths and maps
888s Aug 28 16:06:55 autopkgtest systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller.
888s Aug 28 16:07:36 autopkgtest multipathd[315]: updated bindings file /etc/multipath/bindings
888s Aug 28 16:07:36 autopkgtest multipathd[315]: mpatha: addmap [0 204800 multipath 0 0 1 1 service-time 0 1 1 8:0 1]
888s Aug 28 16:07:36 autopkgtest multipathd[315]: sda [8:0]: path added to devmap mpatha
888s Aug 28 16:07:37 autopkgtest multipathd[315]: mpatha: performing delayed actions
888s Aug 28 16:07:37 autopkgtest multipathd[315]: mpatha: reload [0 204800 multipath 0 0 4 1 service-time 0 1 1 8:0 1 service-time 0 1 1 8:16 1 service-time 0 1 1 8:32 1 service-time 0 1 1 8:48 1]
888s ○ multipathd.socket - multipathd control socket
888s      Loaded: loaded (/usr/lib/systemd/system/multipathd.socket; static)
888s      Active: inactive (dead)
888s    Triggers: ● multipathd.service
888s      Listen: @/org/kernel/linux/storage/multipathd (Stream)
888s autopkgtest [16:07:42]: test tgtbasedmpaths: -----------------------]
888s autopkgtest [16:07:42]: test tgtbasedmpaths:  - - - - - - - - - - results - - - - - - - - - -
888s tgtbasedmpaths       FAIL non-zero exit status 3
889s autopkgtest [16:07:43]: @@@@@@@@@@@@@@@@@@@@ summary
889s initramfs            SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed.
889s initramfs            SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed.
889s kpartx-file-loopback PASS
889s tgtbasedmpaths       FAIL non-zero exit status 3
901s nova [W] Using flock in prodstack6-ppc64el
901s flock: timeout while waiting to get lock
901s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240828-155249-juju-7f2275-prod-proposed-migration-environment-3-977cdf1a-ecd6-4fa0-bd2e-fd3feac44f1c from image adt/ubuntu-oracular-ppc64el-server-20240828.img (UUID e8d8596e-5a77-4997-aa27-9bad68fd8a1f)...
901s nova [W] Using flock in prodstack6-ppc64el
901s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240828-155249-juju-7f2275-prod-proposed-migration-environment-3-977cdf1a-ecd6-4fa0-bd2e-fd3feac44f1c from image adt/ubuntu-oracular-ppc64el-server-20240828.img (UUID e8d8596e-5a77-4997-aa27-9bad68fd8a1f)...