0s autopkgtest [21:40:18]: starting date and time: 2024-03-21 21:40:18+0000 0s autopkgtest [21:40:18]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:40:18]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.wkroea64/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:libselinux,src:ruby-defaults --apt-upgrade pam --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libselinux/3.5-2ubuntu1 ruby-defaults/1:3.2~ubuntu1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-s390x-24.secgroup --name adt-noble-s390x-pam-20240321-214018-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --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/ 97s autopkgtest [21:41:55]: testbed dpkg architecture: s390x 97s autopkgtest [21:41:55]: testbed apt version: 2.7.12 97s autopkgtest [21:41:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 98s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 98s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3801 kB] 99s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [496 kB] 99s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 99s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 99s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [663 kB] 99s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 99s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 99s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 99s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3995 kB] 99s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 99s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 99s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 103s Fetched 9188 kB in 4s (2598 kB/s) 104s Reading package lists... 107s Reading package lists... 108s Building dependency tree... 108s Reading state information... 108s Calculating upgrade... 108s The following packages will be upgraded: 108s cloud-init debianutils libselinux1 python3-markupsafe 108s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 108s Need to get 785 kB of archives. 108s After this operation, 11.3 kB disk space will be freed. 108s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 109s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libselinux1 s390x 3.5-2ubuntu1 [84.7 kB] 109s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x python3-markupsafe s390x 2.1.5-1build1 [12.8 kB] 109s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x cloud-init all 24.1.2-0ubuntu1 [597 kB] 109s Preconfiguring packages ... 110s Fetched 785 kB in 1s (923 kB/s) 110s (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.) 110s Preparing to unpack .../debianutils_5.17_s390x.deb ... 110s Unpacking debianutils (5.17) over (5.16) ... 110s Setting up debianutils (5.17) ... 110s (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.) 110s Preparing to unpack .../libselinux1_3.5-2ubuntu1_s390x.deb ... 110s Unpacking libselinux1:s390x (3.5-2ubuntu1) over (3.5-2build1) ... 110s Setting up libselinux1:s390x (3.5-2ubuntu1) ... 110s (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.) 110s Preparing to unpack .../python3-markupsafe_2.1.5-1build1_s390x.deb ... 110s Unpacking python3-markupsafe (2.1.5-1build1) over (2.1.5-1) ... 110s Preparing to unpack .../cloud-init_24.1.2-0ubuntu1_all.deb ... 111s Unpacking cloud-init (24.1.2-0ubuntu1) over (24.1.1-0ubuntu1) ... 111s Setting up cloud-init (24.1.2-0ubuntu1) ... 113s Setting up python3-markupsafe (2.1.5-1build1) ... 114s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 114s Processing triggers for man-db (2.12.0-3) ... 116s Processing triggers for libc-bin (2.39-0ubuntu2) ... 116s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Unknown architecture, assuming PC-style ttyS0 117s sh: Attempting to set up Debian/Ubuntu apt sources automatically 117s sh: Distribution appears to be Ubuntu 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 120s eatmydata is already the newest version (131-1). 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s dbus is already the newest version (1.14.10-4ubuntu1). 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 121s rng-tools-debian is already the newest version (2.4). 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s The following packages will be REMOVED: 121s cloud-init* python3-configobj* python3-debconf* 121s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 121s After this operation, 3256 kB disk space will be freed. 121s (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 ... 52170 files and directories currently installed.) 121s Removing cloud-init (24.1.2-0ubuntu1) ... 122s Removing python3-configobj (5.0.8-3) ... 122s Removing python3-debconf (1.5.86) ... 122s Processing triggers for man-db (2.12.0-3) ... 123s (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 ... 51781 files and directories currently installed.) 123s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 124s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 124s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 124s invoke-rc.d: policy-rc.d denied execution of try-restart. 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 125s linux-generic is already the newest version (6.8.0-11.11+1). 125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 125s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 125s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 125s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 129s Reading package lists... 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 130s Calculating upgrade... 130s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 130s Reading package lists... 130s Building dependency tree... 130s Reading state information... 131s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 131s autopkgtest [21:42:29]: rebooting testbed after setup commands that affected boot 151s autopkgtest [21:42:49]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 154s autopkgtest [21:42:52]: @@@@@@@@@@@@@@@@@@@@ apt-source pam 161s Get:1 http://ftpmaster.internal/ubuntu noble/main pam 1.5.2-9.1ubuntu3 (dsc) [2733 B] 161s Get:2 http://ftpmaster.internal/ubuntu noble/main pam 1.5.2-9.1ubuntu3 (tar) [989 kB] 161s Get:3 http://ftpmaster.internal/ubuntu noble/main pam 1.5.2-9.1ubuntu3 (diff) [177 kB] 161s gpgv: Signature made Thu Feb 22 12:25:34 2024 UTC 161s gpgv: using RSA key 4FB588A84C2DDE79A74C77876FA458DD1DB03F71 161s gpgv: issuer "juliank@ubuntu.com" 161s gpgv: Can't check signature: No public key 161s dpkg-source: warning: cannot verify inline signature for ./pam_1.5.2-9.1ubuntu3.dsc: no acceptable signature found 162s autopkgtest [21:43:00]: testing package pam version 1.5.2-9.1ubuntu3 163s autopkgtest [21:43:01]: build not needed 165s autopkgtest [21:43:03]: test pam-auth-update: preparing testbed 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 174s Starting pkgProblemResolver with broken count: 0 174s Starting 2 pkgProblemResolver with broken count: 0 174s Done 175s The following NEW packages will be installed: 175s autopkgtest-satdep 175s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 175s Need to get 0 B/716 B of archives. 175s After this operation, 0 B of additional disk space will be used. 175s Get:1 /tmp/autopkgtest.6njcEC/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [716 B] 175s Selecting previously unselected package autopkgtest-satdep. 175s (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 ... 51726 files and directories currently installed.) 175s Preparing to unpack .../1-autopkgtest-satdep.deb ... 175s Unpacking autopkgtest-satdep (0) ... 175s Setting up autopkgtest-satdep (0) ... 179s (Reading database ... 51726 files and directories currently installed.) 179s Removing autopkgtest-satdep (0) ... 180s autopkgtest [21:43:18]: test pam-auth-update: [----------------------- 180s + grep mkhomedir /etc/pam.d/chfn /etc/pam.d/chpasswd /etc/pam.d/chsh /etc/pam.d/common-account /etc/pam.d/common-auth /etc/pam.d/common-password /etc/pam.d/common-session /etc/pam.d/common-session-noninteractive /etc/pam.d/cron /etc/pam.d/login /etc/pam.d/newusers /etc/pam.d/other /etc/pam.d/passwd /etc/pam.d/runuser /etc/pam.d/runuser-l /etc/pam.d/sshd /etc/pam.d/su /etc/pam.d/su-l /etc/pam.d/sudo /etc/pam.d/sudo-i 180s + pam-auth-update --enable mkhomedir 180s + grep mkhomedir /etc/pam.d/common-session 180s + useradd -s /bin/bash pam_test 180s session optional pam_mkhomedir.so 181s + su -c date pam_test 181s Thu Mar 21 21:43:19 UTC 2024 181s + test -d /home/pam_test 181s + grep -i rounds /etc/pam.d/common-password 181s + sed -i -e 's/obscure yescrypt/obscure yescrypt rounds=3/' /etc/pam.d/common-password 181s + grep rounds /etc/pam.d/common-password 181s password [success=1 default=ignore] pam_unix.so obscure yescrypt rounds=3 181s + echo get libpam-runtime/profiles 181s + debconf-communicate 181s + grep mkhomedir 181s 0 unix, systemd, mkhomedir, capability 181s + pam-auth-update --disable mkhomedir 181s + echo get libpam-runtime/profiles 181s + debconf-communicate 181s + grep mkhomedir 181s + grep mkhomedir /var/lib/pam/seen 181s mkhomedir 181s + grep mkhomedir /etc/pam.d/common-session 181s + grep rounds /etc/pam.d/common-password 181s password [success=1 default=ignore] pam_unix.so obscure yescrypt rounds=3 182s autopkgtest [21:43:20]: test pam-auth-update: -----------------------] 182s autopkgtest [21:43:20]: test pam-auth-update: - - - - - - - - - - results - - - - - - - - - - 182s pam-auth-update PASS 183s autopkgtest [21:43:21]: test pam-test: preparing testbed 188s Reading package lists... 188s Building dependency tree... 188s Reading state information... 188s Starting pkgProblemResolver with broken count: 0 188s Starting 2 pkgProblemResolver with broken count: 0 188s Done 189s The following additional packages will be installed: 189s python3-pam 189s Suggested packages: 189s python3-pam-dbg 189s The following NEW packages will be installed: 189s autopkgtest-satdep python3-pam 189s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 189s Need to get 13.4 kB/14.1 kB of archives. 189s After this operation, 62.5 kB of additional disk space will be used. 189s Get:1 /tmp/autopkgtest.6njcEC/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [732 B] 189s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x python3-pam s390x 0.4.2-18build1 [13.4 kB] 190s Fetched 13.4 kB in 0s (80.0 kB/s) 190s Selecting previously unselected package python3-pam. 190s (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 ... 51726 files and directories currently installed.) 190s Preparing to unpack .../python3-pam_0.4.2-18build1_s390x.deb ... 190s Unpacking python3-pam (0.4.2-18build1) ... 190s Selecting previously unselected package autopkgtest-satdep. 190s Preparing to unpack .../2-autopkgtest-satdep.deb ... 190s Unpacking autopkgtest-satdep (0) ... 190s Setting up python3-pam (0.4.2-18build1) ... 190s Setting up autopkgtest-satdep (0) ... 200s (Reading database ... 51741 files and directories currently installed.) 200s Removing autopkgtest-satdep (0) ... 201s autopkgtest [21:43:39]: test pam-test: [----------------------- 201s useradd: user 'pam_test' already exists 202s autopkgtest [21:43:40]: test pam-test: -----------------------] 202s autopkgtest [21:43:40]: test pam-test: - - - - - - - - - - results - - - - - - - - - - 202s pam-test PASS 203s autopkgtest [21:43:41]: @@@@@@@@@@@@@@@@@@@@ summary 203s pam-auth-update PASS 203s pam-test PASS 214s Creating nova instance adt-noble-s390x-pam-20240321-214018-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...