0s autopkgtest [18:28:45]: starting date and time: 2024-04-19 18:28:45+0000 0s autopkgtest [18:28:45]: git checkout: 43bc6cdf gitlab-ci: do not include the salsa pipeline 0s autopkgtest [18:28:45]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.uz_r043r/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:systemd --apt-upgrade multipath-tools --timeout-short=300 --timeout-copy=20000 --timeout-test=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=systemd/255.4-1ubuntu8 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-big --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-17.secgroup --name adt-noble-ppc64el-multipath-tools-20240419-182845-juju-7f2275-prod-proposed-migration-environment-2-39903355-4cfe-4404-a03c-2a3f048ab5cf --image adt/ubuntu-noble-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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 121s autopkgtest [18:30:46]: testbed dpkg architecture: ppc64el 121s autopkgtest [18:30:46]: testbed apt version: 2.7.14build2 121s autopkgtest [18:30:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 122s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 122s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [2340 B] 122s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [12.3 kB] 122s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [264 kB] 122s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [48.6 kB] 122s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [81.4 kB] 122s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 122s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [976 B] 122s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 122s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [236 kB] 122s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 122s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [1376 B] 122s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 124s Fetched 776 kB in 1s (968 kB/s) 124s Reading package lists... 126s sh: 4: dhclient: not found 127s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s Calculating upgrade... 127s The following packages will be upgraded: 127s libnss-systemd libpam-systemd libsystemd-shared libsystemd0 libudev1 systemd 127s systemd-dev systemd-resolved systemd-sysv systemd-timesyncd udev 127s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s Need to get 9899 kB of archives. 127s After this operation, 6144 B of additional disk space will be used. 127s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-dev all 255.4-1ubuntu8 [104 kB] 128s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-timesyncd ppc64el 255.4-1ubuntu8 [37.9 kB] 128s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-resolved ppc64el 255.4-1ubuntu8 [347 kB] 128s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libsystemd-shared ppc64el 255.4-1ubuntu8 [2351 kB] 128s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libsystemd0 ppc64el 255.4-1ubuntu8 [526 kB] 128s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-sysv ppc64el 255.4-1ubuntu8 [11.9 kB] 128s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libnss-systemd ppc64el 255.4-1ubuntu8 [208 kB] 128s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libpam-systemd ppc64el 255.4-1ubuntu8 [304 kB] 128s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd ppc64el 255.4-1ubuntu8 [3771 kB] 128s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el udev ppc64el 255.4-1ubuntu8 [2038 kB] 128s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libudev1 ppc64el 255.4-1ubuntu8 [201 kB] 129s Fetched 9899 kB in 1s (8673 kB/s) 129s (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 ... 72009 files and directories currently installed.) 129s Preparing to unpack .../systemd-dev_255.4-1ubuntu8_all.deb ... 129s Unpacking systemd-dev (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../systemd-timesyncd_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking systemd-timesyncd (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../systemd-resolved_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking systemd-resolved (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../libsystemd-shared_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking libsystemd-shared:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../libsystemd0_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking libsystemd0:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Setting up libsystemd0:ppc64el (255.4-1ubuntu8) ... 129s (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 ... 72009 files and directories currently installed.) 129s Preparing to unpack .../0-systemd-sysv_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking systemd-sysv (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../1-libnss-systemd_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking libnss-systemd:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../2-libpam-systemd_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking libpam-systemd:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../3-systemd_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking systemd (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../4-udev_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking udev (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Preparing to unpack .../5-libudev1_255.4-1ubuntu8_ppc64el.deb ... 129s Unpacking libudev1:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 129s Setting up libudev1:ppc64el (255.4-1ubuntu8) ... 129s Setting up systemd-dev (255.4-1ubuntu8) ... 129s Setting up libsystemd-shared:ppc64el (255.4-1ubuntu8) ... 129s Setting up systemd (255.4-1ubuntu8) ... 130s Setting up systemd-timesyncd (255.4-1ubuntu8) ... 131s Setting up udev (255.4-1ubuntu8) ... 131s Setting up systemd-resolved (255.4-1ubuntu8) ... 132s Setting up systemd-sysv (255.4-1ubuntu8) ... 132s Setting up libnss-systemd:ppc64el (255.4-1ubuntu8) ... 132s Setting up libpam-systemd:ppc64el (255.4-1ubuntu8) ... 132s Processing triggers for libc-bin (2.39-0ubuntu8) ... 132s Processing triggers for man-db (2.12.0-4build2) ... 133s Processing triggers for dbus (1.14.10-4ubuntu4) ... 133s Processing triggers for initramfs-tools (0.142ubuntu25) ... 133s update-initramfs: Generating /boot/initrd.img-6.8.0-22-generic 133s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 141s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 141s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 141s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 142s Reading package lists... 142s Reading package lists... 142s Building dependency tree... 142s Reading state information... 142s Calculating upgrade... 142s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s Reading package lists... 142s Building dependency tree... 142s Reading state information... 143s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 143s autopkgtest [18:31:08]: rebooting testbed after setup commands that affected boot 312s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 319s autopkgtest [18:34:04]: testbed running kernel: Linux 6.8.0-22-generic #22-Ubuntu SMP Thu Apr 4 22:47:57 UTC 2024 322s autopkgtest [18:34:07]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools 325s Get:1 http://ftpmaster.internal/ubuntu noble/main multipath-tools 0.9.4-5ubuntu8 (dsc) [2770 B] 325s Get:2 http://ftpmaster.internal/ubuntu noble/main multipath-tools 0.9.4-5ubuntu8 (tar) [554 kB] 325s Get:3 http://ftpmaster.internal/ubuntu noble/main multipath-tools 0.9.4-5ubuntu8 (diff) [42.7 kB] 325s gpgv: Signature made Sun Apr 7 00:31:08 2024 UTC 325s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 325s gpgv: issuer "steve.langasek@ubuntu.com" 325s gpgv: Can't check signature: No public key 325s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.4-5ubuntu8.dsc: no acceptable signature found 325s autopkgtest [18:34:10]: testing package multipath-tools version 0.9.4-5ubuntu8 326s autopkgtest [18:34:11]: build not needed 331s autopkgtest [18:34:16]: test kpartx-file-loopback: preparing testbed 332s Reading package lists... 332s Building dependency tree... 332s Reading state information... 332s Starting pkgProblemResolver with broken count: 0 332s Starting 2 pkgProblemResolver with broken count: 0 332s Done 333s The following additional packages will be installed: 333s liburing2 qemu-utils 333s Recommended packages: 333s qemu-block-extra 333s The following NEW packages will be installed: 333s autopkgtest-satdep liburing2 qemu-utils 333s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 333s Need to get 2329 kB/2330 kB of archives. 333s After this operation, 15.4 MB of additional disk space will be used. 333s Get:1 /tmp/autopkgtest.BvrWQj/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 333s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el liburing2 ppc64el 2.5-1build1 [26.0 kB] 333s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el qemu-utils ppc64el 1:8.2.2+ds-0ubuntu1 [2303 kB] 334s Fetched 2329 kB in 1s (3082 kB/s) 334s Selecting previously unselected package liburing2:ppc64el. 334s (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 ... 72009 files and directories currently installed.) 334s Preparing to unpack .../liburing2_2.5-1build1_ppc64el.deb ... 334s Unpacking liburing2:ppc64el (2.5-1build1) ... 334s Selecting previously unselected package qemu-utils. 334s Preparing to unpack .../qemu-utils_1%3a8.2.2+ds-0ubuntu1_ppc64el.deb ... 334s Unpacking qemu-utils (1:8.2.2+ds-0ubuntu1) ... 334s Selecting previously unselected package autopkgtest-satdep. 334s Preparing to unpack .../1-autopkgtest-satdep.deb ... 334s Unpacking autopkgtest-satdep (0) ... 334s Setting up liburing2:ppc64el (2.5-1build1) ... 334s Setting up qemu-utils (1:8.2.2+ds-0ubuntu1) ... 334s Setting up autopkgtest-satdep (0) ... 334s Processing triggers for man-db (2.12.0-4build2) ... 335s Processing triggers for libc-bin (2.39-0ubuntu8) ... 337s (Reading database ... 72029 files and directories currently installed.) 337s Removing autopkgtest-satdep (0) ... 337s autopkgtest [18:34:22]: test kpartx-file-loopback: [----------------------- 338s Formatting 'foo.img', fmt=raw size=20971520 339s Creating new GPT entries in memory. 339s Warning: The kernel is still using the old partition table. 339s The new table will be used at the next reboot or after you 339s run partprobe(8) or kpartx(8) 339s The operation has completed successfully. 339s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 339s standard_filename: OK 339s del devmap : loop0p1 339s No devices found 339s standard_filename_cleanup: OK 339s Formatting 'fou du FaFa.img', fmt=raw size=20971520 340s Creating new GPT entries in memory. 340s Warning: The kernel is still using the old partition table. 340s The new table will be used at the next reboot or after you 340s run partprobe(8) or kpartx(8) 340s The operation has completed successfully. 340s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 340s filename_with_spaces: OK 340s del devmap : loop0p1 340s No devices found 340s filename_with_spaces_cleanup: OK 340s autopkgtest [18:34:25]: test kpartx-file-loopback: -----------------------] 341s kpartx-file-loopback PASS 341s autopkgtest [18:34:26]: test kpartx-file-loopback: - - - - - - - - - - results - - - - - - - - - - 341s autopkgtest [18:34:26]: test tgtbasedmpaths: preparing testbed 451s autopkgtest [18:36:16]: testbed dpkg architecture: ppc64el 451s autopkgtest [18:36:16]: testbed apt version: 2.7.14build2 451s autopkgtest [18:36:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 452s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 452s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [12.3 kB] 452s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [2340 B] 452s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [264 kB] 453s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [48.6 kB] 453s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [81.4 kB] 453s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 453s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [976 B] 453s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 453s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [236 kB] 453s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 453s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [1376 B] 453s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 455s Fetched 776 kB in 1s (934 kB/s) 455s Reading package lists... 456s sh: 4: dhclient: not found 457s Reading package lists... 457s Building dependency tree... 457s Reading state information... 458s Calculating upgrade... 458s The following packages will be upgraded: 458s libnss-systemd libpam-systemd libsystemd-shared libsystemd0 libudev1 systemd 458s systemd-dev systemd-resolved systemd-sysv systemd-timesyncd udev 458s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 458s Need to get 9899 kB of archives. 458s After this operation, 6144 B of additional disk space will be used. 458s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-dev all 255.4-1ubuntu8 [104 kB] 458s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-timesyncd ppc64el 255.4-1ubuntu8 [37.9 kB] 458s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-resolved ppc64el 255.4-1ubuntu8 [347 kB] 458s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libsystemd-shared ppc64el 255.4-1ubuntu8 [2351 kB] 459s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libsystemd0 ppc64el 255.4-1ubuntu8 [526 kB] 459s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd-sysv ppc64el 255.4-1ubuntu8 [11.9 kB] 459s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libnss-systemd ppc64el 255.4-1ubuntu8 [208 kB] 459s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libpam-systemd ppc64el 255.4-1ubuntu8 [304 kB] 459s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el systemd ppc64el 255.4-1ubuntu8 [3771 kB] 459s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el udev ppc64el 255.4-1ubuntu8 [2038 kB] 459s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libudev1 ppc64el 255.4-1ubuntu8 [201 kB] 459s Fetched 9899 kB in 1s (9782 kB/s) 459s (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 ... 72009 files and directories currently installed.) 459s Preparing to unpack .../systemd-dev_255.4-1ubuntu8_all.deb ... 459s Unpacking systemd-dev (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 459s Preparing to unpack .../systemd-timesyncd_255.4-1ubuntu8_ppc64el.deb ... 459s Unpacking systemd-timesyncd (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../systemd-resolved_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking systemd-resolved (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../libsystemd-shared_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking libsystemd-shared:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../libsystemd0_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking libsystemd0:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Setting up libsystemd0:ppc64el (255.4-1ubuntu8) ... 460s (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 ... 72009 files and directories currently installed.) 460s Preparing to unpack .../0-systemd-sysv_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking systemd-sysv (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../1-libnss-systemd_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking libnss-systemd:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../2-libpam-systemd_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking libpam-systemd:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../3-systemd_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking systemd (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../4-udev_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking udev (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Preparing to unpack .../5-libudev1_255.4-1ubuntu8_ppc64el.deb ... 460s Unpacking libudev1:ppc64el (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 460s Setting up libudev1:ppc64el (255.4-1ubuntu8) ... 460s Setting up systemd-dev (255.4-1ubuntu8) ... 460s Setting up libsystemd-shared:ppc64el (255.4-1ubuntu8) ... 460s Setting up systemd (255.4-1ubuntu8) ... 461s Setting up systemd-timesyncd (255.4-1ubuntu8) ... 462s Setting up udev (255.4-1ubuntu8) ... 463s Setting up systemd-resolved (255.4-1ubuntu8) ... 463s Setting up systemd-sysv (255.4-1ubuntu8) ... 463s Setting up libnss-systemd:ppc64el (255.4-1ubuntu8) ... 463s Setting up libpam-systemd:ppc64el (255.4-1ubuntu8) ... 463s Processing triggers for libc-bin (2.39-0ubuntu8) ... 464s Processing triggers for man-db (2.12.0-4build2) ... 465s Processing triggers for dbus (1.14.10-4ubuntu4) ... 465s Processing triggers for initramfs-tools (0.142ubuntu25) ... 465s update-initramfs: Generating /boot/initrd.img-6.8.0-22-generic 465s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 474s Reading package lists... 475s Building dependency tree... 475s Reading state information... 475s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 475s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 475s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 476s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 476s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 477s Reading package lists... 477s Reading package lists... 477s Building dependency tree... 477s Reading state information... 477s Calculating upgrade... 477s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 477s Reading package lists... 477s Building dependency tree... 477s Reading state information... 478s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 478s autopkgtest [18:36:43]: rebooting testbed after setup commands that affected boot 521s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 535s Reading package lists... 535s Building dependency tree... 535s Reading state information... 535s Starting pkgProblemResolver with broken count: 0 535s Starting 2 pkgProblemResolver with broken count: 0 535s Done 536s The following additional packages will be installed: 536s fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl 536s libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0 536s libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1 536s librdmacm1t64 lsscsi open-iscsi tgt 536s Suggested packages: 536s fio-examples gnuplot tgt-glusterfs tgt-rbd 536s Recommended packages: 536s finalrd 536s The following NEW packages will be installed: 536s autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0 536s libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 536s libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1 536s libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt 536s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 536s Need to get 10.8 MB/10.8 MB of archives. 536s After this operation, 49.9 MB of additional disk space will be used. 536s Get:1 /tmp/autopkgtest.BvrWQj/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [736 B] 536s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el libopeniscsiusr ppc64el 2.1.9-3ubuntu4 [54.4 kB] 536s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el libisns0t64 ppc64el 0.101-0.3build3 [119 kB] 536s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el open-iscsi ppc64el 2.1.9-3ubuntu4 [389 kB] 536s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el librdmacm1t64 ppc64el 50.0-2build2 [80.9 kB] 536s Get:6 http://ftpmaster.internal/ubuntu noble/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB] 536s Get:7 http://ftpmaster.internal/ubuntu noble/universe ppc64el tgt ppc64el 1:1.0.85-1.1ubuntu6 [256 kB] 536s Get:8 http://ftpmaster.internal/ubuntu noble/universe ppc64el libgfxdr0 ppc64el 11.1-4build3 [21.5 kB] 536s Get:9 http://ftpmaster.internal/ubuntu noble/universe ppc64el libglusterfs0 ppc64el 11.1-4build3 [311 kB] 536s Get:10 http://ftpmaster.internal/ubuntu noble/universe ppc64el libgfrpc0 ppc64el 11.1-4build3 [47.0 kB] 536s Get:11 http://ftpmaster.internal/ubuntu noble/universe ppc64el libgfapi0 ppc64el 11.1-4build3 [100 kB] 536s Get:12 http://ftpmaster.internal/ubuntu noble/universe ppc64el libnbd0 ppc64el 1.20.0-1 [97.3 kB] 536s Get:13 http://ftpmaster.internal/ubuntu noble/main ppc64el libdaxctl1 ppc64el 77-2ubuntu2 [23.7 kB] 537s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el libndctl6 ppc64el 77-2ubuntu2 [74.9 kB] 537s Get:15 http://ftpmaster.internal/ubuntu noble/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB] 537s Get:16 http://ftpmaster.internal/ubuntu noble/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-2.1ubuntu3 [260 kB] 537s Get:17 http://ftpmaster.internal/ubuntu noble/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-2.1ubuntu3 [280 kB] 537s Get:18 http://ftpmaster.internal/ubuntu noble/main ppc64el librados2 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu4 [4049 kB] 537s Get:19 http://ftpmaster.internal/ubuntu noble/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB] 537s Get:20 http://ftpmaster.internal/ubuntu noble/main ppc64el librbd1 ppc64el 19.2.0~git20240301.4c76c50-0ubuntu4 [3612 kB] 538s Get:21 http://ftpmaster.internal/ubuntu noble/universe ppc64el fio ppc64el 3.36-1build2 [716 kB] 538s Get:22 http://ftpmaster.internal/ubuntu noble/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB] 538s Preconfiguring packages ... 538s Fetched 10.8 MB in 2s (4857 kB/s) 538s Selecting previously unselected package libopeniscsiusr. 538s (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 ... 72009 files and directories currently installed.) 538s Preparing to unpack .../00-libopeniscsiusr_2.1.9-3ubuntu4_ppc64el.deb ... 538s Unpacking libopeniscsiusr (2.1.9-3ubuntu4) ... 538s Selecting previously unselected package libisns0t64:ppc64el. 538s Preparing to unpack .../01-libisns0t64_0.101-0.3build3_ppc64el.deb ... 538s Unpacking libisns0t64:ppc64el (0.101-0.3build3) ... 538s Selecting previously unselected package open-iscsi. 538s Preparing to unpack .../02-open-iscsi_2.1.9-3ubuntu4_ppc64el.deb ... 539s Unpacking open-iscsi (2.1.9-3ubuntu4) ... 539s Selecting previously unselected package librdmacm1t64:ppc64el. 539s Preparing to unpack .../03-librdmacm1t64_50.0-2build2_ppc64el.deb ... 539s Unpacking librdmacm1t64:ppc64el (50.0-2build2) ... 539s Selecting previously unselected package libconfig-general-perl. 539s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ... 539s Unpacking libconfig-general-perl (2.65-2) ... 539s Selecting previously unselected package tgt. 539s Preparing to unpack .../05-tgt_1%3a1.0.85-1.1ubuntu6_ppc64el.deb ... 539s Unpacking tgt (1:1.0.85-1.1ubuntu6) ... 539s Selecting previously unselected package libgfxdr0:ppc64el. 539s Preparing to unpack .../06-libgfxdr0_11.1-4build3_ppc64el.deb ... 539s Unpacking libgfxdr0:ppc64el (11.1-4build3) ... 539s Selecting previously unselected package libglusterfs0:ppc64el. 539s Preparing to unpack .../07-libglusterfs0_11.1-4build3_ppc64el.deb ... 539s Unpacking libglusterfs0:ppc64el (11.1-4build3) ... 539s Selecting previously unselected package libgfrpc0:ppc64el. 539s Preparing to unpack .../08-libgfrpc0_11.1-4build3_ppc64el.deb ... 539s Unpacking libgfrpc0:ppc64el (11.1-4build3) ... 539s Selecting previously unselected package libgfapi0:ppc64el. 539s Preparing to unpack .../09-libgfapi0_11.1-4build3_ppc64el.deb ... 539s Unpacking libgfapi0:ppc64el (11.1-4build3) ... 539s Selecting previously unselected package libnbd0. 539s Preparing to unpack .../10-libnbd0_1.20.0-1_ppc64el.deb ... 539s Unpacking libnbd0 (1.20.0-1) ... 539s Selecting previously unselected package libdaxctl1:ppc64el. 539s Preparing to unpack .../11-libdaxctl1_77-2ubuntu2_ppc64el.deb ... 539s Unpacking libdaxctl1:ppc64el (77-2ubuntu2) ... 539s Selecting previously unselected package libndctl6:ppc64el. 539s Preparing to unpack .../12-libndctl6_77-2ubuntu2_ppc64el.deb ... 539s Unpacking libndctl6:ppc64el (77-2ubuntu2) ... 539s Selecting previously unselected package libpmem1:ppc64el. 539s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ... 539s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 539s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el. 539s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-2.1ubuntu3_ppc64el.deb ... 539s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-2.1ubuntu3) ... 539s Selecting previously unselected package libboost-thread1.83.0:ppc64el. 539s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-2.1ubuntu3_ppc64el.deb ... 539s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-2.1ubuntu3) ... 539s Selecting previously unselected package librados2. 539s Preparing to unpack .../16-librados2_19.2.0~git20240301.4c76c50-0ubuntu4_ppc64el.deb ... 539s Unpacking librados2 (19.2.0~git20240301.4c76c50-0ubuntu4) ... 539s Selecting previously unselected package libpmemobj1:ppc64el. 539s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ... 539s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 539s Selecting previously unselected package librbd1. 539s Preparing to unpack .../18-librbd1_19.2.0~git20240301.4c76c50-0ubuntu4_ppc64el.deb ... 539s Unpacking librbd1 (19.2.0~git20240301.4c76c50-0ubuntu4) ... 539s Selecting previously unselected package fio. 539s Preparing to unpack .../19-fio_3.36-1build2_ppc64el.deb ... 539s Unpacking fio (3.36-1build2) ... 539s Selecting previously unselected package lsscsi. 539s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ... 539s Unpacking lsscsi (0.32-1build1) ... 539s Selecting previously unselected package autopkgtest-satdep. 539s Preparing to unpack .../21-2-autopkgtest-satdep.deb ... 539s Unpacking autopkgtest-satdep (0) ... 539s Setting up libconfig-general-perl (2.65-2) ... 539s Setting up libisns0t64:ppc64el (0.101-0.3build3) ... 539s Setting up libboost-thread1.83.0:ppc64el (1.83.0-2.1ubuntu3) ... 539s Setting up libnbd0 (1.20.0-1) ... 539s Setting up libopeniscsiusr (2.1.9-3ubuntu4) ... 539s Setting up libglusterfs0:ppc64el (11.1-4build3) ... 539s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-2.1ubuntu3) ... 539s Setting up lsscsi (0.32-1build1) ... 539s Setting up libdaxctl1:ppc64el (77-2ubuntu2) ... 539s Setting up libndctl6:ppc64el (77-2ubuntu2) ... 539s Setting up librdmacm1t64:ppc64el (50.0-2build2) ... 539s Setting up tgt (1:1.0.85-1.1ubuntu6) ... 540s Created symlink /etc/systemd/system/multi-user.target.wants/tgt.service → /usr/lib/systemd/system/tgt.service. 540s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 540s Setting up libgfxdr0:ppc64el (11.1-4build3) ... 540s Setting up librados2 (19.2.0~git20240301.4c76c50-0ubuntu4) ... 540s Setting up open-iscsi (2.1.9-3ubuntu4) ... 541s Created symlink /etc/systemd/system/sockets.target.wants/iscsid.socket → /usr/lib/systemd/system/iscsid.socket. 542s Created symlink /etc/systemd/system/iscsi.service → /usr/lib/systemd/system/open-iscsi.service. 542s Created symlink /etc/systemd/system/sysinit.target.wants/open-iscsi.service → /usr/lib/systemd/system/open-iscsi.service. 542s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 542s Setting up librbd1 (19.2.0~git20240301.4c76c50-0ubuntu4) ... 542s Setting up libgfrpc0:ppc64el (11.1-4build3) ... 542s Setting up libgfapi0:ppc64el (11.1-4build3) ... 542s Setting up fio (3.36-1build2) ... 543s Setting up autopkgtest-satdep (0) ... 543s Processing triggers for man-db (2.12.0-4build2) ... 544s Processing triggers for initramfs-tools (0.142ubuntu25) ... 544s update-initramfs: Generating /boot/initrd.img-6.8.0-22-generic 544s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 552s Processing triggers for libc-bin (2.39-0ubuntu8) ... 555s (Reading database ... 72250 files and directories currently installed.) 555s Removing autopkgtest-satdep (0) ... 557s autopkgtest [18:38:02]: test tgtbasedmpaths: [----------------------- 557s + targetname=iqn.2016-11.foo.com:target.iscsi 557s + pwd 557s + cwd=/tmp/autopkgtest.BvrWQj/build.Z0E/src 557s + testdir=/mnt/tgtmpathtest 557s + localhost=127.0.0.1 557s + portal=127.0.0.1:3260 557s + maxpaths=4 557s + backfn=backingfile 557s + expectwwid=60000000000000000e00000000010001 557s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001 557s + bglog=/tmp/autopkgtest.BvrWQj/tgtbasedmpaths-artifacts/test-background.log 557s + fioprep=/tmp/autopkgtest.BvrWQj/tgtbasedmpaths-artifacts/path-change-prep.fio 557s + fiovrfy=/tmp/autopkgtest.BvrWQj/tgtbasedmpaths-artifacts/path-change-check.fio 557s + service tgt restart 558s + truncate --size 100M backingfile 558s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi 558s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL 558s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.BvrWQj/build.Z0E/src/backingfile 558s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1 558s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi 558s login #1 558s + echo login #1 558s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login 558s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 558s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 558s + seq 2 4 558s + echo extra login #2 558s + iscsiadm --mode session -r 1 --op new 558s extra login #2 558s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 558s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 558s extra login #3 558s + echo extra login #3 558s + iscsiadm --mode session -r 1 --op new 558s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 558s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 558s extra login #4 558s + echo extra login #4 558s + iscsiadm --mode session -r 1 --op new 558s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 558s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 558s + udevadm settle 558s + sleep 5 563s Status after initial setup 563s + echo Status after initial setup 563s + tgtadm --lld iscsi --mode target --op show 563s + tgtadm --lld iscsi --op show --mode conn --tid 1 563s Target 1: iqn.2016-11.foo.com:target.iscsi 563s System information: 563s Driver: iscsi 563s State: ready 563s I_T nexus information: 563s I_T nexus: 1 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 alias: autopkgtest 563s Connection: 0 563s IP Address: 127.0.0.1 563s I_T nexus: 2 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 alias: autopkgtest 563s Connection: 0 563s IP Address: 127.0.0.1 563s I_T nexus: 3 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 alias: autopkgtest 563s Connection: 0 563s IP Address: 127.0.0.1 563s I_T nexus: 4 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 alias: autopkgtest 563s Connection: 0 563s IP Address: 127.0.0.1 563s LUN information: 563s LUN: 0 563s Type: controller 563s SCSI ID: IET 00010000 563s SCSI SN: beaf10 563s Size: 0 MB, Block size: 1 563s Online: Yes 563s Removable media: No 563s Prevent removal: No 563s Readonly: No 563s SWP: No 563s Thin-provisioning: No 563s Backing store type: null 563s Backing store path: None 563s Backing store flags: 563s LUN: 1 563s Type: disk 563s SCSI ID: IET 00010001 563s SCSI SN: beaf11 563s Size: 105 MB, Block size: 512 563s Online: Yes 563s Removable media: No 563s Prevent removal: No 563s Readonly: No 563s SWP: No 563s Thin-provisioning: No 563s Backing store type: rdwr 563s Backing store path: /tmp/autopkgtest.BvrWQj/build.Z0E/src/backingfile 563s Backing store flags: 563s Account information: 563s ACL information: 563s ALL 563s Session: 4 563s Connection: 0 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s IP Address: 127.0.0.1 563s Session: 3 563s Connection: 0 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s IP Address: 127.0.0.1 563s Session: 2 563s Connection: 0 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s IP Address: 127.0.0.1 563s Session: 1 563s Connection: 0 563s Initiator: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s IP Address: 127.0.0.1 563s + iscsiadm --mode session -P 1 563s Target: iqn.2016-11.foo.com:target.iscsi (non-flash) 563s Current Portal: 127.0.0.1:3260,1 563s Persistent Portal: 127.0.0.1:3260,1 563s ********** 563s Interface: 563s ********** 563s Iface Name: default 563s Iface Transport: tcp 563s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s Iface IPaddress: 127.0.0.1 563s Iface HWaddress: default 563s Iface Netdev: default 563s SID: 1 563s iSCSI Connection State: LOGGED IN 563s iSCSI Session State: LOGGED_IN 563s Internal iscsid Session State: NO CHANGE 563s 563s ********** 563s Interface: 563s ********** 563s Iface Name: default 563s Iface Transport: tcp 563s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s Iface IPaddress: 127.0.0.1 563s Iface HWaddress: default 563s Iface Netdev: default 563s SID: 2 563s iSCSI Connection State: LOGGED IN 563s iSCSI Session State: LOGGED_IN 563s Internal iscsid Session State: NO CHANGE 563s 563s ********** 563s Interface: 563s ********** 563s Iface Name: default 563s Iface Transport: tcp 563s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s Iface IPaddress: 127.0.0.1 563s Iface HWaddress: default 563s Iface Netdev: default 563s SID: 3 563s iSCSI Connection State: LOGGED IN 563s iSCSI Session State: LOGGED_IN 563s Internal iscsid Session State: NO CHANGE 563s 563s ********** 563s Interface: 563s ********** 563s Iface Name: default 563s Iface Transport: tcp 563s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:45f668fcff46 563s Iface IPaddress: 127.0.0.1 563s Iface HWaddress: default 563s Iface Netdev: default 563s SID: 4 563s iSCSI Connection State: LOGGED IN 563s iSCSI Session State: LOGGED_IN 563s Internal iscsid Session State: NO CHANGE 563s + lsscsi -liv 563s list_ndevices: scandir: /sys/class/nvme/: No such file or directory 563s [0:0:0:0] disk QEMU QEMU HARDDISK 2.5+ /dev/sda 0QEMU_QEMU_HARDDISK_drive-scsi0-0-0-0 563s state=running queue_depth=128 scsi_level=6 type=0 device_blocked=0 timeout=30 563s 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] 563s [1:0:0:0] storage IET Controller 0001 - - 563s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/1:0:0:0 [/sys/devices/platform/host1/session1/target1:0:0/1:0:0:0] 563s [1:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdb 360000000000000000e00000000010001 563s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/1:0:0:1 [/sys/devices/platform/host1/session1/target1:0:0/1:0:0:1] 563s [2:0:0:0] storage IET Controller 0001 - - 563s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/2:0:0:0 [/sys/devices/platform/host2/session2/target2:0:0/2:0:0:0] 563s [2:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdc 360000000000000000e00000000010001 563s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/2:0:0:1 [/sys/devices/platform/host2/session2/target2:0:0/2:0:0:1] 563s [3:0:0:0] storage IET Controller 0001 - - 563s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/3:0:0:0 [/sys/devices/platform/host3/session3/target3:0:0/3:0:0:0] 563s [3:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdd 360000000000000000e00000000010001 563s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/3:0:0:1 [/sys/devices/platform/host3/session3/target3:0:0/3:0:0:1] 563s [4:0:0:0] storage IET Controller 0001 - - 563s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/4:0:0:0 [/sys/devices/platform/host4/session4/target4:0:0/4:0:0:0] 563s [4:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sde 33000000100000001 563s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 563s dir: /sys/bus/scsi/devices/4:0:0:1 [/sys/devices/platform/host4/session4/target4:0:0/4:0:0:1] 563s NVMe module may not be loaded 563s + multipath -v3 -ll 563s 52.551986 | set open fds limit to 1048576/1048576 563s 52.552047 | loading /lib/multipath/libchecktur.so checker 563s 52.552176 | checker tur: message table size = 3 563s 52.552215 | loading /lib/multipath/libprioconst.so prioritizer 563s 52.552358 | _init_foreign: foreign library "nvme" is not enabled 563s 52.558487 | sda: size = 167772160 563s 52.558675 | sda: vendor = QEMU 563s 52.558726 | sda: product = QEMU HARDDISK 563s 52.558752 | sda: rev = 2.5+ 563s 52.559429 | sda: h:b:t:l = 0:0:0:0 563s 52.559778 | sda: tgt_node_name = 563s 52.559803 | sda: uid_attribute = ID_SERIAL (setting: multipath internal) 563s 52.559806 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 563s 52.559968 | sda: 10443 cyl, 255 heads, 63 sectors/track, start at 0 563s 52.559992 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.560040 | __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 563s 52.560046 | failed to read sysfs vpd pg80: No such file or directory 563s 52.560311 | sda: fail to get serial 563s 52.560341 | sda: detect_checker = yes (setting: multipath internal) 563s 52.560566 | sda: path_checker = tur (setting: multipath internal) 563s 52.560590 | sda: checker timeout = 30 s (setting: kernel sysfs) 563s 52.560753 | sda: tur state = up 563s 52.561075 | sdb: size = 204800 563s 52.561243 | sdb: vendor = IET 563s 52.561289 | sdb: product = VIRTUAL-DISK 563s 52.561314 | sdb: rev = 0001 563s 52.562009 | sdb: h:b:t:l = 1:0:0:1 563s 52.562441 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.562465 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal) 563s 52.562468 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 563s 52.562640 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.562663 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.562682 | sdb: serial = beaf11 563s 52.562685 | sdb: detect_checker = yes (setting: multipath internal) 563s 52.563014 | sdb: path_checker = tur (setting: multipath internal) 563s 52.563038 | sdb: checker timeout = 30 s (setting: kernel sysfs) 563s 52.563227 | sdb: tur state = up 563s 52.563387 | sdc: size = 204800 563s 52.563553 | sdc: vendor = IET 563s 52.563599 | sdc: product = VIRTUAL-DISK 563s 52.563624 | sdc: rev = 0001 563s 52.564393 | sdc: h:b:t:l = 2:0:0:1 563s 52.568980 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.569011 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal) 563s 52.569014 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 563s 52.569193 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.569217 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.569234 | sdc: serial = beaf11 563s 52.569237 | sdc: detect_checker = yes (setting: multipath internal) 563s 52.569506 | sdc: path_checker = tur (setting: multipath internal) 563s 52.569531 | sdc: checker timeout = 30 s (setting: kernel sysfs) 563s 52.569697 | sdc: tur state = up 563s 52.569876 | sdd: size = 204800 563s 52.570045 | sdd: vendor = IET 563s 52.570091 | sdd: product = VIRTUAL-DISK 563s 52.570116 | sdd: rev = 0001 563s 52.570905 | sdd: h:b:t:l = 3:0:0:1 563s 52.571320 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.571345 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal) 563s 52.571348 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 563s 52.571519 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.571523 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.571540 | sdd: serial = beaf11 563s 52.571564 | sdd: detect_checker = yes (setting: multipath internal) 563s 52.571811 | sdd: path_checker = tur (setting: multipath internal) 563s 52.571836 | sdd: checker timeout = 30 s (setting: kernel sysfs) 563s 52.571949 | sdd: tur state = up 563s 52.572097 | sde: size = 204800 563s 52.572271 | sde: vendor = IET 563s 52.572317 | sde: product = VIRTUAL-DISK 563s 52.572342 | sde: rev = 0001 563s 52.573094 | sde: h:b:t:l = 4:0:0:1 563s 52.573491 | sde: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.573515 | sde: uid_attribute = ID_SERIAL (setting: multipath internal) 563s 52.573517 | sde: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 563s 52.573680 | sde: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.573705 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.573721 | sde: serial = beaf11 563s 52.573724 | sde: detect_checker = yes (setting: multipath internal) 563s 52.573953 | sde: path_checker = tur (setting: multipath internal) 563s 52.573979 | sde: checker timeout = 30 s (setting: kernel sysfs) 563s 52.574141 | sde: tur state = up 563s 52.574284 | loop0: device node name blacklisted 563s 52.574427 | loop1: device node name blacklisted 563s 52.574544 | loop2: device node name blacklisted 563s 52.574661 | loop3: device node name blacklisted 563s 52.574777 | loop4: device node name blacklisted 563s 52.574891 | loop5: device node name blacklisted 563s 52.575006 | loop6: device node name blacklisted 563s 52.575120 | loop7: device node name blacklisted 563s 52.575241 | dm-0: device node name blacklisted 563s 52.576348 | multipath-tools v0.9.4 (12/19, 2022) 563s 52.576387 | libdevmapper version 1.02.185 563s 52.576537 | kernel device mapper v4.48.0 563s 52.576571 | DM multipath kernel driver v1.14.0 563s 52.576691 | sdb: size = 204800 563s 52.576716 | sdb: vendor = IET 563s 52.576719 | sdb: product = VIRTUAL-DISK 563s 52.576722 | sdb: rev = 0001 563s 52.577410 | sdb: h:b:t:l = 1:0:0:1 563s 52.577560 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.577603 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.577606 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.577621 | sdb: serial = beaf11 563s 52.577916 | sdb: tur state = up 563s 52.577940 | sdb: uid = 360000000000000000e00000000010001 (udev) 563s 52.577944 | sdb: detect_prio = yes (setting: multipath internal) 563s 52.577947 | sdb: prio = const (setting: multipath internal) 563s 52.577950 | sdb: prio args = "" (setting: multipath internal) 563s 52.577953 | sdb: const prio = 1 563s 52.577979 | sdc: size = 204800 563s 52.577984 | sdc: vendor = IET 563s 52.577987 | sdc: product = VIRTUAL-DISK 563s 52.577990 | sdc: rev = 0001 563s 52.578684 | sdc: h:b:t:l = 2:0:0:1 563s 52.578833 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.578875 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.578879 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.578893 | sdc: serial = beaf11 563s 52.579017 | sdc: tur state = up 563s 52.579040 | sdc: uid = 360000000000000000e00000000010001 (udev) 563s 52.579043 | sdc: detect_prio = yes (setting: multipath internal) 563s 52.579045 | sdc: prio = const (setting: multipath internal) 563s 52.579048 | sdc: prio args = "" (setting: multipath internal) 563s 52.579050 | sdc: const prio = 1 563s 52.579075 | sdd: size = 204800 563s 52.579079 | sdd: vendor = IET 563s 52.579082 | sdd: product = VIRTUAL-DISK 563s 52.579085 | sdd: rev = 0001 563s 52.579755 | sdd: h:b:t:l = 3:0:0:1 563s 52.579899 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.579940 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.579943 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.579957 | sdd: serial = beaf11 563s 52.580278 | sdd: tur state = up 563s 52.580307 | sdd: uid = 360000000000000000e00000000010001 (udev) 563s 52.580353 | sdd: detect_prio = yes (setting: multipath internal) 563s 52.580356 | sdd: prio = const (setting: multipath internal) 563s 52.580359 | sdd: prio args = "" (setting: multipath internal) 563s 52.580361 | sdd: const prio = 1 563s 52.580388 | sde: size = 204800 563s 52.580414 | sde: vendor = IET 563s 52.580417 | sde: product = VIRTUAL-DISK 563s 52.580420 | sde: rev = 0001 563s 52.581091 | sde: h:b:t:l = 4:0:0:1 563s 52.581236 | sde: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 563s 52.581277 | sde: 1024 cyl, 4 heads, 50 sectors/track, start at 0 563s 52.581281 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal) 563s 52.581295 | sde: serial = beaf11 563s 52.581463 | sde: tur state = up 563s 52.581485 | sde: uid = 360000000000000000e00000000010001 (udev) 563s 52.581487 | sde: detect_prio = yes (setting: multipath internal) 563s 52.581490 | sde: prio = const (setting: multipath internal) 563s 52.581493 | sde: prio args = "" (setting: multipath internal) 563s 52.581495 | sde: const prio = 1 563s 52.582400 | unloading tur checker 563s ===== paths list ===== 563s uuid hcil dev dev_t pri dm_st chk_st vend/prod/rev dev_st 563s 0:0:0:0 sda 8:0 -1 undef undef QEMU,QEMU HARDDISK unknown 563s 1:0:0:1 sdb 8:16 -1 undef undef IET,VIRTUAL-DISK unknown 563s 2:0:0:1 sdc 8:32 -1 undef undef IET,VIRTUAL-DISK unknown 563s 3:0:0:1 sdd 8:48 -1 undef undef IET,VIRTUAL-DISK unknown 563s 4:0:0:1 sde 8:64 -1 undef undef IET,VIRTUAL-DISK unknown 563s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 563s size=100M features='0' hwhandler='0' wp=rw 563s |-+- policy='service-time 0' prio=1 status=active 563s | `- 1:0:0:1 sdb 8:16 active ready running 563s |-+- policy='service-time 0' prio=1 status=enabled 563s | `- 2:0:0:1 sdc 8:32 active ready running 563s |-+- policy='service-time 0' prio=1 status=enabled 563s | `- 3:0:0:1 sdd 8:48 active ready running 563s `-+- policy='service-time 0' prio=1 status=enabled 563s `- 4:0:0:1 sde 8:64 active ready running 563s 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 563s Test WWN should now point to DM 563s ../../dm-0 563s Creating filesystem with 25600 4k blocks and 25600 inodes 563s 563s Allocating group tables: 0/1 done 563s Writing inode tables: 0/1 done 563s Creating journal (1024 blocks): 52.582478 | unloading const prioritizer 563s + dmsetup table 563s + echo Test WWN should now point to DM 563s + readlink /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 563s + grep dm 563s + mkfs.ext4 -F /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 563s mke2fs 1.47.0 (5-Feb-2023) 563s done 563s Writing superblocks and filesystem accounting information: 0/1 done 563s 563s + udevadm settle 563s + sleep 3s 566s + mkdir -p /mnt/tgtmpathtest 566s + mount /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 /mnt/tgtmpathtest 566s + cat 566s + cat 566s + fio --max-jobs=4 /tmp/autopkgtest.BvrWQj/tgtbasedmpaths-artifacts/path-change-prep.fio 566s write-phase: (g=0): rw=write, bs=(R) 64.0KiB-64.0KiB, (W) 64.0KiB-64.0KiB, (T) 64.0KiB-64.0KiB, ioengine=sync, iodepth=1 566s fio-3.36 566s Starting 1 thread 566s write-phase: Laying out IO file (1 file / 17592186044415MiB) 567s fio: io_u error on file /mnt/tgtmpathtest/datafile.tmp: No space left on device: write offset=91881472, buflen=65536 567s 567s write-phase: (groupid=0, jobs=1): err= 0: pid=5622: Fri Apr 19 18:38:12 2024 567s write: IOPS=1587, BW=99.1MiB/s (104MB/s)(87.6MiB/884msec); 0 zone resets 567s clat (usec): min=332, max=3835, avg=605.18, stdev=220.66 567s lat (usec): min=348, max=3871, avg=628.37, stdev=224.96 567s clat percentiles (usec): 567s | 1.00th=[ 383], 5.00th=[ 416], 10.00th=[ 437], 20.00th=[ 465], 567s | 30.00th=[ 498], 40.00th=[ 529], 50.00th=[ 570], 60.00th=[ 611], 567s | 70.00th=[ 652], 80.00th=[ 709], 90.00th=[ 783], 95.00th=[ 865], 567s | 99.00th=[ 1188], 99.50th=[ 1827], 99.90th=[ 3752], 99.95th=[ 3851], 567s | 99.99th=[ 3851] 567s bw ( KiB/s): min=91264, max=91264, per=89.91%, avg=91264.00, stdev= 0.00, samples=1 567s iops : min= 1426, max= 1426, avg=1426.00, stdev= 0.00, samples=1 567s lat (usec) : 500=31.36%, 750=55.02%, 1000=12.19% 567s lat (msec) : 2=0.86%, 4=0.50% 567s cpu : usr=2.72%, sys=9.85%, ctx=1404, majf=0, minf=0 567s IO depths : 1=100.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=0.0%, >=64=0.0% 567s submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0% 567s complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0% 567s issued rwts: total=0,1403,0,0 short=0,0,0,0 dropped=0,0,0,0 567s latency : target=0, window=0, percentile=100.00%, depth=1 567s 567s Run status group 0 (all jobs): 567s WRITE: bw=99.1MiB/s (104MB/s), 99.1MiB/s-99.1MiB/s (104MB/s-104MB/s), io=87.6MiB (91.9MB), run=884-884msec 567s 567s Disk stats (read/write): 567s dm-0: ios=1/949, sectors=8/121472, merge=0/0, ticks=1/611, in_queue=611, util=86.91%, aggrios=0/351, aggsectors=2/44884, aggrmerge=0/0, aggrticks=0/199, aggrin_queue=199, aggrutil=88.62% 567s sdd: ios=0/0, sectors=0/0, merge=0/0, ticks=0/0, in_queue=0, util=0.00% 567s sdb: ios=1/1404, sectors=8/179536, merge=0/0, ticks=1/796, in_queue=797, util=88.62% 567s sde: ios=0/0, sectors=0/0, merge=0/0, ticks=0/0, in_queue=0, util=0.00% 567s sdc: ios=0/0, sectors=0/0, merge=0/0, ticks=0/0, in_queue=0, util=0.00% 567s Starting the path changes in background 567s + echo Starting the path changes in background 567s + date +Pre FIO %H:%M:%S.%N 567s Pre FIO 18:38:12.954960456 567s + fio --max-jobs=4 /tmp/autopkgtest.BvrWQj/tgtbasedmpaths-artifacts/path-change-check.fio 568s verify-phase: (g=0): rw=read, bs=(R) 64.0KiB-64.0KiB, (W) 64.0KiB-64.0KiB, (T) 64.0KiB-64.0KiB, ioengine=sync, iodepth=1 568s fio-3.36 568s Starting 1 thread 748s 748s verify-phase: (groupid=0, jobs=1): err= 0: pid=5629: Fri Apr 19 18:41:13 2024 748s read: IOPS=2424, BW=152MiB/s (159MB/s)(26.6GiB/180001msec) 748s clat (usec): min=97, max=61097, avg=386.09, stdev=418.29 748s lat (usec): min=97, max=61097, avg=386.22, stdev=418.30 748s clat percentiles (usec): 748s | 1.00th=[ 155], 5.00th=[ 194], 10.00th=[ 215], 20.00th=[ 243], 748s | 30.00th=[ 277], 40.00th=[ 314], 50.00th=[ 351], 60.00th=[ 379], 748s | 70.00th=[ 412], 80.00th=[ 445], 90.00th=[ 498], 95.00th=[ 594], 748s | 99.00th=[ 1483], 99.50th=[ 2376], 99.90th=[ 5276], 99.95th=[ 7046], 748s | 99.99th=[12256] 748s bw ( KiB/s): min=77952, max=307584, per=100.00%, avg=155363.55, stdev=38227.43, samples=359 748s iops : min= 1218, max= 4806, avg=2427.48, stdev=597.32, samples=359 748s lat (usec) : 100=0.01%, 250=22.83%, 500=67.24%, 750=7.06%, 1000=1.06% 748s lat (msec) : 2=1.17%, 4=0.44%, 10=0.18%, 20=0.01%, 50=0.01% 748s lat (msec) : 100=0.01% 748s cpu : usr=7.11%, sys=8.70%, ctx=436455, majf=0, minf=1 748s IO depths : 1=100.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=0.0%, >=64=0.0% 748s submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0% 748s complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0% 748s issued rwts: total=436360,0,0,0 short=0,0,0,0 dropped=0,0,0,0 748s latency : target=0, window=0, percentile=100.00%, depth=1 748s 748s Run status group 0 (all jobs): 748s READ: bw=152MiB/s (159MB/s), 152MiB/s-152MiB/s (159MB/s-159MB/s), io=26.6GiB (28.6GB), run=180001-180001msec 748s 748s Disk stats (read/write): 748s dm-0: ios=436585/9, sectors=55841080/12912, merge=19/8, ticks=173852/103, in_queue=174009, util=99.72%, aggrios=32124/2, aggsectors=4108430/3228, aggrmerge=0/0, aggrticks=10981/12, aggrin_queue=10993, aggrutil=99.14% 748s sdd: ios=26060/0, sectors=3333248/0, merge=0/0, ticks=8661/0, in_queue=8661, util=32.87% 748s sdb: ios=25821/8, sectors=3302528/12912, merge=0/0, ticks=8555/49, in_queue=8604, util=99.14% 748s sde: ios=53330/0, sectors=6821376/0, merge=0/0, ticks=17723/0, in_queue=17724, util=39.86% 748s sdc: ios=23287/0, sectors=2976568/0, merge=0/0, ticks=8985/0, in_queue=8985, util=50.12% 748s Post FIO 18:41:13.183210916 748s FIO verify test with changing paths - OK 748s Report log of background activity 748s + date +Post FIO %H:%M:%S.%N 748s + echo FIO verify test with changing paths - OK 748s + echo Report log of background activity 748s + cat /tmp/autopkgtest.BvrWQj/tgtbasedmpaths-artifacts/test-background.log 748s + iscsiadm --mode session 748s tcp: [1] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [2] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [3] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [4] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s + sleep 10s 748s + date +MP report (expect 4) %H:%M:%S.%N 748s MP report (expect 4) 18:38:22.963620858 748s + multipath -ll 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s |-+- policy='service-time 0' prio=1 status=active 748s | `- 1:0:0:1 sdb 8:16 active ready running 748s |-+- policy='service-time 0' prio=1 status=enabled 748s | `- 2:0:0:1 sdc 8:32 active ready running 748s |-+- policy='service-time 0' prio=1 status=enabled 748s | `- 3:0:0:1 sdd 8:48 active ready running 748s `-+- policy='service-time 0' prio=1 status=enabled 748s `- 4:0:0:1 sde 8:64 active ready running 748s + date +UN-plug path 1 %H:%M:%S.%N 748s UN-plug path 1 18:38:22.995721338 748s + iscsiadm --mode session -r 1 -u 748s Logging out of session [sid: 1, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 1, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session 748s tcp: [2] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [3] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [4] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s + sleep 10s 748s + date +MP report (expect 3) %H:%M:%S.%N 748s MP report (expect 3) 18:38:33.111531381 748s + multipath -ll 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s |-+- policy='service-time 0' prio=1 status=active 748s | `- 2:0:0:1 sdc 8:32 active ready running 748s |-+- policy='service-time 0' prio=1 status=enabled 748s | `- 3:0:0:1 sdd 8:48 active ready running 748s `-+- policy='service-time 0' prio=1 status=enabled 748s `- 4:0:0:1 sde 8:64 active ready running 748s + date +UN-plug path 2 %H:%M:%S.%N 748s UN-plug path 2 18:38:33.138229394 748s + iscsiadm --mode session -r 2 -u 748s Logging out of session [sid: 2, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 2, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session 748s tcp: [3] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [4] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s + sleep 10s 748s + date +MP report (expect 2) %H:%M:%S.%N 748s MP report (expect 2) 18:38:43.246520824 748s + multipath -ll 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s |-+- policy='service-time 0' prio=1 status=active 748s | `- 3:0:0:1 sdd 8:48 active ready running 748s `-+- policy='service-time 0' prio=1 status=enabled 748s `- 4:0:0:1 sde 8:64 active ready running 748s + date +UN-plug path 3 %H:%M:%S.%N 748s UN-plug path 3 18:38:43.272280868 748s + iscsiadm --mode session -r 3 -u 748s Logging out of session [sid: 3, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 3, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session 748s tcp: [4] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s + sleep 10s 748s + date +MP report (expect 1) %H:%M:%S.%N 748s MP report (expect 1) 18:38:53.348993306 748s + multipath -ll 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s `-+- policy='service-time 0' prio=1 status=active 748s `- 4:0:0:1 sde 8:64 active ready running 748s + date +Add paths 5/6/7/8 %H:%M:%S.%N 748s Add paths 5/6/7/8 18:38:53.367211438 748s + iscsiadm --mode session -r 4 --op new 748s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 748s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session -r 4 --op new 748s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 748s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session -r 4 --op new 748s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 748s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session -r 4 --op new 748s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 748s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session 748s tcp: [4] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [5] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [6] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [7] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [8] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s + sleep 10s 748s + date +MP report (expect 5) %H:%M:%S.%N 748s MP report (expect 5) 18:39:03.558996440 748s + multipath -ll 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s |-+- policy='service-time 0' prio=1 status=active 748s | `- 4:0:0:1 sde 8:64 active ready running 748s |-+- policy='service-time 0' prio=1 status=enabled 748s | `- 1:0:0:1 sdb 8:16 active ready running 748s |-+- policy='service-time 0' prio=1 status=enabled 748s | `- 2:0:0:1 sdc 8:32 active ready running 748s |-+- policy='service-time 0' prio=1 status=enabled 748s | `- 3:0:0:1 sdd 8:48 active ready running 748s `-+- policy='service-time 0' prio=1 status=enabled 748s `- 5:0:0:1 sdf 8:80 active ready running 748s + date +UN-plug multiple paths 4/7/8 %H:%M:%S.%N 748s UN-plug multiple paths 4/7/8 18:39:03.596835104 748s + iscsiadm --mode session -r 4 -u 748s Logging out of session [sid: 4, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 4, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session -r 7 -u 748s Logging out of session [sid: 7, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 7, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session -r 8 -u 748s Logging out of session [sid: 8, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 8, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + iscsiadm --mode session 748s tcp: [5] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s tcp: [6] 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi (non-flash) 748s + sleep 10s 748s + date +Restart multipath daemon %H:%M:%S.%N 748s Restart multipath daemon 18:39:13.892369010 748s + systemctl restart multipathd 748s + sleep 10s 748s + date +Final background report (expect 2) %H:%M:%S.%N 748s Final background report (expect 2) 18:39:23.965634593 748s + multipath -ll 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s |-+- policy='service-time 0' prio=1 status=active 748s | `- 1:0:0:1 sdb 8:16 active ready running 748s `-+- policy='service-time 0' prio=1 status=enabled 748s `- 2:0:0:1 sdc 8:32 active ready running 748s Final stats 748s Stats for session [sid: 5, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s iSCSI SNMP: 748s txdata_octets: 14731608 748s rxdata_octets: 20090297796 748s noptx_pdus: 0 748s scsicmd_pdus: 306641 748s tmfcmd_pdus: 0 748s login_pdus: 0 748s text_pdus: 0 748s dataout_pdus: 0 748s logout_pdus: 0 748s snack_pdus: 0 748s noprx_pdus: 0 748s scsirsp_pdus: 306641 748s tmfrsp_pdus: 0 748s textrsp_pdus: 0 748s datain_pdus: 306608 748s logoutrsp_pdus: 0 748s r2t_pdus: 0 748s async_pdus: 0 748s rjt_pdus: 0 748s digest_err: 0 748s timeout_err: 0 748s iSCSI Extended: 748s tx_sendpage_failures: 0 748s rx_discontiguous_hdr: 0 748s eh_abort_cnt: 0 748s Stats for session [sid: 6, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s iSCSI SNMP: 748s txdata_octets: 6552 748s rxdata_octets: 1515012 748s noptx_pdus: 0 748s scsicmd_pdus: 106 748s tmfcmd_pdus: 0 748s login_pdus: 0 748s text_pdus: 0 748s dataout_pdus: 0 748s logout_pdus: 0 748s snack_pdus: 0 748s noprx_pdus: 0 748s scsirsp_pdus: 106 748s tmfrsp_pdus: 0 748s textrsp_pdus: 0 748s datain_pdus: 83 748s logoutrsp_pdus: 0 748s r2t_pdus: 0 748s async_pdus: 0 748s rjt_pdus: 0 748s digest_err: 0 748s timeout_err:+ sync 748s + umount /dev/disk/by-id/wwn-0x60000000000000000e00000000010001 748s + echo Final stats 748s + iscsiadm --mode session --stats 748s + journalctl --no-pager -u multipathd 748s 0 748s iSCSI Extended: 748s tx_sendpage_failures: 0 748s rx_discontiguous_hdr: 0 748s eh_abort_cnt: 0 748s Apr 19 17:06:48 ubuntu systemd[1]: Starting multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 17:06:48 ubuntu multipathd[326]: multipathd v0.9.4: start up 748s Apr 19 17:06:48 ubuntu multipathd[326]: reconfigure: setting up paths and maps 748s Apr 19 17:06:48 ubuntu systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller. 748s Apr 19 17:09:48 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 multipathd[326]: multipathd: shut down 748s Apr 19 17:09:48 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Stopping multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 17:09:48 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: multipathd.service: Deactivated successfully. 748s Apr 19 17:09:48 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Stopped multipathd.service - Device-Mapper Multipath Device Controller. 748s -- Boot a8846aa352cb4d7e962b6076db740ad2 -- 748s Apr 19 17:10:14 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Starting multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 17:10:14 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 multipathd[276]: multipathd v0.9.4: start up 748s Apr 19 17:10:14 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 multipathd[276]: reconfigure: setting up paths and maps 748s Apr 19 17:10:14 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller. 748s Apr 19 17:10:33 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 multipathd[276]: multipathd: shut down 748s Apr 19 17:10:33 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Stopping multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 17:10:33 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: multipathd.service: Deactivated successfully. 748s Apr 19 17:10:33 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Stopped multipathd.service - Device-Mapper Multipath Device Controller. 748s -- Boot 52faa9f3ce6a409abad97543046cfc5d -- 748s Apr 19 18:35:46 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Starting multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 18:35:46 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 multipathd[331]: multipathd v0.9.4: start up 748s Apr 19 18:35:46 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 multipathd[331]: reconfigure: setting up paths and maps 748s Apr 19 18:35:46 auto-syncubuntu-noble-daily-ppc64el-server-20240416-disk1 systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller. 748s Apr 19 18:36:47 autopkgtest multipathd[331]: multipathd: shut down 748s Apr 19 18:36:47 autopkgtest systemd[1]: Stopping multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 18:36:47 autopkgtest systemd[1]: multipathd.service: Deactivated successfully. 748s Apr 19 18:36:47 autopkgtest systemd[1]: Stopped multipathd.service - Device-Mapper Multipath Device Controller. 748s -- Boot 0dfe8b2b6a844244bfd1d9a52496d609 -- 748s Apr 19 18:37:19 autopkgtest multipathd[310]: multipathd v0.9.4: start up 748s Apr 19 18:37:19 autopkgtest multipathd[310]: reconfigure: setting up paths and maps 748s Apr 19 18:37:19 autopkgtest systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller. 748s Apr 19 18:38:03 autopkgtest multipathd[310]: mpatha: addmap [0 204800 multipath 0 0 2 1 service-time 0 1 1 8:16 1 service-time 0 1 1 8:32 1] 748s Apr 19 18:38:03 autopkgtest multipathd[310]: sdc [8:32]: path added to devmap mpatha 748s Apr 19 18:38:03 autopkgtest multipathd[310]: mpatha: performing delayed actions 748s Apr 19 18:38:03 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 4 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 service-time 0 1 1 8:64 1] 748s Apr 19 18:38:23 autopkgtest multipathd[310]: sdb: mark as failed 748s Apr 19 18:38:23 autopkgtest multipathd[310]: mpatha: remaining active paths: 3 748s Apr 19 18:38:23 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 3 1 service-time 0 1 1 8:32 1 service-time 0 1 1 8:48 1 service-time 0 1 1 8:64 1] 748s Apr 19 18:38:23 autopkgtest multipathd[310]: check_removed_paths: sdb: freeing path in removed state 748s Apr 19 18:38:23 autopkgtest multipathd[310]: 8:16: path removed from map mpatha 748s Apr 19 18:38:33 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 2 1 service-time 0 1 1 8:48 1 service-time 0 1 1 8:64 1] 748s Apr 19 18:38:33 autopkgtest multipathd[310]: check_removed_paths: sdc: freeing path in removed state 748s Apr 19 18:38:33 autopkgtest multipathd[310]: 8:32: path removed from map mpatha 748s Apr 19 18:38:43 autopkgtest multipathd[310]: sdd: mark as failed 748s Apr 19 18:38:43 autopkgtest multipathd[310]: mpatha: remaining active paths: 1 748s Apr 19 18:38:43 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 1 1 service-time 0 1 1 8:64 1] 748s Apr 19 18:38:43 autopkgtest multipathd[310]: check_removed_paths: sdd: freeing path in removed state 748s Apr 19 18:38:43 autopkgtest multipathd[310]: 8:48: path removed from map mpatha 748s Apr 19 18:38:53 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 2 1 service-time 0 1 1 8:64 1 service-time 0 1 1 8:16 1] 748s Apr 19 18:38:53 autopkgtest multipathd[310]: sdb [8:16]: path added to devmap mpatha 748s Apr 19 18:38:53 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 3 1 service-time 0 1 1 8:64 1 service-time 0 1 1 8:16 1 service-time 0 1 1 8:32 1] 748s Apr 19 18:38:53 autopkgtest multipathd[310]: sdc [8:32]: path added to devmap mpatha 748s Apr 19 18:38:53 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 4 1 service-time 0 1 1 8:64 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] 748s Apr 19 18:38:53 autopkgtest multipathd[310]: sdd [8:48]: path added to devmap mpatha 748s Apr 19 18:38:53 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 5 1 service-time 0 1 1 8:64 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 service-time 0 1 1 8:80 1] 748s Apr 19 18:38:53 autopkgtest multipathd[310]: sdf [8:80]: path added to devmap mpatha 748s Apr 19 18:39:03 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 4 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 service-time 0 1 1 8:80 1] 748s Apr 19 18:39:03 autopkgtest multipathd[310]: check_removed_paths: sde: freeing path in removed state 748s Apr 19 18:39:03 autopkgtest multipathd[310]: 8:64: path removed from map mpatha 748s Apr 19 18:39:03 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 3 1 service-time 0 1 1 8:16 1 service-time 0 1 1 8:32 1 service-time 0 1 1 8:80 1] 748s Apr 19 18:39:03 autopkgtest multipathd[310]: check_removed_paths: sdd: freeing path in removed state 748s Apr 19 18:39:03 autopkgtest multipathd[310]: 8:48: path removed from map mpatha 748s Apr 19 18:39:03 autopkgtest multipathd[310]: mpatha: reload [0 204800 multipath 0 0 2 1 service-time 0 1 1 8:16 1 service-time 0 1 1 8:32 1] 748s Apr 19 18:39:03 autopkgtest multipathd[310]: check_removed_paths: sdf: freeing path in removed state 748s Apr 19 18:39:03 autopkgtest multipathd[310]: 8:80: path removed from map mpatha 748s Apr 19 18:39:13 autopkgtest multipathd[310]: multipathd: shut down 748s Apr 19 18:39:13 autopkgtest systemd[1]: Stopping multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 18:39:13 autopkgtest systemd[1]: multipathd.service: Deactivated successfully. 748s Apr 19 18:39:13 autopkgtest systemd[1]: Stopped multipathd.service - Device-Mapper Multipath Device Controller. 748s Apr 19 18:39:13 autopkgtest systemd[1]: Starting multipathd.service - Device-Mapper Multipath Device Controller... 748s Apr 19 18:39:13 autopkgtest multipathd[5997]: multipathd v0.9.4: start up 748s Apr 19 18:39:13 autopkgtest multipathd[5997]: reconfigure: setting up paths and maps 748s Apr 19 18:39:13 autopkgtest systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller. 748s Check final path status 748s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 748s size=100M features='0' hwhandler='0' wp=rw 748s |-+- policy='service-time 0'+ echo Check final path status 748s + multipath -ll 748s + multipath -ll 748s + grep --count status= 748s prio=1 status=active 748s | `- 1:0:0:1 sdb 8:16 active ready running 748s `-+- policy='service-time 0' prio=1 status=enabled 748s `- 2:0:0:1 sdc 8:32 active ready running 748s + diskc=2 748s + multipath -ll 748s + grep --count status=active 748s + diska=1 748s + multipath -ll 748s + grep --count status=enabled 748s OK 748s + diske=1 748s + [ 2 -ne 2 -o 1 -ne 1 -o 1 -ne 1 ] 748s + echo OK 748s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --logout 748s Logging out of session [sid: 5, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logging out of session [sid: 6, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 748s Logout of [sid: 5, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s Logout of [sid: 6, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 748s + tgtadm --lld iscsi --op delete --mode logicalunit --tid 1 --lun 1 748s autopkgtest [18:41:13]: test tgtbasedmpaths: -----------------------] 749s tgtbasedmpaths PASS 749s autopkgtest [18:41:14]: test tgtbasedmpaths: - - - - - - - - - - results - - - - - - - - - - 749s autopkgtest [18:41:14]: @@@@@@@@@@@@@@@@@@@@ summary 749s kpartx-file-loopback PASS 749s tgtbasedmpaths PASS 761s Creating nova instance adt-noble-ppc64el-multipath-tools-20240419-182845-juju-7f2275-prod-proposed-migration-environment-2-39903355-4cfe-4404-a03c-2a3f048ab5cf from image adt/ubuntu-noble-ppc64el-server-20240419.img (UUID b7a0af62-4a88-423a-a98e-eb9a30e958ea)... 761s Creating nova instance adt-noble-ppc64el-multipath-tools-20240419-182845-juju-7f2275-prod-proposed-migration-environment-2-39903355-4cfe-4404-a03c-2a3f048ab5cf from image adt/ubuntu-noble-ppc64el-server-20240419.img (UUID b7a0af62-4a88-423a-a98e-eb9a30e958ea)...