0s autopkgtest [20:38:49]: starting date and time: 2024-03-21 20:38:49+0000
  0s autopkgtest [20:38:49]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps
  0s autopkgtest [20:38:49]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.q0g0k8kv/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:openssh,src:at-spi2-core,src:glib2.0,src:gtk+3.0,src:openssl --apt-upgrade libpam-mount --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=openssh/1:9.6p1-3ubuntu11 at-spi2-core/2.51.90-3 glib2.0/2.79.3-3ubuntu5 gtk+3.0/3.24.41-1.1ubuntu1 openssl/3.0.13-0ubuntu2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-6.secgroup --name adt-noble-s390x-libpam-mount-20240321-203849-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-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/
139s autopkgtest [20:41:08]: testbed dpkg architecture: s390x
139s autopkgtest [20:41:08]: testbed apt version: 2.7.12
139s autopkgtest [20:41:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup
140s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
140s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [496 kB]
141s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3779 kB]
142s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB]
142s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
142s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [663 kB]
142s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B]
142s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B]
142s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B]
142s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3987 kB]
143s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B]
143s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB]
143s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B]
144s Fetched 9159 kB in 3s (2698 kB/s)
144s Reading package lists...
147s Reading package lists...
147s Building dependency tree...
147s Reading state information...
150s Calculating upgrade...
150s The following packages will be REMOVED:
150s   libglib2.0-0 libssl3
150s The following NEW packages will be installed:
150s   libglib2.0-0t64 libssl3t64 xdg-user-dirs
150s The following packages will be upgraded:
150s   debianutils gir1.2-glib-2.0 libglib2.0-data openssh-client openssh-server
150s   openssh-sftp-server openssl
150s 7 upgraded, 3 newly installed, 2 to remove and 0 not upgraded.
150s Need to get 6088 kB of archives.
150s After this operation, 902 kB of additional disk space will be used.
150s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB]
150s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x gir1.2-glib-2.0 s390x 2.79.3-3ubuntu5 [180 kB]
150s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libglib2.0-0t64 s390x 2.79.3-3ubuntu5 [1566 kB]
150s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB]
150s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssh-sftp-server s390x 1:9.6p1-3ubuntu11 [39.0 kB]
150s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssh-server s390x 1:9.6p1-3ubuntu11 [529 kB]
150s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssh-client s390x 1:9.6p1-3ubuntu11 [935 kB]
150s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB]
150s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libglib2.0-data all 2.79.3-3ubuntu5 [46.6 kB]
150s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x xdg-user-dirs s390x 0.18-1 [18.5 kB]
150s Preconfiguring packages ...
150s Fetched 6088 kB in 2s (3199 kB/s)
150s (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 ... 52171 files and directories currently installed.)
150s Preparing to unpack .../debianutils_5.17_s390x.deb ...
150s Unpacking debianutils (5.17) over (5.16) ...
150s Setting up debianutils (5.17) ...
150s (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 ... 52171 files and directories currently installed.)
150s Preparing to unpack .../gir1.2-glib-2.0_2.79.3-3ubuntu5_s390x.deb ...
150s Unpacking gir1.2-glib-2.0:s390x (2.79.3-3ubuntu5) over (2.79.2-1~ubuntu1) ...
150s dpkg: libglib2.0-0:s390x: dependency problems, but removing anyway as you requested:
150s  udisks2 depends on libglib2.0-0 (>= 2.77.0).
150s  shared-mime-info depends on libglib2.0-0 (>= 2.75.3).
150s  s390-tools depends on libglib2.0-0 (>= 2.77.0).
150s  python3-gi depends on libglib2.0-0 (>= 2.77.0).
150s  python3-dbus depends on libglib2.0-0 (>= 2.16.0).
150s  netplan.io depends on libglib2.0-0 (>= 2.70.0).
150s  netplan-generator depends on libglib2.0-0 (>= 2.70.0).
150s  libxmlb2:s390x depends on libglib2.0-0 (>= 2.54.0).
150s  libvolume-key1:s390x depends on libglib2.0-0 (>= 2.18.0).
150s  libudisks2-0:s390x depends on libglib2.0-0 (>= 2.75.3).
150s  libqrtr-glib0:s390x depends on libglib2.0-0 (>= 2.56).
150s  libqmi-proxy depends on libglib2.0-0 (>= 2.30.0).
150s  libqmi-glib5:s390x depends on libglib2.0-0 (>= 2.54.0).
150s  libpolkit-gobject-1-0:s390x depends on libglib2.0-0 (>= 2.38.0).
150s  libpolkit-agent-1-0:s390x depends on libglib2.0-0 (>= 2.38.0).
150s  libnetplan0:s390x depends on libglib2.0-0 (>= 2.75.3).
150s  libmm-glib0:s390x depends on libglib2.0-0 (>= 2.62.0).
150s  libmbim-proxy depends on libglib2.0-0 (>= 2.56).
150s  libmbim-glib4:s390x depends on libglib2.0-0 (>= 2.56).
150s  libjson-glib-1.0-0:s390x depends on libglib2.0-0 (>= 2.75.3).
150s  libjcat1:s390x depends on libglib2.0-0 (>= 2.75.3).
150s  libgusb2:s390x depends on libglib2.0-0 (>= 2.75.3).
150s  libgudev-1.0-0:s390x depends on libglib2.0-0 (>= 2.38.0).
150s  libgirepository-1.0-1:s390x depends on libglib2.0-0 (>= 2.79.0).
150s  libfwupd2:s390x depends on libglib2.0-0 (>= 2.79.0).
150s  libblockdev3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-utils3:s390x depends on libglib2.0-0 (>= 2.75.3).
150s  libblockdev-swap3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-part3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-nvme3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-mdraid3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-loop3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-fs3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  libblockdev-crypto3:s390x depends on libglib2.0-0 (>= 2.42.2).
150s  fwupd depends on libglib2.0-0 (>= 2.79.0).
150s  bolt depends on libglib2.0-0 (>= 2.56.0).
150s 
150s (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 ... 52171 files and directories currently installed.)
150s Removing libglib2.0-0:s390x (2.79.2-1~ubuntu1) ...
150s Selecting previously unselected package libglib2.0-0t64:s390x.
150s (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 ... 52146 files and directories currently installed.)
150s Preparing to unpack .../libglib2.0-0t64_2.79.3-3ubuntu5_s390x.deb ...
150s libglib2.0-0t64.preinst: Removing /var/lib/dpkg/info/libglib2.0-0:s390x.postrm to avoid loss of /usr/share/glib-2.0/schemas/gschemas.compiled...
150s removed '/var/lib/dpkg/info/libglib2.0-0:s390x.postrm'
150s Unpacking libglib2.0-0t64:s390x (2.79.3-3ubuntu5) ...
150s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ...
150s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ...
151s Preparing to unpack .../openssh-sftp-server_1%3a9.6p1-3ubuntu11_s390x.deb ...
151s Unpacking openssh-sftp-server (1:9.6p1-3ubuntu11) over (1:9.6p1-3ubuntu2) ...
151s Preparing to unpack .../openssh-server_1%3a9.6p1-3ubuntu11_s390x.deb ...
151s Unpacking openssh-server (1:9.6p1-3ubuntu11) over (1:9.6p1-3ubuntu2) ...
151s Preparing to unpack .../openssh-client_1%3a9.6p1-3ubuntu11_s390x.deb ...
151s Unpacking openssh-client (1:9.6p1-3ubuntu11) over (1:9.6p1-3ubuntu2) ...
151s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested:
151s  wget depends on libssl3 (>= 3.0.0).
151s  tnftp depends on libssl3 (>= 3.0.0).
151s  tcpdump depends on libssl3 (>= 3.0.0).
151s  systemd-resolved depends on libssl3 (>= 3.0.0).
151s  systemd depends on libssl3 (>= 3.0.0).
151s  sudo depends on libssl3 (>= 3.0.0).
151s  s390-tools depends on libssl3 (>= 3.0.0).
151s  rsync depends on libssl3 (>= 3.0.0).
151s  python3-cryptography depends on libssl3 (>= 3.0.0).
151s  linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0).
151s  libsystemd-shared:s390x depends on libssl3 (>= 3.0.0).
151s  libssh-4:s390x depends on libssl3 (>= 3.0.0).
151s  libsasl2-modules:s390x depends on libssl3 (>= 3.0.0).
151s  libsasl2-2:s390x depends on libssl3 (>= 3.0.0).
151s  libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0).
151s  libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0).
151s  libnvme1 depends on libssl3 (>= 3.0.0).
151s  libkrb5-3:s390x depends on libssl3 (>= 3.0.0).
151s  libkmod2:s390x depends on libssl3 (>= 3.0.0).
151s  libfido2-1:s390x depends on libssl3 (>= 3.0.0).
151s  libcurl4:s390x depends on libssl3 (>= 3.0.0).
151s  libcryptsetup12:s390x depends on libssl3 (>= 3.0.0).
151s  kmod depends on libssl3 (>= 3.0.0).
151s  dhcpcd-base depends on libssl3 (>= 3.0.0).
151s  bind9-libs:s390x depends on libssl3 (>= 3.0.0).
151s 
151s (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 ... 52171 files and directories currently installed.)
151s Removing libssl3:s390x (3.0.10-1ubuntu4) ...
151s Selecting previously unselected package libssl3t64:s390x.
151s (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 ... 52160 files and directories currently installed.)
151s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ...
151s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ...
151s Preparing to unpack .../libglib2.0-data_2.79.3-3ubuntu5_all.deb ...
151s Unpacking libglib2.0-data (2.79.3-3ubuntu5) over (2.79.2-1~ubuntu1) ...
151s Selecting previously unselected package xdg-user-dirs.
151s Preparing to unpack .../xdg-user-dirs_0.18-1_s390x.deb ...
151s Unpacking xdg-user-dirs (0.18-1) ...
151s Setting up xdg-user-dirs (0.18-1) ...
151s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ...
151s Setting up openssh-client (1:9.6p1-3ubuntu11) ...
151s Setting up libglib2.0-0t64:s390x (2.79.3-3ubuntu5) ...
151s No schema files found: doing nothing.
151s Setting up libglib2.0-data (2.79.3-3ubuntu5) ...
151s Setting up gir1.2-glib-2.0:s390x (2.79.3-3ubuntu5) ...
151s Setting up openssl (3.0.13-0ubuntu2) ...
151s Setting up openssh-sftp-server (1:9.6p1-3ubuntu11) ...
151s Setting up openssh-server (1:9.6p1-3ubuntu11) ...
151s Replacing config file /etc/ssh/sshd_config with new version
153s Created symlink /etc/systemd/system/ssh.service.requires/ssh.socket → /usr/lib/systemd/system/ssh.socket.

