15s autopkgtest [16:23:26]: starting date and time: 2024-09-06 16:23:26+0000 15s autopkgtest [16:23:26]: git checkout: fd3bed09 nova: allow more retries for quota issues 15s autopkgtest [16:23:26]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.smgcbv1w/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/256.5-2ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-big-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-ppc64el-19.secgroup --name adt-oracular-ppc64el-multipath-tools-20240906-162259-juju-7f2275-prod-proposed-migration-environment-3-77bf91a5-7f75-40a1-95a8-06ea8afc4387 --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/ 258s autopkgtest [16:27:29]: testbed dpkg architecture: ppc64el 263s autopkgtest [16:27:34]: testbed apt version: 2.9.8 263s autopkgtest [16:27:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 286s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 287s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8424 B] 287s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [45.0 kB] 287s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [9288 B] 287s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [670 kB] 287s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [64.5 kB] 287s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [1444 B] 287s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1372 B] 287s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 287s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [600 kB] 287s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [14.7 kB] 287s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [5308 B] 287s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [340 B] 290s Fetched 1546 kB in 1s (1729 kB/s) 290s Reading package lists... 307s Reading package lists... 307s Building dependency tree... 307s Reading state information... 307s Calculating upgrade... 307s The following packages will be upgraded: 307s libnss-systemd libpam-systemd libsystemd-shared libsystemd0 libudev1 systemd 307s systemd-cryptsetup systemd-dev systemd-resolved systemd-sysv 307s systemd-timesyncd udev 307s 12 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 307s Need to get 10.4 MB of archives. 307s After this operation, 12.3 kB of additional disk space will be used. 307s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-dev all 256.5-2ubuntu1 [114 kB] 308s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-timesyncd ppc64el 256.5-2ubuntu1 [42.4 kB] 308s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-resolved ppc64el 256.5-2ubuntu1 [370 kB] 308s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-cryptsetup ppc64el 256.5-2ubuntu1 [127 kB] 308s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libsystemd-shared ppc64el 256.5-2ubuntu1 [2491 kB] 308s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libsystemd0 ppc64el 256.5-2ubuntu1 [565 kB] 308s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-sysv ppc64el 256.5-2ubuntu1 [11.9 kB] 308s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libnss-systemd ppc64el 256.5-2ubuntu1 [215 kB] 308s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libpam-systemd ppc64el 256.5-2ubuntu1 [325 kB] 308s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd ppc64el 256.5-2ubuntu1 [3740 kB] 308s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el udev ppc64el 256.5-2ubuntu1 [2142 kB] 308s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libudev1 ppc64el 256.5-2ubuntu1 [224 kB] 308s Fetched 10.4 MB in 1s (11.9 MB/s) 309s (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 ... 72411 files and directories currently installed.) 309s Preparing to unpack .../0-systemd-dev_256.5-2ubuntu1_all.deb ... 309s Unpacking systemd-dev (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../1-systemd-timesyncd_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking systemd-timesyncd (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../2-systemd-resolved_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking systemd-resolved (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../3-systemd-cryptsetup_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking systemd-cryptsetup (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../4-libsystemd-shared_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking libsystemd-shared:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../5-libsystemd0_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking libsystemd0:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Setting up libsystemd0:ppc64el (256.5-2ubuntu1) ... 309s (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 ... 72411 files and directories currently installed.) 309s Preparing to unpack .../0-systemd-sysv_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking systemd-sysv (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../1-libnss-systemd_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking libnss-systemd:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../2-libpam-systemd_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking libpam-systemd:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../3-systemd_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking systemd (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../4-udev_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking udev (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Preparing to unpack .../5-libudev1_256.5-2ubuntu1_ppc64el.deb ... 309s Unpacking libudev1:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 309s Setting up libudev1:ppc64el (256.5-2ubuntu1) ... 309s Setting up systemd-dev (256.5-2ubuntu1) ... 309s Setting up libsystemd-shared:ppc64el (256.5-2ubuntu1) ... 309s Setting up systemd (256.5-2ubuntu1) ... 309s Installing new version of config file /etc/systemd/journald.conf ... 309s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 309s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 310s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 310s Setting up systemd-cryptsetup (256.5-2ubuntu1) ... 310s Setting up systemd-timesyncd (256.5-2ubuntu1) ... 311s systemd-time-wait-sync.service is a disabled or a static unit not running, not starting it. 311s Setting up udev (256.5-2ubuntu1) ... 311s Setting up systemd-resolved (256.5-2ubuntu1) ... 312s Setting up systemd-sysv (256.5-2ubuntu1) ... 312s Setting up libnss-systemd:ppc64el (256.5-2ubuntu1) ... 312s Setting up libpam-systemd:ppc64el (256.5-2ubuntu1) ... 312s Processing triggers for libc-bin (2.40-1ubuntu1) ... 312s Processing triggers for man-db (2.12.1-3) ... 313s Processing triggers for dbus (1.14.10-4ubuntu5) ... 313s Processing triggers for shared-mime-info (2.4-5) ... 314s Processing triggers for initramfs-tools (0.142ubuntu33) ... 314s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 314s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 326s Reading package lists... 326s Building dependency tree... 326s Reading state information... 326s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 326s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 326s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 326s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 326s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 327s Reading package lists... 327s Reading package lists... 327s Building dependency tree... 327s Reading state information... 328s Calculating upgrade... 328s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 328s Reading package lists... 328s Building dependency tree... 328s Reading state information... 328s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 328s autopkgtest [16:28:39]: rebooting testbed after setup commands that affected boot 385s autopkgtest [16:29:36]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 413s autopkgtest [16:30:04]: @@@@@@@@@@@@@@@@@@@@ apt-source multipath-tools 448s Get:1 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.9-1ubuntu2 (dsc) [2772 B] 448s Get:2 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.9-1ubuntu2 (tar) [588 kB] 448s Get:3 http://ftpmaster.internal/ubuntu oracular/main multipath-tools 0.9.9-1ubuntu2 (diff) [42.4 kB] 448s gpgv: Signature made Tue Jul 23 21:24:22 2024 UTC 448s gpgv: using RSA key D09F8A854F1055BCFC482C4B23566B906047AFC8 448s gpgv: Can't check signature: No public key 448s dpkg-source: warning: cannot verify inline signature for ./multipath-tools_0.9.9-1ubuntu2.dsc: no acceptable signature found 450s autopkgtest [16:30:41]: testing package multipath-tools version 0.9.9-1ubuntu2 478s initramfs SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 478s autopkgtest [16:31:09]: build not needed 478s autopkgtest [16:31:09]: test kpartx-file-loopback: preparing testbed 478s initramfs SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 480s Reading package lists... 480s Building dependency tree... 480s Reading state information... 480s Starting pkgProblemResolver with broken count: 0 480s Starting 2 pkgProblemResolver with broken count: 0 480s Done 480s The following additional packages will be installed: 480s liburing2 qemu-utils 480s Recommended packages: 480s qemu-block-extra 480s The following NEW packages will be installed: 480s autopkgtest-satdep liburing2 qemu-utils 480s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 480s Need to get 2432 kB/2433 kB of archives. 480s After this operation, 16.9 MB of additional disk space will be used. 480s Get:1 /tmp/autopkgtest.nL4NN0/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 480s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el liburing2 ppc64el 2.6-1 [26.9 kB] 480s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el qemu-utils ppc64el 1:9.0.2+ds-4ubuntu2 [2405 kB] 481s Fetched 2432 kB in 1s (3547 kB/s) 481s Selecting previously unselected package liburing2:ppc64el. 481s (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 ... 72411 files and directories currently installed.) 481s Preparing to unpack .../liburing2_2.6-1_ppc64el.deb ... 481s Unpacking liburing2:ppc64el (2.6-1) ... 481s Selecting previously unselected package qemu-utils. 481s Preparing to unpack .../qemu-utils_1%3a9.0.2+ds-4ubuntu2_ppc64el.deb ... 481s Unpacking qemu-utils (1:9.0.2+ds-4ubuntu2) ... 481s Selecting previously unselected package autopkgtest-satdep. 481s Preparing to unpack .../1-autopkgtest-satdep.deb ... 481s Unpacking autopkgtest-satdep (0) ... 481s Setting up liburing2:ppc64el (2.6-1) ... 481s Setting up qemu-utils (1:9.0.2+ds-4ubuntu2) ... 481s Setting up autopkgtest-satdep (0) ... 481s Processing triggers for man-db (2.12.1-3) ... 483s Processing triggers for libc-bin (2.40-1ubuntu1) ... 488s (Reading database ... 72435 files and directories currently installed.) 488s Removing autopkgtest-satdep (0) ... 488s autopkgtest [16:31:19]: test kpartx-file-loopback: [----------------------- 488s Formatting 'foo.img', fmt=raw size=20971520 489s Creating new GPT entries in memory. 489s Warning: The kernel is still using the old partition table. 489s The new table will be used at the next reboot or after you 489s run partprobe(8) or kpartx(8) 489s The operation has completed successfully. 489s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 489s standard_filename: OK 490s del devmap : loop0p1 490s No devices found 490s standard_filename_cleanup: OK 490s Formatting 'fou du FaFa.img', fmt=raw size=20971520 491s Creating new GPT entries in memory. 491s Warning: The kernel is still using the old partition table. 491s The new table will be used at the next reboot or after you 491s run partprobe(8) or kpartx(8) 491s The operation has completed successfully. 491s add map loop0p1 (252:0): 0 38879 linear 7:0 2048 491s filename_with_spaces: OK 491s del devmap : loop0p1 491s No devices found 491s filename_with_spaces_cleanup: OK 497s autopkgtest [16:31:28]: test kpartx-file-loopback: -----------------------] 508s autopkgtest [16:31:39]: test kpartx-file-loopback: - - - - - - - - - - results - - - - - - - - - - 508s kpartx-file-loopback PASS 509s autopkgtest [16:31:40]: test tgtbasedmpaths: preparing testbed 923s autopkgtest [16:38:34]: testbed dpkg architecture: ppc64el 964s autopkgtest [16:39:15]: testbed apt version: 2.9.8 964s autopkgtest [16:39:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 979s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 980s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [670 kB] 980s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [9288 B] 980s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [45.0 kB] 980s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8424 B] 980s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [64.5 kB] 980s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [1444 B] 980s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1372 B] 980s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 980s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [600 kB] 980s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [14.7 kB] 980s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [5308 B] 980s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [340 B] 983s Fetched 1546 kB in 1s (1758 kB/s) 983s Reading package lists... 1024s Reading package lists... 1024s Building dependency tree... 1024s Reading state information... 1024s Calculating upgrade... 1024s The following packages will be upgraded: 1024s libnss-systemd libpam-systemd libsystemd-shared libsystemd0 libudev1 systemd 1024s systemd-cryptsetup systemd-dev systemd-resolved systemd-sysv 1024s systemd-timesyncd udev 1024s 12 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1024s Need to get 10.4 MB of archives. 1024s After this operation, 12.3 kB of additional disk space will be used. 1024s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-dev all 256.5-2ubuntu1 [114 kB] 1024s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-timesyncd ppc64el 256.5-2ubuntu1 [42.4 kB] 1024s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-resolved ppc64el 256.5-2ubuntu1 [370 kB] 1025s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-cryptsetup ppc64el 256.5-2ubuntu1 [127 kB] 1025s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libsystemd-shared ppc64el 256.5-2ubuntu1 [2491 kB] 1025s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libsystemd0 ppc64el 256.5-2ubuntu1 [565 kB] 1025s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd-sysv ppc64el 256.5-2ubuntu1 [11.9 kB] 1025s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libnss-systemd ppc64el 256.5-2ubuntu1 [215 kB] 1025s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libpam-systemd ppc64el 256.5-2ubuntu1 [325 kB] 1025s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el systemd ppc64el 256.5-2ubuntu1 [3740 kB] 1025s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el udev ppc64el 256.5-2ubuntu1 [2142 kB] 1025s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libudev1 ppc64el 256.5-2ubuntu1 [224 kB] 1025s Fetched 10.4 MB in 1s (10.9 MB/s) 1026s (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 ... 72411 files and directories currently installed.) 1026s Preparing to unpack .../0-systemd-dev_256.5-2ubuntu1_all.deb ... 1026s Unpacking systemd-dev (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../1-systemd-timesyncd_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking systemd-timesyncd (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../2-systemd-resolved_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking systemd-resolved (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../3-systemd-cryptsetup_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking systemd-cryptsetup (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../4-libsystemd-shared_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking libsystemd-shared:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../5-libsystemd0_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking libsystemd0:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Setting up libsystemd0:ppc64el (256.5-2ubuntu1) ... 1026s (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 ... 72411 files and directories currently installed.) 1026s Preparing to unpack .../0-systemd-sysv_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking systemd-sysv (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../1-libnss-systemd_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking libnss-systemd:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../2-libpam-systemd_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking libpam-systemd:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../3-systemd_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking systemd (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../4-udev_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking udev (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Preparing to unpack .../5-libudev1_256.5-2ubuntu1_ppc64el.deb ... 1026s Unpacking libudev1:ppc64el (256.5-2ubuntu1) over (256.4-2ubuntu1) ... 1026s Setting up libudev1:ppc64el (256.5-2ubuntu1) ... 1026s Setting up systemd-dev (256.5-2ubuntu1) ... 1026s Setting up libsystemd-shared:ppc64el (256.5-2ubuntu1) ... 1026s Setting up systemd (256.5-2ubuntu1) ... 1026s Installing new version of config file /etc/systemd/journald.conf ... 1026s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 1026s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 1027s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 1027s Setting up systemd-cryptsetup (256.5-2ubuntu1) ... 1027s Setting up systemd-timesyncd (256.5-2ubuntu1) ... 1028s systemd-time-wait-sync.service is a disabled or a static unit not running, not starting it. 1028s Setting up udev (256.5-2ubuntu1) ... 1029s Setting up systemd-resolved (256.5-2ubuntu1) ... 1029s Setting up systemd-sysv (256.5-2ubuntu1) ... 1029s Setting up libnss-systemd:ppc64el (256.5-2ubuntu1) ... 1029s Setting up libpam-systemd:ppc64el (256.5-2ubuntu1) ... 1030s Processing triggers for libc-bin (2.40-1ubuntu1) ... 1030s Processing triggers for man-db (2.12.1-3) ... 1031s Processing triggers for dbus (1.14.10-4ubuntu5) ... 1031s Processing triggers for shared-mime-info (2.4-5) ... 1032s Processing triggers for initramfs-tools (0.142ubuntu33) ... 1032s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 1032s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1041s Reading package lists... 1042s Building dependency tree... 1042s Reading state information... 1042s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1050s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 1050s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 1050s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 1050s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 1051s Reading package lists... 1051s Reading package lists... 1051s Building dependency tree... 1051s Reading state information... 1051s Calculating upgrade... 1051s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1051s Reading package lists... 1051s Building dependency tree... 1051s Reading state information... 1051s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1054s autopkgtest [16:40:45]: rebooting testbed after setup commands that affected boot 1216s Reading package lists... 1216s Building dependency tree... 1216s Reading state information... 1216s Starting pkgProblemResolver with broken count: 0 1216s Starting 2 pkgProblemResolver with broken count: 0 1216s Done 1216s The following additional packages will be installed: 1216s fio libboost-iostreams1.83.0 libboost-thread1.83.0 libconfig-general-perl 1216s libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 libglusterfs0 libisns0t64 libnbd0 1216s libndctl6 libopeniscsiusr libpmem1 libpmemobj1 librados2 librbd1 1216s librdmacm1t64 lsscsi open-iscsi tgt 1216s Suggested packages: 1216s fio-examples gnuplot tgt-glusterfs tgt-rbd 1216s Recommended packages: 1216s finalrd 1216s The following NEW packages will be installed: 1216s autopkgtest-satdep fio libboost-iostreams1.83.0 libboost-thread1.83.0 1216s libconfig-general-perl libdaxctl1 libgfapi0 libgfrpc0 libgfxdr0 1216s libglusterfs0 libisns0t64 libnbd0 libndctl6 libopeniscsiusr libpmem1 1216s libpmemobj1 librados2 librbd1 librdmacm1t64 lsscsi open-iscsi tgt 1216s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 1216s Need to get 11.0 MB/11.0 MB of archives. 1216s After this operation, 50.0 MB of additional disk space will be used. 1216s Get:1 /tmp/autopkgtest.nL4NN0/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [732 B] 1216s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libopeniscsiusr ppc64el 2.1.10-1ubuntu1 [54.9 kB] 1216s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libisns0t64 ppc64el 0.101-1 [117 kB] 1216s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el open-iscsi ppc64el 2.1.10-1ubuntu1 [385 kB] 1216s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el librdmacm1t64 ppc64el 52.0-2ubuntu1 [80.6 kB] 1216s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libconfig-general-perl all 2.65-2 [57.1 kB] 1216s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el tgt ppc64el 1:1.0.85-1.2ubuntu1 [254 kB] 1216s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfxdr0 ppc64el 11.1-5ubuntu1 [21.7 kB] 1216s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libglusterfs0 ppc64el 11.1-5ubuntu1 [308 kB] 1216s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfrpc0 ppc64el 11.1-5ubuntu1 [46.3 kB] 1216s Get:11 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libgfapi0 ppc64el 11.1-5ubuntu1 [99.1 kB] 1216s Get:12 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libnbd0 ppc64el 1.20.2-2 [98.5 kB] 1216s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdaxctl1 ppc64el 77-2.2ubuntu1 [23.7 kB] 1216s Get:14 http://ftpmaster.internal/ubuntu oracular/main ppc64el libndctl6 ppc64el 77-2.2ubuntu1 [73.4 kB] 1216s Get:15 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmem1 ppc64el 1.13.1-1.1ubuntu2 [41.1 kB] 1216s Get:16 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-iostreams1.83.0 ppc64el 1.83.0-3.2ubuntu2 [260 kB] 1216s Get:17 http://ftpmaster.internal/ubuntu oracular/main ppc64el libboost-thread1.83.0 ppc64el 1.83.0-3.2ubuntu2 [281 kB] 1216s Get:18 http://ftpmaster.internal/ubuntu oracular/main ppc64el librados2 ppc64el 19.2.0~rc2-0ubuntu1 [4194 kB] 1216s Get:19 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpmemobj1 ppc64el 1.13.1-1.1ubuntu2 [143 kB] 1216s Get:20 http://ftpmaster.internal/ubuntu oracular/main ppc64el librbd1 ppc64el 19.2.0~rc2-0ubuntu1 [3714 kB] 1216s Get:21 http://ftpmaster.internal/ubuntu oracular/universe ppc64el fio ppc64el 3.37-1 [716 kB] 1216s Get:22 http://ftpmaster.internal/ubuntu oracular/main ppc64el lsscsi ppc64el 0.32-1build1 [54.0 kB] 1216s Preconfiguring packages ... 1216s Fetched 11.0 MB in 1s (10.8 MB/s) 1216s Selecting previously unselected package libopeniscsiusr. 1216s (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 ... 72411 files and directories currently installed.) 1216s Preparing to unpack .../00-libopeniscsiusr_2.1.10-1ubuntu1_ppc64el.deb ... 1216s Unpacking libopeniscsiusr (2.1.10-1ubuntu1) ... 1216s Selecting previously unselected package libisns0t64:ppc64el. 1216s Preparing to unpack .../01-libisns0t64_0.101-1_ppc64el.deb ... 1216s Unpacking libisns0t64:ppc64el (0.101-1) ... 1216s Selecting previously unselected package open-iscsi. 1216s Preparing to unpack .../02-open-iscsi_2.1.10-1ubuntu1_ppc64el.deb ... 1216s Unpacking open-iscsi (2.1.10-1ubuntu1) ... 1216s Selecting previously unselected package librdmacm1t64:ppc64el. 1216s Preparing to unpack .../03-librdmacm1t64_52.0-2ubuntu1_ppc64el.deb ... 1216s Unpacking librdmacm1t64:ppc64el (52.0-2ubuntu1) ... 1216s Selecting previously unselected package libconfig-general-perl. 1216s Preparing to unpack .../04-libconfig-general-perl_2.65-2_all.deb ... 1216s Unpacking libconfig-general-perl (2.65-2) ... 1216s Selecting previously unselected package tgt. 1216s Preparing to unpack .../05-tgt_1%3a1.0.85-1.2ubuntu1_ppc64el.deb ... 1216s Unpacking tgt (1:1.0.85-1.2ubuntu1) ... 1216s Selecting previously unselected package libgfxdr0:ppc64el. 1216s Preparing to unpack .../06-libgfxdr0_11.1-5ubuntu1_ppc64el.deb ... 1216s Unpacking libgfxdr0:ppc64el (11.1-5ubuntu1) ... 1216s Selecting previously unselected package libglusterfs0:ppc64el. 1216s Preparing to unpack .../07-libglusterfs0_11.1-5ubuntu1_ppc64el.deb ... 1216s Unpacking libglusterfs0:ppc64el (11.1-5ubuntu1) ... 1216s Selecting previously unselected package libgfrpc0:ppc64el. 1216s Preparing to unpack .../08-libgfrpc0_11.1-5ubuntu1_ppc64el.deb ... 1216s Unpacking libgfrpc0:ppc64el (11.1-5ubuntu1) ... 1216s Selecting previously unselected package libgfapi0:ppc64el. 1216s Preparing to unpack .../09-libgfapi0_11.1-5ubuntu1_ppc64el.deb ... 1216s Unpacking libgfapi0:ppc64el (11.1-5ubuntu1) ... 1216s Selecting previously unselected package libnbd0. 1216s Preparing to unpack .../10-libnbd0_1.20.2-2_ppc64el.deb ... 1216s Unpacking libnbd0 (1.20.2-2) ... 1216s Selecting previously unselected package libdaxctl1:ppc64el. 1216s Preparing to unpack .../11-libdaxctl1_77-2.2ubuntu1_ppc64el.deb ... 1216s Unpacking libdaxctl1:ppc64el (77-2.2ubuntu1) ... 1216s Selecting previously unselected package libndctl6:ppc64el. 1216s Preparing to unpack .../12-libndctl6_77-2.2ubuntu1_ppc64el.deb ... 1216s Unpacking libndctl6:ppc64el (77-2.2ubuntu1) ... 1216s Selecting previously unselected package libpmem1:ppc64el. 1216s Preparing to unpack .../13-libpmem1_1.13.1-1.1ubuntu2_ppc64el.deb ... 1216s Unpacking libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 1216s Selecting previously unselected package libboost-iostreams1.83.0:ppc64el. 1216s Preparing to unpack .../14-libboost-iostreams1.83.0_1.83.0-3.2ubuntu2_ppc64el.deb ... 1216s Unpacking libboost-iostreams1.83.0:ppc64el (1.83.0-3.2ubuntu2) ... 1216s Selecting previously unselected package libboost-thread1.83.0:ppc64el. 1216s Preparing to unpack .../15-libboost-thread1.83.0_1.83.0-3.2ubuntu2_ppc64el.deb ... 1216s Unpacking libboost-thread1.83.0:ppc64el (1.83.0-3.2ubuntu2) ... 1216s Selecting previously unselected package librados2. 1216s Preparing to unpack .../16-librados2_19.2.0~rc2-0ubuntu1_ppc64el.deb ... 1216s Unpacking librados2 (19.2.0~rc2-0ubuntu1) ... 1216s Selecting previously unselected package libpmemobj1:ppc64el. 1216s Preparing to unpack .../17-libpmemobj1_1.13.1-1.1ubuntu2_ppc64el.deb ... 1216s Unpacking libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 1216s Selecting previously unselected package librbd1. 1216s Preparing to unpack .../18-librbd1_19.2.0~rc2-0ubuntu1_ppc64el.deb ... 1216s Unpacking librbd1 (19.2.0~rc2-0ubuntu1) ... 1216s Selecting previously unselected package fio. 1216s Preparing to unpack .../19-fio_3.37-1_ppc64el.deb ... 1216s Unpacking fio (3.37-1) ... 1216s Selecting previously unselected package lsscsi. 1216s Preparing to unpack .../20-lsscsi_0.32-1build1_ppc64el.deb ... 1216s Unpacking lsscsi (0.32-1build1) ... 1216s Selecting previously unselected package autopkgtest-satdep. 1216s Preparing to unpack .../21-2-autopkgtest-satdep.deb ... 1216s Unpacking autopkgtest-satdep (0) ... 1216s Setting up libconfig-general-perl (2.65-2) ... 1216s Setting up libisns0t64:ppc64el (0.101-1) ... 1216s Setting up libboost-thread1.83.0:ppc64el (1.83.0-3.2ubuntu2) ... 1216s Setting up libnbd0 (1.20.2-2) ... 1216s Setting up libopeniscsiusr (2.1.10-1ubuntu1) ... 1216s Setting up libglusterfs0:ppc64el (11.1-5ubuntu1) ... 1216s Setting up libboost-iostreams1.83.0:ppc64el (1.83.0-3.2ubuntu2) ... 1216s Setting up lsscsi (0.32-1build1) ... 1216s Setting up libdaxctl1:ppc64el (77-2.2ubuntu1) ... 1216s Setting up libndctl6:ppc64el (77-2.2ubuntu1) ... 1216s Setting up librdmacm1t64:ppc64el (52.0-2ubuntu1) ... 1216s Setting up tgt (1:1.0.85-1.2ubuntu1) ... 1216s Created symlink '/etc/systemd/system/multi-user.target.wants/tgt.service' → '/usr/lib/systemd/system/tgt.service'. 1216s Setting up libpmem1:ppc64el (1.13.1-1.1ubuntu2) ... 1216s Setting up libgfxdr0:ppc64el (11.1-5ubuntu1) ... 1216s Setting up librados2 (19.2.0~rc2-0ubuntu1) ... 1216s Setting up open-iscsi (2.1.10-1ubuntu1) ... 1217s Created symlink '/etc/systemd/system/sockets.target.wants/iscsid.socket' → '/usr/lib/systemd/system/iscsid.socket'. 1218s Created symlink '/etc/systemd/system/iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 1218s Created symlink '/etc/systemd/system/sysinit.target.wants/open-iscsi.service' → '/usr/lib/systemd/system/open-iscsi.service'. 1218s Setting up libpmemobj1:ppc64el (1.13.1-1.1ubuntu2) ... 1218s Setting up librbd1 (19.2.0~rc2-0ubuntu1) ... 1218s Setting up libgfrpc0:ppc64el (11.1-5ubuntu1) ... 1218s Setting up libgfapi0:ppc64el (11.1-5ubuntu1) ... 1218s Setting up fio (3.37-1) ... 1218s Setting up autopkgtest-satdep (0) ... 1218s Processing triggers for man-db (2.12.1-3) ... 1220s Processing triggers for initramfs-tools (0.142ubuntu33) ... 1220s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 1220s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1228s Processing triggers for libc-bin (2.40-1ubuntu1) ... 1309s (Reading database ... 72652 files and directories currently installed.) 1309s Removing autopkgtest-satdep (0) ... 1431s autopkgtest [16:47:02]: test tgtbasedmpaths: [----------------------- 1438s + targetname=iqn.2016-11.foo.com:target.iscsi 1438s + pwd 1438s + cwd=/tmp/autopkgtest.nL4NN0/build.Zwj/src 1438s + testdir=/mnt/tgtmpathtest 1438s + localhost=127.0.0.1 1438s + portal=127.0.0.1:3260 1438s + maxpaths=4 1438s + backfn=backingfile 1438s + expectwwid=60000000000000000e00000000010001 1438s + testdisk=/dev/disk/by-id/wwn-0x60000000000000000e00000000010001 1438s + bglog=/tmp/autopkgtest.nL4NN0/tgtbasedmpaths-artifacts/test-background.log 1438s + fioprep=/tmp/autopkgtest.nL4NN0/tgtbasedmpaths-artifacts/path-change-prep.fio 1438s + fiovrfy=/tmp/autopkgtest.nL4NN0/tgtbasedmpaths-artifacts/path-change-check.fio 1438s + mkdir -p /etc/multipath 1438s + echo /360000000000000000e00000000010001/ 1438s + service tgt restart 1438s + truncate --size 100M backingfile 1438s + tgtadm --lld iscsi --op new --mode target --tid 1 -T iqn.2016-11.foo.com:target.iscsi 1438s + tgtadm --lld iscsi --op bind --mode target --tid 1 -I ALL 1438s + tgtadm --lld iscsi --op new --mode logicalunit --tid 1 --lun 1 -b /tmp/autopkgtest.nL4NN0/build.Zwj/src/backingfile 1438s + iscsiadm --mode discovery --type sendtargets --portal 127.0.0.1 1438s 127.0.0.1:3260,1 iqn.2016-11.foo.com:target.iscsi 1438s login #1 1438s + echo login #1 1438s + iscsiadm --mode node --targetname iqn.2016-11.foo.com:target.iscsi --portal 127.0.0.1:3260 --login 1438s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] 1438s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 1438s + seq 2 4 1438s + echo extra login #2 1438s + iscsiadm --mode session -r 1 --op new 1438s extra login #2 1438s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 1438s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 1438s + echo extra login #3 1438s + iscsiadm --mode session -r 1 --op new 1438s extra login #3 1438s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 1438s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 1438s + echo extra login #4 1438s + iscsiadm --mode session -r 1 --op new 1438s extra login #4 1438s Logging in to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] (multiple) 1438s Login to [iface: default, target: iqn.2016-11.foo.com:target.iscsi, portal: 127.0.0.1,3260] successful. 1438s + udevadm settle 1438s + sleep 5 1443s Status after initial setup 1443s + echo Status after initial setup 1443s + tgtadm --lld iscsi --mode target --op show 1443s Target 1: iqn.2016-11.foo.com:target.iscsi 1443s System information: 1443s Driver: iscsi 1443s State: ready 1443s I_T nexus information: 1443s I_T nexus: 1 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 alias: autopkgtest 1443s Connection: 0 1443s IP Address: 127.0.0.1 1443s I_T nexus: 2 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 alias: autopkgtest 1443s Connection: 0 1443s IP Address: 127.0.0.1 1443s I_T nexus: 3 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 alias: autopkgtest 1443s Connection: 0 1443s IP Address: 127.0.0.1 1443s I_T nexus: 4 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 alias: autopkgtest 1443s Connection: 0 1443s IP Address: 127.0.0.1 1443s LUN information: 1443s LUN: 0 1443s Type: controller 1443s SCSI ID: IET 00010000 1443s SCSI SN: beaf10 1443s Size: 0 MB, Block size: 1 1443s Online: Yes 1443s Removable media: No 1443s Prevent removal: No 1443s Readonly: No 1443s SWP: No 1443s Thin-provisioning: No 1443s Backing store type: null 1443s Backing store path: None 1443s Backing store flags: 1443s LUN: 1 1443s Type: disk 1443s SCSI ID: IET 00010001 1443s SCSI SN: beaf11 1443s Size: 105 MB, Block size: 512 1443s Online: Yes 1443s Removable media: No 1443s Prevent removal: No 1443s Readonly: No 1443s SWP: No 1443s Thin-provisioning: No 1443s Backing store type: rdwr 1443s Backing store path: /tmp/autopkgtest.nL4NN0/build.Zwj/src/backingfile 1443s Backing store flags: 1443s Account information: 1443s ACL information: 1443s ALL 1443s + tgtadm --lld iscsi --op show --mode conn --tid 1 1443s + iscsiadm --mode session -P 1 1443s Session: 4 1443s Connection: 0 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s IP Address: 127.0.0.1 1443s Session: 3 1443s Connection: 0 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s IP Address: 127.0.0.1 1443s Session: 2 1443s Connection: 0 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s IP Address: 127.0.0.1 1443s Session: 1 1443s Connection: 0 1443s Initiator: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s IP Address: 127.0.0.1 1443s Target: iqn.2016-11.foo.com:target.iscsi (non-flash) 1443s Current Portal: 127.0.0.1:3260,1 1443s Persistent Portal: 127.0.0.1:3260,1 1443s ********** 1443s Interface: 1443s ********** 1443s Iface Name: default 1443s Iface Transport: tcp 1443s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s Iface IPaddress: 127.0.0.1 1443s Iface HWaddress: default 1443s Iface Netdev: default 1443s SID: 1 1443s iSCSI Connection State: LOGGED IN 1443s iSCSI Session State: LOGGED_IN 1443s Internal iscsid Session State: NO CHANGE 1443s 1443s ********** 1443s Interface: 1443s ********** 1443s Iface Name: default 1443s Iface Transport: tcp 1443s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s Iface IPaddress: 127.0.0.1 1443s Iface HWaddress: default 1443s Iface Netdev: default 1443s SID: 2 1443s iSCSI Connection State: LOGGED IN 1443s iSCSI Session State: LOGGED_IN 1443s Internal iscsid Session State: NO CHANGE 1443s 1443s ********** 1443s Interface: 1443s ********** 1443s Iface Name: default 1443s Iface Transport: tcp 1443s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s Iface IPaddress: 127.0.0.1 1443s Iface HWaddress: default 1443s Iface Netdev: default 1443s SID: 3 1443s iSCSI Connection State: LOGGED IN 1443s iSCSI Session State: LOGGED_IN 1443s Internal iscsid Session State: NO CHANGE 1443s 1443s ********** 1443s Interface: 1443s ********** 1443s Iface Name: default 1443s Iface Transport: tcp 1443s Iface Initiatorname: iqn.2004-10.com.ubuntu:01:48ce5c9e4176 1443s Iface IPaddress: 127.0.0.1 1443s Iface HWaddress: default 1443s Iface Netdev: default 1443s SID: 4 1443s iSCSI Connection State: LOGGED IN 1443s iSCSI Session State: LOGGED_IN 1443s Internal iscsid Session State: NO CHANGE 1443s + lsscsi -liv 1443s list_ndevices: scandir: /sys/class/nvme/: No such file or directory 1443s [0:0:0:0] storage IET Controller 0001 - - 1443s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/0:0:0:0 [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:0] 1443s [0:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sda 360000000000000000e00000000010001 1443s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/0:0:0:1 [/sys/devices/platform/host0/session1/target0:0:0/0:0:0:1] 1443s [1:0:0:0] storage IET Controller 0001 - - 1443s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/1:0:0:0 [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:0] 1443s [1:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdb 360000000000000000e00000000010001 1443s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/1:0:0:1 [/sys/devices/platform/host1/session2/target1:0:0/1:0:0:1] 1443s [2:0:0:0] storage IET Controller 0001 - - 1443s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/2:0:0:0 [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:0] 1443s [2:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdc 360000000000000000e00000000010001 1443s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/2:0:0:1 [/sys/devices/platform/host2/session3/target2:0:0/2:0:0:1] 1443s [3:0:0:0] storage IET Controller 0001 - - 1443s state=running queue_depth=32 scsi_level=6 type=12 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/3:0:0:0 [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:0] 1443s [3:0:0:1] disk IET VIRTUAL-DISK 0001 /dev/sdd 33000000100000001 1443s state=running queue_depth=32 scsi_level=6 type=0 device_blocked=0 timeout=30 1443s dir: /sys/bus/scsi/devices/3:0:0:1 [/sys/devices/platform/host3/session4/target3:0:0/3:0:0:1] 1443s NVMe module may not be loaded 1443s + multipath -v3 -ll 1443s 369.434022 | set open fds limit to 1073741816/1073741816 1443s 369.434050 | _read_bindings_file: reading /etc/multipath/bindings 1443s 369.434075 | loading /usr/lib/multipath/libchecktur.so checker 1443s 369.434154 | checker tur: message table size = 4 1443s 369.434162 | loading /usr/lib/multipath/libprioconst.so prioritizer 1443s 369.434266 | _init_foreign: foreign library "nvme" is not enabled 1443s 369.439121 | vda: device node name blacklisted 1443s 369.439391 | sda: size = 204800 1443s 369.439521 | sda: vendor = IET 1443s 369.439543 | sda: product = VIRTUAL-DISK 1443s 369.439565 | sda: rev = 0001 1443s 369.440233 | sda: h:b:t:l = 0:0:0:1 1443s 369.440577 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.440581 | sda: uid_attribute = ID_SERIAL (setting: multipath internal) 1443s 369.440583 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 1443s 369.440762 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.440767 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.440781 | sda: serial = beaf11 1443s 369.440784 | sda: detect_checker = yes (setting: multipath internal) 1443s 369.440816 | sda checker timeout = 30 s (setting: kernel sysfs) 1443s 369.440966 | sda: path_checker = tur (setting: multipath internal) 1443s 369.441026 | sda: tur state = up 1443s 369.441152 | sdb: size = 204800 1443s 369.441284 | sdb: vendor = IET 1443s 369.441306 | sdb: product = VIRTUAL-DISK 1443s 369.441329 | sdb: rev = 0001 1443s 369.442018 | sdb: h:b:t:l = 1:0:0:1 1443s 369.442393 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.442397 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal) 1443s 369.442399 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 1443s 369.442542 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.442546 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.442558 | sdb: serial = beaf11 1443s 369.442561 | sdb: detect_checker = yes (setting: multipath internal) 1443s 369.442587 | sdb checker timeout = 30 s (setting: kernel sysfs) 1443s 369.442672 | sdb: path_checker = tur (setting: multipath internal) 1443s 369.442737 | sdb: tur state = up 1443s 369.442860 | sdc: size = 204800 1443s 369.442990 | sdc: vendor = IET 1443s 369.443012 | sdc: product = VIRTUAL-DISK 1443s 369.443035 | sdc: rev = 0001 1443s 369.443667 | sdc: h:b:t:l = 2:0:0:1 1443s 369.443998 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.444020 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal) 1443s 369.444024 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 1443s 369.444186 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.444191 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.444203 | sdc: serial = beaf11 1443s 369.444205 | sdc: detect_checker = yes (setting: multipath internal) 1443s 369.444249 | sdc checker timeout = 30 s (setting: kernel sysfs) 1443s 369.444336 | sdc: path_checker = tur (setting: multipath internal) 1443s 369.444402 | sdc: tur state = up 1443s 369.444531 | sdd: size = 204800 1443s 369.444681 | sdd: vendor = IET 1443s 369.444704 | sdd: product = VIRTUAL-DISK 1443s 369.444727 | sdd: rev = 0001 1443s 369.445379 | sdd: h:b:t:l = 3:0:0:1 1443s 369.445722 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.445726 | sdd: uid_attribute = ID_SERIAL (setting: multipath internal) 1443s 369.445729 | sdd: recheck_wwid = 1 (setting: multipath.conf defaults/devices section) 1443s 369.445869 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.445873 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.445886 | sdd: serial = beaf11 1443s 369.445888 | sdd: detect_checker = yes (setting: multipath internal) 1443s 369.445916 | sdd checker timeout = 30 s (setting: kernel sysfs) 1443s 369.445999 | sdd: path_checker = tur (setting: multipath internal) 1443s 369.446064 | sdd: tur state = up 1443s 369.446159 | loop0: device node name blacklisted 1443s 369.446249 | loop1: device node name blacklisted 1443s 369.446336 | loop2: device node name blacklisted 1443s 369.446423 | loop3: device node name blacklisted 1443s 369.446511 | loop4: device node name blacklisted 1443s 369.446597 | loop5: device node name blacklisted 1443s 369.446684 | loop6: device node name blacklisted 1443s 369.446770 | loop7: device node name blacklisted 1443s 369.446862 | dm-0: device node name blacklisted 1443s 369.447891 | multipath-tools v0.9.9 (05/03, 2024) 1443s 369.447900 | libdevmapper version 1.02.196 1443s 369.448065 | kernel device mapper v4.48.0 1443s 369.448081 | DM multipath kernel driver v1.14.0 1443s 369.448182 | sda: size = 204800 1443s 369.448187 | sda: vendor = IET 1443s 369.448190 | sda: product = VIRTUAL-DISK 1443s 369.448193 | sda: rev = 0001 1443s 369.448817 | sda: h:b:t:l = 0:0:0:1 1443s 369.448936 | sda: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.448955 | sda: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.448958 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.448970 | sda: serial = beaf11 1443s 369.449036 | sda: tur state = up 1443s 369.449041 | sda: uid = 360000000000000000e00000000010001 (udev) 1443s 369.449044 | sda: detect_prio = yes (setting: multipath internal) 1443s 369.449046 | sda: prio = const (setting: multipath internal) 1443s 369.449048 | sda: prio args = "" (setting: multipath internal) 1443s 369.449051 | sda: const prio = 1 1443s 369.449073 | sdb: size = 204800 1443s 369.449078 | sdb: vendor = IET 1443s 369.449081 | sdb: product = VIRTUAL-DISK 1443s 369.449083 | sdb: rev = 0001 1443s 369.449644 | sdb: h:b:t:l = 1:0:0:1 1443s 369.449756 | sdb: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.449774 | sdb: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.449777 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.449789 | sdb: serial = beaf11 1443s 369.449849 | sdb: tur state = up 1443s 369.449853 | sdb: uid = 360000000000000000e00000000010001 (udev) 1443s 369.449856 | sdb: detect_prio = yes (setting: multipath internal) 1443s 369.449858 | sdb: prio = const (setting: multipath internal) 1443s 369.449860 | sdb: prio args = "" (setting: multipath internal) 1443s 369.449862 | sdb: const prio = 1 1443s 369.449884 | sdc: size = 204800 1443s 369.449889 | sdc: vendor = IET 1443s 369.449892 | sdc: product = VIRTUAL-DISK 1443s 369.449894 | sdc: rev = 0001 1443s 369.450449 | sdc: h:b:t:l = 2:0:0:1 1443s 369.450558 | sdc: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.450577 | sdc: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.450580 | sdc: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.450592 | sdc: serial = beaf11 1443s 369.450647 | sdc: tur state = up 1443s 369.450652 | sdc: uid = 360000000000000000e00000000010001 (udev) 1443s 369.450655 | sdc: detect_prio = yes (setting: multipath internal) 1443s 369.450657 | sdc: prio = const (setting: multipath internal) 1443s 369.450659 | sdc: prio args = "" (setting: multipath internal) 1443s 369.450661 | sdc: const prio = 1 1443s 369.450683 | sdd: size = 204800 1443s 369.450688 | sdd: vendor = IET 1443s 369.450691 | sdd: product = VIRTUAL-DISK 1443s 369.450693 | sdd: rev = 0001 1443s 369.451246 | sdd: h:b:t:l = 3:0:0:1 1443s 369.451357 | sdd: tgt_node_name = iqn.2016-11.foo.com:target.iscsi 1443s 369.451376 | sdd: 1024 cyl, 4 heads, 50 sectors/track, start at 0 1443s 369.451379 | sdd: vpd_vendor_id = 0 "undef" (setting: multipath internal) 1443s 369.451391 | sdd: serial = beaf11 1443s 369.451473 | sdd: tur state = up 1443s 369.451479 | sdd: uid = 360000000000000000e00000000010001 (udev) 1443s 369.451482 | sdd: detect_prio = yes (setting: multipath internal) 1443s 369.451484 | sdd: prio = const (setting: multipath internal) 1443s 369.451487 | sdd: prio args = "" (setting: multipath internal) 1443s 369.451489 | sdd: const prio = 1 1443s 369.452199 | unloading tur checker 1443s 369.452228 | unloading const prioritizer 1443s + dmsetup table 1443s + grep . /etc/multipath/bindings /etc/multipath/wwids 1443s + systemctl status multipathd.service 1443s + systemctl status multipathd.socket 1443s ===== paths list ===== 1443s uuid hcil dev dev_t pri dm_st chk_st vend/prod/rev dev_st 1443s 0:0:0:1 sda 8:0 -1 undef undef IET,VIRTUAL-DISK,0001 unknown 1443s 1:0:0:1 sdb 8:16 -1 undef undef IET,VIRTUAL-DISK,0001 unknown 1443s 2:0:0:1 sdc 8:32 -1 undef undef IET,VIRTUAL-DISK,0001 unknown 1443s 3:0:0:1 sdd 8:48 -1 undef undef IET,VIRTUAL-DISK,0001 unknown 1443s mpatha (360000000000000000e00000000010001) dm-0 IET,VIRTUAL-DISK 1443s size=100M features='0' hwhandler='0' wp=rw 1443s |-+- policy='service-time 0' prio=1 status=active 1443s | `- 0:0:0:1 sda 8:0 active ready running 1443s |-+- policy='service-time 0' prio=1 status=enabled 1443s | `- 1:0:0:1 sdb 8:16 active ready running 1443s |-+- policy='service-time 0' prio=1 status=enabled 1443s | `- 2:0:0:1 sdc 8:32 active ready running 1443s `-+- policy='service-time 0' prio=1 status=enabled 1443s `- 3:0:0:1 sdd 8:48 active ready running 1443s 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 1443s /etc/multipath/bindings:# Multipath bindings, Version : 1.0 1443s /etc/multipath/bindings:# NOTE: this file is automatically maintained by the multipath program. 1443s /etc/multipath/bindings:# You should not need to edit this file in normal circumstances. 1443s /etc/multipath/bindings:# 1443s /etc/multipath/bindings:# Format: 1443s /etc/multipath/bindings:# alias wwid 1443s /etc/multipath/bindings:# 1443s /etc/multipath/bindings:mpatha 360000000000000000e00000000010001 1443s /etc/multipath/wwids:/360000000000000000e00000000010001/ 1443s ● multipathd.service - Device-Mapper Multipath Device Controller 1443s Loaded: loaded (/usr/lib/systemd/system/multipathd.service; enabled; preset: enabled) 1443s Active: active (running) since Fri 2024-09-06 16:41:08 UTC; 6min ago 1443s Invocation: 62c61aa4269e4f209f6ec82d1113fa8a 1443s TriggeredBy: ○ multipathd.socket 1443s Main PID: 327 (multipathd) 1443s Status: "up" 1443s Tasks: 7 1443s Memory: 27.7M (peak: 35.3M) 1443s CPU: 75ms 1443s CGroup: /system.slice/multipathd.service 1443s └─327 /sbin/multipathd -d -s 1443s 1443s Sep 06 16:41:08 autopkgtest systemd[1]: Starting multipathd.service - Device-Mapper Multipath Device Controller... 1443s Sep 06 16:41:08 autopkgtest multipathd[327]: multipathd v0.9.9: start up 1443s Sep 06 16:41:08 autopkgtest multipathd[327]: reconfigure: setting up paths and maps 1443s Sep 06 16:41:08 autopkgtest systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller. 1443s Sep 06 16:47:09 autopkgtest multipathd[327]: updated bindings file /etc/multipath/bindings 1443s Sep 06 16:47:09 autopkgtest multipathd[327]: mpatha: addmap [0 204800 multipath 0 0 1 1 service-time 0 1 1 8:0 1] 1443s Sep 06 16:47:09 autopkgtest multipathd[327]: sda [8:0]: path added to devmap mpatha 1443s Sep 06 16:47:09 autopkgtest multipathd[327]: mpatha: performing delayed actions 1443s Sep 06 16:47:09 autopkgtest multipathd[327]: 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] 1443s ○ multipathd.socket - multipathd control socket 1443s Loaded: loaded (/usr/lib/systemd/system/multipathd.socket; static) 1443s Active: inactive (dead) 1443s Triggers: ● multipathd.service 1443s Listen: @/org/kernel/linux/storage/multipathd (Stream) 1444s autopkgtest [16:47:15]: test tgtbasedmpaths: -----------------------] 1444s tgtbasedmpaths FAIL non-zero exit status 3 1444s autopkgtest [16:47:15]: test tgtbasedmpaths: - - - - - - - - - - results - - - - - - - - - - 1445s autopkgtest [16:47:16]: @@@@@@@@@@@@@@@@@@@@ summary 1445s initramfs SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 1445s initramfs SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 1445s kpartx-file-loopback PASS 1445s tgtbasedmpaths FAIL non-zero exit status 3 1616s nova [W] Using flock in prodstack6-ppc64el 1616s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240906-162259-juju-7f2275-prod-proposed-migration-environment-3-77bf91a5-7f75-40a1-95a8-06ea8afc4387 from image adt/ubuntu-oracular-ppc64el-server-20240906.img (UUID 96a448bf-2be4-4914-8b6c-81bdec5eef3b)... 1616s nova [W] Using flock in prodstack6-ppc64el 1616s Creating nova instance adt-oracular-ppc64el-multipath-tools-20240906-162259-juju-7f2275-prod-proposed-migration-environment-3-77bf91a5-7f75-40a1-95a8-06ea8afc4387 from image adt/ubuntu-oracular-ppc64el-server-20240906.img (UUID 96a448bf-2be4-4914-8b6c-81bdec5eef3b)...