0s autopkgtest [15:49:57]: starting date and time: 2024-03-14 15:49:57+0000
0s autopkgtest [15:49:57]: git checkout: b506e79c ssh-setup/nova: fix ARCH having two lines of data
0s autopkgtest [15:49:57]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.c4aczx1w/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:e2fsprogs,src:borgbackup,src:borgbackup2,src:fuse --apt-upgrade libpam-mount --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=e2fsprogs/1.47.0-2.4~exp1ubuntu2 borgbackup/1.2.7-2build2 borgbackup2/2.0.0b8-2build1 fuse/2.9.9-8.1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos01-ppc64el-12.secgroup --name adt-noble-ppc64el-libpam-mount-20240314-154957-juju-7f2275-prod-proposed-migration-environment-2 --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://us.ports.ubuntu.com/ubuntu-ports/
170s autopkgtest [15:52:46]: testbed dpkg architecture: ppc64el
170s autopkgtest [15:52:46]: testbed apt version: 2.7.12
170s autopkgtest [15:52:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup
170s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
170s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3004 kB]
171s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [4812 B]
171s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [453 kB]
171s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [47.8 kB]
171s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [604 kB]
172s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B]
172s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B]
172s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B]
172s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [3294 kB]
172s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B]
172s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [42.2 kB]
172s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B]
175s Fetched 7580 kB in 3s (2986 kB/s)
175s Reading package lists...
177s Reading package lists...
178s Building dependency tree...
178s Reading state information...
178s Calculating upgrade...
178s The following packages will be REMOVED:
178s libext2fs2
178s The following NEW packages will be installed:
178s libext2fs2t64
178s The following packages will be upgraded:
178s dosfstools e2fsprogs e2fsprogs-l10n libcom-err2 libss2 logsave
178s 6 upgraded, 1 newly installed, 1 to remove and 0 not upgraded.
178s Need to get 1104 kB of archives.
178s After this operation, 125 kB of additional disk space will be used.
178s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el e2fsprogs-l10n all 1.47.0-2.4~exp1ubuntu2 [5996 B]
178s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el logsave ppc64el 1.47.0-2.4~exp1ubuntu2 [22.9 kB]
178s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libext2fs2t64 ppc64el 1.47.0-2.4~exp1ubuntu2 [270 kB]
178s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el e2fsprogs ppc64el 1.47.0-2.4~exp1ubuntu2 [663 kB]
178s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libcom-err2 ppc64el 1.47.0-2.4~exp1ubuntu2 [22.9 kB]
178s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libss2 ppc64el 1.47.0-2.4~exp1ubuntu2 [18.0 kB]
178s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el dosfstools ppc64el 4.2-1.1 [101 kB]
179s Fetched 1104 kB in 1s (1712 kB/s)
179s (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 ... 70096 files and directories currently installed.)
179s Preparing to unpack .../e2fsprogs-l10n_1.47.0-2.4~exp1ubuntu2_all.deb ...
179s Unpacking e2fsprogs-l10n (1.47.0-2.4~exp1ubuntu2) over (1.47.0-2ubuntu1) ...
179s Preparing to unpack .../logsave_1.47.0-2.4~exp1ubuntu2_ppc64el.deb ...
179s Unpacking logsave (1.47.0-2.4~exp1ubuntu2) over (1.47.0-2ubuntu1) ...
179s dpkg: libext2fs2:ppc64el: dependency problems, but removing anyway as you requested:
179s libblockdev-fs3:ppc64el depends on libext2fs2 (>= 1.42.11).
179s e2fsprogs depends on libext2fs2 (= 1.47.0-2ubuntu1).
179s btrfs-progs depends on libext2fs2 (>= 1.42).
179s
179s (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 ... 70096 files and directories currently installed.)
179s Removing libext2fs2:ppc64el (1.47.0-2ubuntu1) ...
179s Selecting previously unselected package libext2fs2t64:ppc64el.
179s (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 ... 70089 files and directories currently installed.)
179s Preparing to unpack .../libext2fs2t64_1.47.0-2.4~exp1ubuntu2_ppc64el.deb ...
179s Adding 'diversion of /lib/powerpc64le-linux-gnu/libe2p.so.2 to /lib/powerpc64le-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64'
179s Adding 'diversion of /lib/powerpc64le-linux-gnu/libe2p.so.2.3 to /lib/powerpc64le-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64'
179s Adding 'diversion of /lib/powerpc64le-linux-gnu/libext2fs.so.2 to /lib/powerpc64le-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64'
179s Adding 'diversion of /lib/powerpc64le-linux-gnu/libext2fs.so.2.4 to /lib/powerpc64le-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64'
179s Unpacking libext2fs2t64:ppc64el (1.47.0-2.4~exp1ubuntu2) ...
179s Setting up libext2fs2t64:ppc64el (1.47.0-2.4~exp1ubuntu2) ...
179s (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 ... 70105 files and directories currently installed.)
179s Preparing to unpack .../e2fsprogs_1.47.0-2.4~exp1ubuntu2_ppc64el.deb ...
179s Unpacking e2fsprogs (1.47.0-2.4~exp1ubuntu2) over (1.47.0-2ubuntu1) ...
179s Preparing to unpack .../libcom-err2_1.47.0-2.4~exp1ubuntu2_ppc64el.deb ...
179s Unpacking libcom-err2:ppc64el (1.47.0-2.4~exp1ubuntu2) over (1.47.0-2ubuntu1) ...
179s Preparing to unpack .../libss2_1.47.0-2.4~exp1ubuntu2_ppc64el.deb ...
179s Unpacking libss2:ppc64el (1.47.0-2.4~exp1ubuntu2) over (1.47.0-2ubuntu1) ...
179s Preparing to unpack .../dosfstools_4.2-1.1_ppc64el.deb ...
179s Unpacking dosfstools (4.2-1.1) over (4.2-1build3) ...
179s Setting up dosfstools (4.2-1.1) ...
179s Setting up libcom-err2:ppc64el (1.47.0-2.4~exp1ubuntu2) ...
179s Setting up libss2:ppc64el (1.47.0-2.4~exp1ubuntu2) ...
179s Setting up logsave (1.47.0-2.4~exp1ubuntu2) ...
179s Setting up e2fsprogs (1.47.0-2.4~exp1ubuntu2) ...
179s update-initramfs: deferring update (trigger activated)
180s e2scrub_all.service is a disabled or a static unit not running, not starting it.
180s Setting up e2fsprogs-l10n (1.47.0-2.4~exp1ubuntu2) ...
180s Processing triggers for man-db (2.12.0-3) ...
181s Processing triggers for libc-bin (2.39-0ubuntu2) ...
181s Processing triggers for initramfs-tools (0.142ubuntu20) ...
181s update-initramfs: Generating /boot/initrd.img-6.8.0-11-generic
181s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
186s Reading package lists...
186s Building dependency tree...
186s Reading state information...
187s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
187s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease
187s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease
187s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease
187s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease
188s Reading package lists...
188s Reading package lists...
189s Building dependency tree...
189s Reading state information...
189s Calculating upgrade...
189s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
189s Reading package lists...
189s Building dependency tree...
189s Reading state information...
189s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
189s autopkgtest [15:53:06]: rebooting testbed after setup commands that affected boot
352s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
374s autopkgtest [15:56:11]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024
376s autopkgtest [15:56:13]: @@@@@@@@@@@@@@@@@@@@ apt-source libpam-mount
379s Get:1 http://ftpmaster.internal/ubuntu noble/main libpam-mount 2.20-3 (dsc) [2196 B]
379s Get:2 http://ftpmaster.internal/ubuntu noble/main libpam-mount 2.20-3 (tar) [325 kB]
379s Get:3 http://ftpmaster.internal/ubuntu noble/main libpam-mount 2.20-3 (diff) [29.0 kB]
379s gpgv: Signature made Sun Dec 17 05:49:05 2023 UTC
379s gpgv: using RSA key 73B299CBD4EEADDCC017E87A5BFFDCC258E69433
379s gpgv: Can't check signature: No public key
379s dpkg-source: warning: cannot verify inline signature for ./libpam-mount_2.20-3.dsc: no acceptable signature found
379s autopkgtest [15:56:16]: testing package libpam-mount version 2.20-3
380s autopkgtest [15:56:17]: build not needed
380s autopkgtest [15:56:17]: test local-luks: preparing testbed
386s Reading package lists...
386s Building dependency tree...
386s Reading state information...
386s Starting pkgProblemResolver with broken count: 0
386s Starting 2 pkgProblemResolver with broken count: 0
386s Done
386s The following additional packages will be installed:
386s cryptsetup hxtools libhx32 libpam-mount libpam-mount-bin
386s Suggested packages:
386s keyutils cifs-utils davfs2 sshfs
386s Recommended packages:
386s cryptsetup-initramfs
386s The following NEW packages will be installed:
386s autopkgtest-satdep cryptsetup hxtools libhx32 libpam-mount libpam-mount-bin
386s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded.
386s Need to get 468 kB/469 kB of archives.
386s After this operation, 2497 kB of additional disk space will be used.
386s Get:1 /tmp/autopkgtest.k4E6PP/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [764 B]
387s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el cryptsetup ppc64el 2:2.7.0-1ubuntu1 [208 kB]
387s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el libhx32 ppc64el 4.19-1 [53.5 kB]
387s Get:4 http://ftpmaster.internal/ubuntu noble/universe ppc64el hxtools ppc64el 20231224-2 [106 kB]
387s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el libpam-mount ppc64el 2.20-3 [67.8 kB]
387s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el libpam-mount-bin ppc64el 2.20-3 [32.0 kB]
387s Preconfiguring packages ...
387s Fetched 468 kB in 1s (813 kB/s)
387s Selecting previously unselected package cryptsetup.
387s (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 ... 70104 files and directories currently installed.)
387s Preparing to unpack .../0-cryptsetup_2%3a2.7.0-1ubuntu1_ppc64el.deb ...
387s Unpacking cryptsetup (2:2.7.0-1ubuntu1) ...
387s Selecting previously unselected package libhx32:ppc64el.
387s Preparing to unpack .../1-libhx32_4.19-1_ppc64el.deb ...
387s Unpacking libhx32:ppc64el (4.19-1) ...
387s Selecting previously unselected package hxtools.
387s Preparing to unpack .../2-hxtools_20231224-2_ppc64el.deb ...
387s Unpacking hxtools (20231224-2) ...
388s Selecting previously unselected package libpam-mount:ppc64el.
388s Preparing to unpack .../3-libpam-mount_2.20-3_ppc64el.deb ...
388s Unpacking libpam-mount:ppc64el (2.20-3) ...
388s Selecting previously unselected package libpam-mount-bin.
388s Preparing to unpack .../4-libpam-mount-bin_2.20-3_ppc64el.deb ...
388s Unpacking libpam-mount-bin (2.20-3) ...
388s Selecting previously unselected package autopkgtest-satdep.
388s Preparing to unpack .../5-1-autopkgtest-satdep.deb ...
388s Unpacking autopkgtest-satdep (0) ...
388s Setting up libhx32:ppc64el (4.19-1) ...
388s Setting up cryptsetup (2:2.7.0-1ubuntu1) ...
388s Setting up hxtools (20231224-2) ...
388s Setting up libpam-mount:ppc64el (2.20-3) ...
389s Setting up libpam-mount-bin (2.20-3) ...
389s Setting up autopkgtest-satdep (0) ...
389s Processing triggers for man-db (2.12.0-3) ...
390s Processing triggers for libc-bin (2.39-0ubuntu2) ...
392s (Reading database ... 70355 files and directories currently installed.)
392s Removing autopkgtest-satdep (0) ...
393s autopkgtest [15:56:30]: test local-luks: [-----------------------
393s + echo libpam-mount is working
393s + openssl passwd -1 alice
393s + useradd -m --password $1$lKyuLOuu$PxXaSltcXXjI6WIxqQpZc1 alice
393s + printf alice
393s + ssh-keygen -t rsa -N -f test.key
394s + mkdir -p /home/alice/.ssh
394s + chown alice:aliceGenerating public/private rsa key pair.
394s Your identification has been saved in test.key
394s Your public key has been saved in test.key.pub
394s The key fingerprint is:
394s SHA256:RrhKGAyOOggfrqV6eNxM5eIwOMm1SoikUZVqPWZq7i0 root@autopkgtest
394s The key's randomart image is:
394s +---[RSA 3072]----+
394s |. ... |
394s |oo. . . |
394s |oo+o . . |
394s |=+o== .o |
394s |X=*+o+. S |
394s |BB=oo... |
394s |+*.B.. |
394s |o.E.+ |
394s |.+... |
394s +----[SHA256]-----+
394s /home/alice/.ssh
394s + cp test.key.pub /home/alice/.ssh/authorized_keys
394s + mkdir -p /home/alice/img-luks1 /home/alice/img-luks2
394s + chown alice:alice /home/alice/.ssh/authorized_keys /home/alice/img-luks1 /home/alice/img-luks2
394s + createluks 1 luks
394s + id=1
394s + type=luks
394s + mktemp --directory
394s + tdir=/tmp/tmp.O0KhWCrTsu
394s + fallocate -l 32M /tmp/disk-l1.img
394s + cryptsetup luksFormat --batch-mode --verbose --force-password --key-file=/home/alice/alice.key --type luks /tmp/disk-l1.img
401s Key slot 0 created.
401s Command successful.
401s + cryptsetup open --type luks --batch-mode --verbose --key-file=/home/alice/alice.key /tmp/disk-l1.img img-luks1
403s No usable token is available.
403s Key slot 0 unlocked.
403s Command successful.
403s + mkfs.ext4 -L IMG-LUKS1 /dev/mapper/img-luks1
403s mke2fs 1.47.0 (5-Feb-2023)
403s Creating filesystem with 4096 4k blocks and 4096 inodes
403s
403s Allocating group tables: 0/1 done
403s Writing inode tables: 0/1 + mount /dev/mapper/img-luks1 /tmp/tmp.O0KhWCrTsu
403s + echo libpam-mount is working
403s + umount /dev/mapper/img-luks1
403s + cryptsetup close img-luks1
403s + createluks 2 luks2
403s + id=2
403s + type=luks2
403s + mktemp --directory
403s + tdir=/tmp/tmp.FxQFxIFmmd
403s + fallocate -l 32M /tmp/disk-l2.img
403s done
403s Creating journal (1024 blocks): done
403s Writing superblocks and filesystem accounting information: 0/1 done
403s
403s + cryptsetup luksFormat --batch-mode --verbose --force-password --key-file=/home/alice/alice.key --type luks2 /tmp/disk-l2.img
411s Key slot 0 created.
411s Command successful.
411s + cryptsetup open --type luks2 --batch-mode --verbose --key-file=/home/alice/alice.key /tmp/disk-l2.img img-luks2
413s + mkfs.ext4 -L IMG-LUKS2 /dev/mapper/img-luks2
413s No usable token is available.
413s Key slot 0 unlocked.
413s Command successful.
413s mke2fs 1.47.0 (5-Feb-2023)
413s Creating filesystem with 4096 4k blocks and 4096 inodes
413s
413s Allocating group tables: 0/1 done
413s Writing inode tables: 0/1 done
413s + mount /dev/mapper/img-luks2 /tmp/tmp.FxQFxIFmmd
413s + echo libpam-mount is working
413s + umount /dev/mapper/img-luks2
413s + cryptsetup close img-luks2
413s + checkluks 1
413s + id=1
413s + mktemp
413s + bckp=/tmp/tmp.aDdh6TcnG8
413s + cp /etc/security/pam_mount.conf.xml /tmp/tmp.aDdh6TcnG8
413s + sed -i -e s/debug enable="0"/debug enable="1"/ /etc/security/pam_mount.conf.xml
413s Creating journal (1024 blocks): done
413s Writing superblocks and filesystem accounting information: 0/1 done
413s
413s + sed -i -e //a /etc/security/pam_mount.conf.xml
413s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost ls -laF ~/img-luks1/testfile
413s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
416s -rw-r--r-- 1 root root 24 Mar 14 15:56 /home/alice/img-luks1/testfile
416s + sleep 5s
421s + ssh -o StrictHostKeyChecking=no -i+ test.key alice@localhost mount
421s grep img-luks1
423s /dev/mapper/_tmp_disk_l1_img on /home/alice/img-luks1 type ext4 (rw,relatime,helper=crypt)
423s + sleep 5s
428s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-auth
428s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-session
428s + su --login --command cat ~/img-luks1/testfile alice
428s (pam_mount.c:568): pam_mount 2.20: entering session stage
428s (mount.c:264): Mount info: globalconf, user=alice
428s (mount.c:678): Password will be sent to helper as-is.
428s command: 'mount' '-t' 'crypt' '-ofsk_cipher=none' '-ofsk_hash=md5' '-okeyfile=/home/alice/alice.key' '/tmp/disk-l1.img' '/home/alice/img-luks1'
430s (mount.c:567): 23 28 0:21 / /sys rw,nosuid,nodev,noexec,relatime shared:7 - sysfs sysfs rw
430s (mount.c:567): 24 28 0:22 / /proc rw,nosuid,nodev,noexec,relatime shared:12 - proc proc rw
430s (mount.c:567): 25 28 0:5 / /dev rw,nosuid,relatime shared:2 - devtmpfs udev rw,size=692544k,nr_inodes=10821,mode=755,inode64
430s (mount.c:567): 26 25 0:23 / /dev/pts rw,nosuid,noexec,relatime shared:3 - devpts devpts rw,gid=5,mode=620,ptmxmode=000
430s (mount.c:567): 27 28 0:24 / /run rw,nosuid,nodev,noexec,relatime shared:5 - tmpfs tmpfs rw,size=144448k,mode=755,inode64
430s (mount.c:567): 28 1 8:1 / / rw,relatime shared:1 - ext4 /dev/sda1 rw,discard,errors=remount-ro,commit=30
430s (mount.c:567): 29 23 0:6 / /sys/kernel/security rw,nosuid,nodev,noexec,relatime shared:8 - securityfs securityfs rw
430s (mount.c:567): 30 25 0:25 / /dev/shm rw,nosuid,nodev shared:4 - tmpfs tmpfs rw,inode64
430s (mount.c:567): 31 27 0:26 / /run/lock rw,nosuid,nodev,noexec,relatime shared:6 - tmpfs tmpfs rw,size=5120k,inode64
430s (mount.c:567): 32 23 0:27 / /sys/fs/cgroup rw,nosuid,nodev,noexec,relatime shared:9 - cgroup2 cgroup2 rw,nsdelegate,memory_recursiveprot
430s (mount.c:567): 33 23 0:28 / /sys/fs/pstore rw,nosuid,nodev,noexec,relatime shared:10 - pstore pstore rw
430s (mount.c:567): 34 23 0:29 / /sys/fs/bpf rw,nosuid,nodev,noexec,relatime shared:11 - bpf bpf rw,mode=700
430s (mount.c:567): 35 24 0:30 / /proc/sys/fs/binfmt_misc rw,relatime shared:13 - autofs systemd-1 rw,fd=32,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=2802
430s (mount.c:567): 36 25 0:31 / /dev/hugepages rw,nosuid,nodev,relatime shared:14 - hugetlbfs hugetlbfs rw,pagesize=2M
430s (mount.c:567): 37 25 0:19 / /dev/mqueue rw,nosuid,nodev,noexec,relatime shared:15 - mqueue mqueue rw
430s (mount.c:567): 38 23 0:7 / /sys/kernel/debug rw,nosuid,nodev,noexec,relatime shared:16 - debugfs debugfs rw
430s (mount.c:567): 39 23 0:12 / /sys/kernel/tracing rw,nosuid,nodev,noexec,relatime shared:17 - tracefs tracefs rw
430s (mount.c:567): 40 23 0:32 / /sys/fs/fuse/connections rw,nosuid,nodev,noexec,relatime shared:18 - fusectl fusectl rw
430s (mount.c:567): 41 23 0:20 / /sys/kernel/config rw,nosuid,nodev,noexec,relatime shared:19 - configfs configfs rw
430s (mount.c:567): 43 35 0:33 / /proc/sys/fs/binfmt_misc rw,nosuid,nodev,noexec,relatime shared:29 - binfmt_misc binfmt_misc rw
430s (mount.c:567): 49 27 0:44 / /run/user/1000 rw,nosuid,nodev,relatime shared:192 - tmpfs tmpfs rw,size=144384k,nr_inodes=36096,mode=700,uid=1000,gid=1000,inode64
430s (mount.c:567): 87 27 0:41 / /run/user/1001 rw,nosuid,nodev,relatime shared:196 - tmpfs tmpfs rw,size=144384k,nr_inodes=36096,mode=700,uid=1001,gid=1001,inode64
430s (mount.c:567): 48 28 252:0 / /home/alice/img-luks1 rw,relatime shared:159 - ext4 /dev/mapper/_tmp_disk_l1_img rw
430s command: 'pmvarrun' '-u' 'alice' '-o' '1'
431s (pmvarrun.c:261): parsed count value 0
431s (pam_mount.c:441): pmvarrun says login count is 1
431s (pam_mount.c:660): done opening session (ret=0)
431s (pam_mount.c:116): Clean global config (1073741824)
431s (pam_mount.c:706): received order to close things
431s command: 'pmvarrun' '-u' 'alice' '-o' '-1'
431s (pmvarrun.c:261): parsed count value 1
431s (pam_mount.c:441): pmvarrun says login count is 0
431s (mount.c:904): going to unmount
431s (mount.c:264): Mount info: globalconf, user=alice
431s command: 'ofl' '-k0' '/home/alice/img-luks1'
431s command: 'umount' '/home/alice/img-luks1'
431s (pam_mount.c:743): pam_mount execution complete
431s (pam_mount.c:116): Clean global config (0)
431s + sleep 5s
436s + cmp expected luks1
436s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost cat ~/img-luks1/testfile
438s + sleep 5s
443s + cmp expected luks1
443s + cp /tmp/tmp.aDdh6TcnG8 /etc/security/pam_mount.conf.xml
443s + checkluks 2
443s + id=2
443s + mktemp
443s + bckp=/tmp/tmp.tuRDVjXEXd
443s + cp /etc/security/pam_mount.conf.xml /tmp/tmp.tuRDVjXEXd
443s + sed -i -e s/debug enable="0"/debug enable="1"/ /etc/security/pam_mount.conf.xml
443s + sed -i -e //a /etc/security/pam_mount.conf.xml
443s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost ls -laF ~/img-luks2/testfile
446s -rw-r--r-- 1 root root 24 Mar 14 15:56 /home/alice/img-luks2/testfile
446s + sleep 5s
451s + + grep img-luks2
451s ssh -o StrictHostKeyChecking=no -i test.key alice@localhost mount
453s /dev/mapper/_tmp_disk_l2_img on /home/alice/img-luks2 type ext4 (rw,relatime,helper=crypt)
453s + sleep 5s
458s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-auth
458s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-session
458s + su --login --command cat ~/img-luks2/testfile alice
458s (pam_mount.c:568): pam_mount 2.20: entering session stage
458s (mount.c:264): Mount info: globalconf, user=alice
458s (mount.c:678): Password will be sent to helper as-is.
458s command: 'mount' '-t' 'crypt' '-ofsk_cipher=none' '-ofsk_hash=md5' '-okeyfile=/home/alice/alice.key' '/tmp/disk-l2.img' '/home/alice/img-luks2'
460s (mount.c:567): 23 28 0:21 / /sys rw,nosuid,nodev,noexec,relatime shared:7 - sysfs sysfs rw
460s (mount.c:567): 24 28 0:22 / /proc rw,nosuid,nodev,noexec,relatime shared:12 - proc proc rw
460s (mount.c:567): 25 28 0:5 / /dev rw,nosuid,relatime shared:2 - devtmpfs udev rw,size=692544k,nr_inodes=10821,mode=755,inode64
460s (mount.c:567): 26 25 0:23 / /dev/pts rw,nosuid,noexec,relatime shared:3 - devpts devpts rw,gid=5,mode=620,ptmxmode=000
460s (mount.c:567): 27 28 0:24 / /run rw,nosuid,nodev,noexec,relatime shared:5 - tmpfs tmpfs rw,size=144448k,mode=755,inode64
460s (mount.c:567): 28 1 8:1 / / rw,relatime shared:1 - ext4 /dev/sda1 rw,discard,errors=remount-ro,commit=30
460s (mount.c:567): 29 23 0:6 / /sys/kernel/security rw,nosuid,nodev,noexec,relatime shared:8 - securityfs securityfs rw
460s (mount.c:567): 30 25 0:25 / /dev/shm rw,nosuid,nodev shared:4 - tmpfs tmpfs rw,inode64
460s (mount.c:567): 31 27 0:26 / /run/lock rw,nosuid,nodev,noexec,relatime shared:6 - tmpfs tmpfs rw,size=5120k,inode64
460s (mount.c:567): 32 23 0:27 / /sys/fs/cgroup rw,nosuid,nodev,noexec,relatime shared:9 - cgroup2 cgroup2 rw,nsdelegate,memory_recursiveprot
460s (mount.c:567): 33 23 0:28 / /sys/fs/pstore rw,nosuid,nodev,noexec,relatime shared:10 - pstore pstore rw
460s (mount.c:567): 34 23 0:29 / /sys/fs/bpf rw,nosuid,nodev,noexec,relatime shared:11 - bpf bpf rw,mode=700
460s (mount.c:567): 35 24 0:30 / /proc/sys/fs/binfmt_misc rw,relatime shared:13 - autofs systemd-1 rw,fd=32,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=2802
460s (mount.c:567): 36 25 0:31 / /dev/hugepages rw,nosuid,nodev,relatime shared:14 - hugetlbfs hugetlbfs rw,pagesize=2M
460s (mount.c:567): 37 25 0:19 / /dev/mqueue rw,nosuid,nodev,noexec,relatime shared:15 - mqueue mqueue rw
460s (mount.c:567): 38 23 0:7 / /sys/kernel/debug rw,nosuid,nodev,noexec,relatime shared:16 - debugfs debugfs rw
460s (mount.c:567): 39 23 0:12 / /sys/kernel/tracing rw,nosuid,nodev,noexec,relatime shared:17 - tracefs tracefs rw
460s (mount.c:567): 40 23 0:32 / /sys/fs/fuse/connections rw,nosuid,nodev,noexec,relatime shared:18 - fusectl fusectl rw
460s (mount.c:567): 41 23 0:20 / /sys/kernel/config rw,nosuid,nodev,noexec,relatime shared:19 - configfs configfs rw
460s (mount.c:567): 43 35 0:33 / /proc/sys/fs/binfmt_misc rw,nosuid,nodev,noexec,relatime shared:29 - binfmt_misc binfmt_misc rw
460s (mount.c:567): 49 27 0:44 / /run/user/1000 rw,nosuid,nodev,relatime shared:192 - tmpfs tmpfs rw,size=144384k,nr_inodes=36096,mode=700,uid=1000,gid=1000,inode64
460s (mount.c:567): 87 27 0:41 / /run/user/1001 rw,nosuid,nodev,relatime shared:196 - tmpfs tmpfs rw,size=144384k,nr_inodes=36096,mode=700,uid=1001,gid=1001,inode64
460s (mount.c:567): 48 28 252:0 / /home/alice/img-luks2 rw,relatime shared:159 - ext4 /dev/mapper/_tmp_disk_l2_img rw
460s command: 'pmvarrun' '-u' 'alice' '-o' '1'
460s (pmvarrun.c:261): parsed count value 0
460s (pam_mount.c:441): pmvarrun says login count is 1
460s (pam_mount.c:660): done opening session (ret=0)
460s (pam_mount.c:116): Clean global config (1073741824)
460s (pam_mount.c:706): received order to close things
460s command: 'pmvarrun' '-u' 'alice' '-o' '-1'
460s (pmvarrun.c:261): parsed count value 1
460s (pam_mount.c:441): pmvarrun says login count is 0
460s (mount.c:904): going to unmount
460s (mount.c:264): Mount info: globalconf, user=alice
460s command: 'ofl' '-k0' '/home/alice/img-luks2'
460s command: 'umount' '/home/alice/img-luks2'
460s (pam_mount.c:743): pam_mount execution complete
460s (pam_mount.c:116): Clean global config (0)
460s + sleep 5s
465s + cmp expected luks2
465s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost cat ~/img-luks2/testfile
468s + sleep 5s
473s + cmp expected luks2
473s + cp /tmp/tmp.tuRDVjXEXd /etc/security/pam_mount.conf.xml
473s autopkgtest [15:57:50]: test local-luks: -----------------------]
474s autopkgtest [15:57:51]: test local-luks: - - - - - - - - - - results - - - - - - - - - -
474s local-luks PASS
474s autopkgtest [15:57:51]: @@@@@@@@@@@@@@@@@@@@ summary
474s local-luks PASS
502s Creating nova instance adt-noble-ppc64el-libpam-mount-20240314-154957-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240314.img (UUID 438daa89-732e-4eab-98ca-4d7eade8166d)...