154s Processing triggers for ufw (0.36.2-5) ...
154s Processing triggers for man-db (2.12.0-3) ...
155s Processing triggers for libc-bin (2.39-0ubuntu2) ...
155s Reading package lists...
155s Building dependency tree...
155s Reading state information...
155s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
156s Unknown architecture, assuming PC-style ttyS0
156s sh: Attempting to set up Debian/Ubuntu apt sources automatically
156s sh: Distribution appears to be Ubuntu
157s Reading package lists...
157s Building dependency tree...
157s Reading state information...
157s eatmydata is already the newest version (131-1).
157s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
157s Reading package lists...
157s Building dependency tree...
157s Reading state information...
157s dbus is already the newest version (1.14.10-4ubuntu1).
157s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
157s Reading package lists...
157s Building dependency tree...
157s Reading state information...
158s rng-tools-debian is already the newest version (2.4).
158s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
158s Reading package lists...
158s Building dependency tree...
158s Reading state information...
158s The following packages will be REMOVED:
158s   cloud-init* python3-configobj* python3-debconf*
158s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
158s After this operation, 3252 kB disk space will be freed.
158s (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 ... 52187 files and directories currently installed.)
158s Removing cloud-init (24.1.1-0ubuntu1) ...
159s Removing python3-configobj (5.0.8-3) ...
159s Removing python3-debconf (1.5.86) ...
159s Processing triggers for man-db (2.12.0-3) ...
159s (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 ... 51798 files and directories currently installed.)
159s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ...
160s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
160s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
160s invoke-rc.d: policy-rc.d denied execution of try-restart.
160s Reading package lists...
160s Building dependency tree...
160s Reading state information...
160s linux-generic is already the newest version (6.8.0-11.11+1).
160s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
161s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
161s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
161s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
163s Reading package lists...
163s Reading package lists...
163s Building dependency tree...
163s Reading state information...
163s Calculating upgrade...
163s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
163s Reading package lists...
163s Building dependency tree...
163s Reading state information...
164s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
164s autopkgtest [20:41:33]: rebooting testbed after setup commands that affected boot
183s autopkgtest [20:41:52]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024
186s autopkgtest [20:41:55]: @@@@@@@@@@@@@@@@@@@@ apt-source libpam-mount
189s Get:1 http://ftpmaster.internal/ubuntu noble/main libpam-mount 2.20-3 (dsc) [2196 B]
189s Get:2 http://ftpmaster.internal/ubuntu noble/main libpam-mount 2.20-3 (tar) [325 kB]
189s Get:3 http://ftpmaster.internal/ubuntu noble/main libpam-mount 2.20-3 (diff) [29.0 kB]
189s gpgv: Signature made Sun Dec 17 05:49:05 2023 UTC
189s gpgv:                using RSA key 73B299CBD4EEADDCC017E87A5BFFDCC258E69433
189s gpgv: Can't check signature: No public key
189s dpkg-source: warning: cannot verify inline signature for ./libpam-mount_2.20-3.dsc: no acceptable signature found
189s autopkgtest [20:41:58]: testing package libpam-mount version 2.20-3
190s autopkgtest [20:41:59]: build not needed
191s autopkgtest [20:42:00]: test local-luks: preparing testbed
196s Reading package lists...
196s Building dependency tree...
196s Reading state information...
196s Starting pkgProblemResolver with broken count: 0
196s Starting 2 pkgProblemResolver with broken count: 0
196s Done
196s The following additional packages will be installed:
196s   cryptsetup hxtools libhx32 libpam-mount libpam-mount-bin
196s Suggested packages:
196s   keyutils cifs-utils davfs2 sshfs
196s Recommended packages:
196s   cryptsetup-initramfs
196s The following NEW packages will be installed:
196s   autopkgtest-satdep cryptsetup hxtools libhx32 libpam-mount libpam-mount-bin
196s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded.
196s Need to get 447 kB/448 kB of archives.
196s After this operation, 1258 kB of additional disk space will be used.
196s Get:1 /tmp/autopkgtest.BX14G3/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [760 B]
196s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x cryptsetup s390x 2:2.7.0-1ubuntu1 [208 kB]
197s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libhx32 s390x 4.19-1 [47.0 kB]
197s Get:4 http://ftpmaster.internal/ubuntu noble/universe s390x hxtools s390x 20231224-2 [99.8 kB]
197s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libpam-mount s390x 2.20-3 [61.9 kB]
197s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libpam-mount-bin s390x 2.20-3 [30.2 kB]
197s Preconfiguring packages ...
197s Fetched 447 kB in 1s (502 kB/s)
197s Selecting previously unselected package cryptsetup.
198s (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 ... 51743 files and directories currently installed.)
198s Preparing to unpack .../0-cryptsetup_2%3a2.7.0-1ubuntu1_s390x.deb ...
198s Unpacking cryptsetup (2:2.7.0-1ubuntu1) ...
198s Selecting previously unselected package libhx32:s390x.
198s Preparing to unpack .../1-libhx32_4.19-1_s390x.deb ...
198s Unpacking libhx32:s390x (4.19-1) ...
198s Selecting previously unselected package hxtools.
198s Preparing to unpack .../2-hxtools_20231224-2_s390x.deb ...
198s Unpacking hxtools (20231224-2) ...
198s Selecting previously unselected package libpam-mount:s390x.
198s Preparing to unpack .../3-libpam-mount_2.20-3_s390x.deb ...
198s Unpacking libpam-mount:s390x (2.20-3) ...
198s Selecting previously unselected package libpam-mount-bin.
198s Preparing to unpack .../4-libpam-mount-bin_2.20-3_s390x.deb ...
198s Unpacking libpam-mount-bin (2.20-3) ...
198s Selecting previously unselected package autopkgtest-satdep.
198s Preparing to unpack .../5-1-autopkgtest-satdep.deb ...
198s Unpacking autopkgtest-satdep (0) ...
198s Setting up libhx32:s390x (4.19-1) ...
198s Setting up cryptsetup (2:2.7.0-1ubuntu1) ...
198s Setting up hxtools (20231224-2) ...
198s Setting up libpam-mount:s390x (2.20-3) ...
198s Setting up libpam-mount-bin (2.20-3) ...
198s Setting up autopkgtest-satdep (0) ...
198s Processing triggers for man-db (2.12.0-3) ...
199s Processing triggers for libc-bin (2.39-0ubuntu2) ...
202s (Reading database ... 51994 files and directories currently installed.)
202s Removing autopkgtest-satdep (0) ...
202s autopkgtest [20:42:11]: test local-luks: [-----------------------
202s + echo libpam-mount is working
202s + openssl passwd -1 alice
202s + useradd -m --password $1$GwsYAQdP$Zh9cH1U45fbO8lebjQPwn0 alice
202s + printf alice
202s + ssh-keygen -t rsa -N  -f test.key
204s Generating public/private rsa key pair.
204s Your identification has been saved in test.key
204s Your public key has been saved in test.key.pub
204s The key fingerprint is:
204s SHA256:L2ParoiGyDpAxAbzDTKEoPwP99Z58fMntf1/6rC2xIU root@autopkgtest
204s The key's randomart image is:
204s +---[RSA 3072]----+
204s |%..              |
204s |+O o             |
204s |+.. .            |
204s | ..          .   |
204s |.  o .  S  .E .  |
204s |.   + . ....o.  .|
204s |+.   . o+o..+o .o|
204s |+... ..+ o...o+.+|
204s |oo. . ooo  .ooo+B|
204s +----[SHA256]-----+
204s + mkdir -p /home/alice/.ssh
204s + chown alice:alice /home/alice/.ssh
204s + cp test.key.pub /home/alice/.ssh/authorized_keys
204s + mkdir -p /home/alice/img-luks1 /home/alice/img-luks2
204s + chown alice:alice /home/alice/.ssh/authorized_keys /home/alice/img-luks1 /home/alice/img-luks2
204s + createluks 1 luks
204s + id=1
204s + type=luks
204s + mktemp --directory
204s + tdir=/tmp/tmp.iNFUuBYXMa
204s + fallocate -l 32M /tmp/disk-l1.img
204s + cryptsetup luksFormat --batch-mode --verbose --force-password --key-file=/home/alice/alice.key --type luks /tmp/disk-l1.img
213s Key slot 0 created.
213s Command successful.
213s + cryptsetup open --type luks --batch-mode --verbose --key-file=/home/alice/alice.key /tmp/disk-l1.img img-luks1
215s No usable token is available.
215s Key slot 0 unlocked.
215s Command successful.
215s + mkfs.ext4 -L IMG-LUKS1 /dev/mapper/img-luks1
215s mke2fs 1.47.0 (5-Feb-2023)
215s Creating filesystem with 4096 4k blocks and 4096 inodes
215s 
215s Allocating group tables: 0/1   done                            
215s Writing inode tables: 0/1   done                            
215s Creating journal (1024 blocks): done
215s Writing superblocks and filesystem accounting information: 0/1   done
215s 
215s + mount /dev/mapper/img-luks1 /tmp/tmp.iNFUuBYXMa
215s + echo libpam-mount is working
215s + umount /dev/mapper/img-luks1
215s + cryptsetup close img-luks1
215s + createluks 2 luks2
215s + id=2
215s + type=luks2
215s + mktemp --directory
215s + tdir=/tmp/tmp.6u5d7DXjeL
215s + fallocate -l 32M /tmp/disk-l2.img
215s + cryptsetup luksFormat --batch-mode --verbose --force-password --key-file=/home/alice/alice.key --type luks2 /tmp/disk-l2.img
224s Key slot 0 created.
224s Command successful.
224s + cryptsetup open --type luks2 --batch-mode --verbose --key-file=/home/alice/alice.key /tmp/disk-l2.img img-luks2
226s No usable token is available.
226s Key slot 0 unlocked.
226s Command successful.
226s + mkfs.ext4 -L IMG-LUKS2 /dev/mapper/img-luks2
226s mke2fs 1.47.0 (5-Feb-2023)
226s Creating filesystem with 4096 4k blocks and 4096 inodes
226s 
226s Allocating group tables: 0/1   done                            
226s Writing inode tables: 0/1   done                            
226s Creating journal (1024 blocks): done
226s Writing superblocks and filesystem accounting information: 0/1   done
226s 
226s + mount /dev/mapper/img-luks2 /tmp/tmp.6u5d7DXjeL
226s + echo libpam-mount is working
226s + umount /dev/mapper/img-luks2
226s + cryptsetup close img-luks2
226s + checkluks 1
226s + id=1
226s + mktemp
226s + bckp=/tmp/tmp.zOroFo54ss
226s + cp /etc/security/pam_mount.conf.xml /tmp/tmp.zOroFo54ss
226s + sed -i -e s/debug enable="0"/debug enable="1"/ /etc/security/pam_mount.conf.xml
226s + sed -i -e /<!-- Volume definitions -->/a<volume user='alice' path='/tmp/disk-l1.img' mountpoint='~/img-luks1' fstype='crypt' fskeycipher='none' fskeyhash='md5' fskeypath='/home/alice/alice.key' /> /etc/security/pam_mount.conf.xml
226s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost ls -laF ~/img-luks1/testfile
227s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
229s -rw-r--r-- 1 root root 24 Mar 21 20:42 /home/alice/img-luks1/testfile
229s + sleep 5s
234s + + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost mount
234s grep img-luks1
237s /dev/mapper/_tmp_disk_l1_img on /home/alice/img-luks1 type ext4 (rw,relatime,helper=crypt)
237s + sleep 5s
242s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-auth
242s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-session
242s + su --login --command cat ~/img-luks1/testfile alice
242s (pam_mount.c:568): pam_mount 2.20: entering session stage
242s (mount.c:264): Mount info: globalconf, user=alice <volume fstype="crypt" server="(null)" path="/tmp/disk-l1.img" mountpoint="/home/alice/img-luks1" cipher="(null)" fskeypath="/home/alice/alice.key" fskeycipher="none" fskeyhash="md5" header="(null)" options="" fstab=0 ssh=0 ignoresource=0 /> 
242s (mount.c:678): Password will be sent to helper as-is.
242s command: 'mount' '-t' 'crypt' '-ofsk_cipher=none' '-ofsk_hash=md5' '-okeyfile=/home/alice/alice.key' '/tmp/disk-l1.img' '/home/alice/img-luks1' 
244s (mount.c:567): 23 28 0:21 / /sys rw,nosuid,nodev,noexec,relatime shared:7 - sysfs sysfs rw
244s (mount.c:567): 24 28 0:22 / /proc rw,nosuid,nodev,noexec,relatime shared:11 - proc proc rw
244s (mount.c:567): 25 28 0:5 / /dev rw,nosuid,relatime shared:2 - devtmpfs udev rw,size=746816k,nr_inodes=186704,mode=755,inode64
244s (mount.c:567): 26 25 0:23 / /dev/pts rw,nosuid,noexec,relatime shared:3 - devpts devpts rw,gid=5,mode=620,ptmxmode=000
244s (mount.c:567): 27 28 0:24 / /run rw,nosuid,nodev,noexec,relatime shared:5 - tmpfs tmpfs rw,size=151640k,mode=755,inode64
244s (mount.c:567): 28 1 253:1 / / rw,relatime shared:1 - ext4 /dev/vda1 rw,discard,errors=remount-ro,commit=30
244s (mount.c:567): 29 23 0:6 / /sys/kernel/security rw,nosuid,nodev,noexec,relatime shared:8 - securityfs securityfs rw
244s (mount.c:567): 30 25 0:25 / /dev/shm rw,nosuid,nodev shared:4 - tmpfs tmpfs rw,inode64
244s (mount.c:567): 31 27 0:26 / /run/lock rw,nosuid,nodev,noexec,relatime shared:6 - tmpfs tmpfs rw,size=5120k,inode64
244s (mount.c:567): 32 23 0:27 / /sys/fs/cgroup rw,nosuid,nodev,noexec,relatime shared:9 - cgroup2 cgroup2 rw,nsdelegate,memory_recursiveprot
244s (mount.c:567): 33 23 0:28 / /sys/fs/bpf rw,nosuid,nodev,noexec,relatime shared:10 - bpf bpf rw,mode=700
244s (mount.c:567): 34 24 0:29 / /proc/sys/fs/binfmt_misc rw,relatime shared:12 - autofs systemd-1 rw,fd=31,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=1746
244s (mount.c:567): 35 25 0:19 / /dev/mqueue rw,nosuid,nodev,noexec,relatime shared:13 - mqueue mqueue rw
244s (mount.c:567): 36 25 0:30 / /dev/hugepages rw,nosuid,nodev,relatime shared:14 - hugetlbfs hugetlbfs rw,pagesize=1M
244s (mount.c:567): 37 23 0:12 / /sys/kernel/tracing rw,nosuid,nodev,noexec,relatime shared:15 - tracefs tracefs rw
244s (mount.c:567): 38 23 0:7 / /sys/kernel/debug rw,nosuid,nodev,noexec,relatime shared:16 - debugfs debugfs rw
244s (mount.c:567): 39 23 0:31 / /sys/fs/fuse/connections rw,nosuid,nodev,noexec,relatime shared:17 - fusectl fusectl rw
244s (mount.c:567): 40 23 0:20 / /sys/kernel/config rw,nosuid,nodev,noexec,relatime shared:18 - configfs configfs rw
244s (mount.c:567): 42 34 0:32 / /proc/sys/fs/binfmt_misc rw,nosuid,nodev,noexec,relatime shared:28 - binfmt_misc binfmt_misc rw
244s (mount.c:567): 48 27 0:43 / /run/user/1000 rw,nosuid,nodev,relatime shared:182 - tmpfs tmpfs rw,size=151636k,nr_inodes=37909,mode=700,uid=1000,gid=1000,inode64
244s (mount.c:567): 59 27 0:40 / /run/user/1001 rw,nosuid,nodev,relatime shared:184 - tmpfs tmpfs rw,size=151636k,nr_inodes=37909,mode=700,uid=1001,gid=1001,inode64
244s (mount.c:567): 47 28 252:0 / /home/alice/img-luks1 rw,relatime shared:151 - ext4 /dev/mapper/_tmp_disk_l1_img rw
244s command: 'pmvarrun' '-u' 'alice' '-o' '1' 
244s (pmvarrun.c:261): parsed count value 0
244s (pam_mount.c:441): pmvarrun says login count is 1
244s (pam_mount.c:660): done opening session (ret=0)
244s (pam_mount.c:116): Clean global config (1073741824)
244s (pam_mount.c:706): received order to close things
244s command: 'pmvarrun' '-u' 'alice' '-o' '-1' 
244s (pmvarrun.c:261): parsed count value 1
244s (pam_mount.c:441): pmvarrun says login count is 0
244s (mount.c:904): going to unmount
244s (mount.c:264): Mount info: globalconf, user=alice <volume fstype="crypt" server="(null)" path="/tmp/disk-l1.img" mountpoint="/home/alice/img-luks1" cipher="(null)" fskeypath="/home/alice/alice.key" fskeycipher="none" fskeyhash="md5" header="(null)" options="" fstab=0 ssh=0 ignoresource=0 /> 
244s command: 'ofl' '-k0' '/home/alice/img-luks1' 
244s command: 'umount' '/home/alice/img-luks1' 
244s (pam_mount.c:743): pam_mount execution complete
244s (pam_mount.c:116): Clean global config (0)
244s + sleep 5s
249s + cmp expected luks1
249s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost cat ~/img-luks1/testfile
252s + sleep 5s
257s + cmp expected luks1
257s + cp /tmp/tmp.zOroFo54ss /etc/security/pam_mount.conf.xml
257s + checkluks 2
257s + id=2
257s + mktemp
257s + bckp=/tmp/tmp.Xgj7ue4w2y
257s + cp /etc/security/pam_mount.conf.xml /tmp/tmp.Xgj7ue4w2y
257s + sed -i -e s/debug enable="0"/debug enable="1"/ /etc/security/pam_mount.conf.xml
257s + sed -i -e /<!-- Volume definitions -->/a<volume user='alice' path='/tmp/disk-l2.img' mountpoint='~/img-luks2' fstype='crypt' fskeycipher='none' fskeyhash='md5' fskeypath='/home/alice/alice.key' /> /etc/security/pam_mount.conf.xml
257s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost ls -laF ~/img-luks2/testfile
259s -rw-r--r-- 1 root root 24 Mar 21 20:42 /home/alice/img-luks2/testfile
259s + sleep 5s
264s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost mount
264s + grep img-luks2
267s /dev/mapper/_tmp_disk_l2_img on /home/alice/img-luks2 type ext4 (rw,relatime,helper=crypt)
267s + sleep 5s
272s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-auth
272s + sed -i -e s/pam_mount.so\s*$/pam_mount.so disable_interactive/ /etc/pam.d/common-session
272s + su --login --command cat ~/img-luks2/testfile alice
272s (pam_mount.c:568): pam_mount 2.20: entering session stage
272s (mount.c:264): Mount info: globalconf, user=alice <volume fstype="crypt" server="(null)" path="/tmp/disk-l2.img" mountpoint="/home/alice/img-luks2" cipher="(null)" fskeypath="/home/alice/alice.key" fskeycipher="none" fskeyhash="md5" header="(null)" options="" fstab=0 ssh=0 ignoresource=0 /> 
272s (mount.c:678): Password will be sent to helper as-is.
272s command: 'mount' '-t' 'crypt' '-ofsk_cipher=none' '-ofsk_hash=md5' '-okeyfile=/home/alice/alice.key' '/tmp/disk-l2.img' '/home/alice/img-luks2' 
274s (mount.c:567): 23 28 0:21 / /sys rw,nosuid,nodev,noexec,relatime shared:7 - sysfs sysfs rw
274s (mount.c:567): 24 28 0:22 / /proc rw,nosuid,nodev,noexec,relatime shared:11 - proc proc rw
274s (mount.c:567): 25 28 0:5 / /dev rw,nosuid,relatime shared:2 - devtmpfs udev rw,size=746816k,nr_inodes=186704,mode=755,inode64
274s (mount.c:567): 26 25 0:23 / /dev/pts rw,nosuid,noexec,relatime shared:3 - devpts devpts rw,gid=5,mode=620,ptmxmode=000
274s (mount.c:567): 27 28 0:24 / /run rw,nosuid,nodev,noexec,relatime shared:5 - tmpfs tmpfs rw,size=151640k,mode=755,inode64
274s (mount.c:567): 28 1 253:1 / / rw,relatime shared:1 - ext4 /dev/vda1 rw,discard,errors=remount-ro,commit=30
274s (mount.c:567): 29 23 0:6 / /sys/kernel/security rw,nosuid,nodev,noexec,relatime shared:8 - securityfs securityfs rw
274s (mount.c:567): 30 25 0:25 / /dev/shm rw,nosuid,nodev shared:4 - tmpfs tmpfs rw,inode64
274s (mount.c:567): 31 27 0:26 / /run/lock rw,nosuid,nodev,noexec,relatime shared:6 - tmpfs tmpfs rw,size=5120k,inode64
274s (mount.c:567): 32 23 0:27 / /sys/fs/cgroup rw,nosuid,nodev,noexec,relatime shared:9 - cgroup2 cgroup2 rw,nsdelegate,memory_recursiveprot
274s (mount.c:567): 33 23 0:28 / /sys/fs/bpf rw,nosuid,nodev,noexec,relatime shared:10 - bpf bpf rw,mode=700
274s (mount.c:567): 34 24 0:29 / /proc/sys/fs/binfmt_misc rw,relatime shared:12 - autofs systemd-1 rw,fd=31,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=1746
274s (mount.c:567): 35 25 0:19 / /dev/mqueue rw,nosuid,nodev,noexec,relatime shared:13 - mqueue mqueue rw
274s (mount.c:567): 36 25 0:30 / /dev/hugepages rw,nosuid,nodev,relatime shared:14 - hugetlbfs hugetlbfs rw,pagesize=1M
274s (mount.c:567): 37 23 0:12 / /sys/kernel/tracing rw,nosuid,nodev,noexec,relatime shared:15 - tracefs tracefs rw
274s (mount.c:567): 38 23 0:7 / /sys/kernel/debug rw,nosuid,nodev,noexec,relatime shared:16 - debugfs debugfs rw
274s (mount.c:567): 39 23 0:31 / /sys/fs/fuse/connections rw,nosuid,nodev,noexec,relatime shared:17 - fusectl fusectl rw
274s (mount.c:567): 40 23 0:20 / /sys/kernel/config rw,nosuid,nodev,noexec,relatime shared:18 - configfs configfs rw
274s (mount.c:567): 42 34 0:32 / /proc/sys/fs/binfmt_misc rw,nosuid,nodev,noexec,relatime shared:28 - binfmt_misc binfmt_misc rw
274s (mount.c:567): 48 27 0:43 / /run/user/1000 rw,nosuid,nodev,relatime shared:182 - tmpfs tmpfs rw,size=151636k,nr_inodes=37909,mode=700,uid=1000,gid=1000,inode64
274s (mount.c:567): 59 27 0:40 / /run/user/1001 rw,nosuid,nodev,relatime shared:184 - tmpfs tmpfs rw,size=151636k,nr_inodes=37909,mode=700,uid=1001,gid=1001,inode64
274s (mount.c:567): 47 28 252:0 / /home/alice/img-luks2 rw,relatime shared:151 - ext4 /dev/mapper/_tmp_disk_l2_img rw
274s command: 'pmvarrun' '-u' 'alice' '-o' '1' 
274s (pmvarrun.c:261): parsed count value 0
274s (pam_mount.c:441): pmvarrun says login count is 1
274s (pam_mount.c:660): done opening session (ret=0)
274s (pam_mount.c:116): Clean global config (1073741824)
274s (pam_mount.c:706): received order to close things
274s command: 'pmvarrun' '-u' 'alice' '-o' '-1' 
274s (pmvarrun.c:261): parsed count value 1
274s (pam_mount.c:441): pmvarrun says login count is 0
274s (mount.c:904): going to unmount
274s (mount.c:264): Mount info: globalconf, user=alice <volume fstype="crypt" server="(null)" path="/tmp/disk-l2.img" mountpoint="/home/alice/img-luks2" cipher="(null)" fskeypath="/home/alice/alice.key" fskeycipher="none" fskeyhash="md5" header="(null)" options="" fstab=0 ssh=0 ignoresource=0 /> 
274s command: 'ofl' '-k0' '/home/alice/img-luks2' 
274s command: 'umount' '/home/alice/img-luks2' 
274s (pam_mount.c:743): pam_mount execution complete
274s (pam_mount.c:116): Clean global config (0)
274s + sleep 5s
280s + cmp expected luks2
280s + ssh -o StrictHostKeyChecking=no -i test.key alice@localhost cat ~/img-luks2/testfile
282s + sleep 5s
287s + cmp expected luks2
287s + cp /tmp/tmp.Xgj7ue4w2y /etc/security/pam_mount.conf.xml
287s autopkgtest [20:43:36]: test local-luks: -----------------------]
288s autopkgtest [20:43:37]: test local-luks:  - - - - - - - - - - results - - - - - - - - - -
288s local-luks           PASS
289s autopkgtest [20:43:38]: @@@@@@@@@@@@@@@@@@@@ summary
289s local-luks           PASS
302s Creating nova instance adt-noble-s390x-libpam-mount-20240321-203849-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...