7s autopkgtest [18:34:55]: starting date and time: 2024-08-06 18:34:55+0000 7s autopkgtest [18:35:02]: git checkout: fd3bed09 nova: allow more retries for quota issues 7s autopkgtest [18:35:02]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.6jus6f1z/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:cdebconf --apt-upgrade pam --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=cdebconf/0.272ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-ppc64el-2.secgroup --name adt-oracular-ppc64el-pam-20240806-182950-juju-7f2275-prod-proposed-migration-environment-3-00dcd170-2609-45d1-a25b-de08b1615bce --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/ 271s autopkgtest [18:39:26]: testbed dpkg architecture: ppc64el 271s autopkgtest [18:39:26]: testbed apt version: 2.9.6 271s autopkgtest [18:39:26]: @@@@@@@@@@@@@@@@@@@@ test bed setup 273s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 273s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [8756 B] 273s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8552 B] 273s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [601 kB] 273s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [58.8 kB] 273s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [89.7 kB] 273s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [3192 B] 273s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1364 B] 273s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 273s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [567 kB] 273s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [18.8 kB] 273s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [4752 B] 273s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [388 B] 277s Fetched 1489 kB in 1s (1544 kB/s) 277s Reading package lists... 280s Reading package lists... 280s Building dependency tree... 280s Reading state information... 280s Calculating upgrade... 280s The following packages will be upgraded: 280s apt apt-utils dpkg dpkg-dev gcc-14-base libapt-pkg6.0t64 libatomic1 280s libdebconfclient0 libdpkg-perl libgcc-s1 libgomp1 libstdc++6 281s 12 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 281s Need to get 6661 kB of archives. 281s After this operation, 46.1 kB of additional disk space will be used. 281s Get:1 http://ftpmaster.internal/ubuntu oracular/main ppc64el libatomic1 ppc64el 14.2.0-1ubuntu1 [10.7 kB] 281s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libgomp1 ppc64el 14.2.0-1ubuntu1 [161 kB] 281s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el gcc-14-base ppc64el 14.2.0-1ubuntu1 [50.0 kB] 281s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el libgcc-s1 ppc64el 14.2.0-1ubuntu1 [39.1 kB] 281s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el libstdc++6 ppc64el 14.2.0-1ubuntu1 [886 kB] 281s Get:6 http://ftpmaster.internal/ubuntu oracular/main ppc64el libapt-pkg6.0t64 ppc64el 2.9.7 [1113 kB] 281s Get:7 http://ftpmaster.internal/ubuntu oracular/main ppc64el dpkg ppc64el 1.22.11ubuntu1 [1360 kB] 281s Get:8 http://ftpmaster.internal/ubuntu oracular/main ppc64el apt ppc64el 2.9.7 [1435 kB] 281s Get:9 http://ftpmaster.internal/ubuntu oracular/main ppc64el apt-utils ppc64el 2.9.7 [229 kB] 281s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libdebconfclient0 ppc64el 0.272ubuntu1 [11.4 kB] 281s Get:11 http://ftpmaster.internal/ubuntu oracular/main ppc64el dpkg-dev all 1.22.11ubuntu1 [1087 kB] 281s Get:12 http://ftpmaster.internal/ubuntu oracular/main ppc64el libdpkg-perl all 1.22.11ubuntu1 [279 kB] 282s Fetched 6661 kB in 1s (7323 kB/s) 282s (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 ... 72527 files and directories currently installed.) 282s Preparing to unpack .../libatomic1_14.2.0-1ubuntu1_ppc64el.deb ... 282s Unpacking libatomic1:ppc64el (14.2.0-1ubuntu1) over (14.1.0-5ubuntu1) ... 282s Preparing to unpack .../libgomp1_14.2.0-1ubuntu1_ppc64el.deb ... 282s Unpacking libgomp1:ppc64el (14.2.0-1ubuntu1) over (14.1.0-5ubuntu1) ... 282s Preparing to unpack .../gcc-14-base_14.2.0-1ubuntu1_ppc64el.deb ... 282s Unpacking gcc-14-base:ppc64el (14.2.0-1ubuntu1) over (14.1.0-5ubuntu1) ... 282s Setting up gcc-14-base:ppc64el (14.2.0-1ubuntu1) ... 282s (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 ... 72527 files and directories currently installed.) 282s Preparing to unpack .../libgcc-s1_14.2.0-1ubuntu1_ppc64el.deb ... 282s Unpacking libgcc-s1:ppc64el (14.2.0-1ubuntu1) over (14.1.0-5ubuntu1) ... 282s Setting up libgcc-s1:ppc64el (14.2.0-1ubuntu1) ... 282s (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 ... 72527 files and directories currently installed.) 282s Preparing to unpack .../libstdc++6_14.2.0-1ubuntu1_ppc64el.deb ... 282s Unpacking libstdc++6:ppc64el (14.2.0-1ubuntu1) over (14.1.0-5ubuntu1) ... 282s Setting up libstdc++6:ppc64el (14.2.0-1ubuntu1) ... 282s (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 ... 72527 files and directories currently installed.) 282s Preparing to unpack .../libapt-pkg6.0t64_2.9.7_ppc64el.deb ... 282s Unpacking libapt-pkg6.0t64:ppc64el (2.9.7) over (2.9.6) ... 282s Setting up libapt-pkg6.0t64:ppc64el (2.9.7) ... 282s (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 ... 72527 files and directories currently installed.) 282s Preparing to unpack .../dpkg_1.22.11ubuntu1_ppc64el.deb ... 282s Unpacking dpkg (1.22.11ubuntu1) over (1.22.10ubuntu2) ... 283s Setting up dpkg (1.22.11ubuntu1) ... 283s (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 ... 72527 files and directories currently installed.) 283s Preparing to unpack .../archives/apt_2.9.7_ppc64el.deb ... 283s Unpacking apt (2.9.7) over (2.9.6) ... 283s Setting up apt (2.9.7) ... 284s (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 ... 72527 files and directories currently installed.) 284s Preparing to unpack .../apt-utils_2.9.7_ppc64el.deb ... 284s Unpacking apt-utils (2.9.7) over (2.9.6) ... 284s Preparing to unpack .../libdebconfclient0_0.272ubuntu1_ppc64el.deb ... 284s Unpacking libdebconfclient0:ppc64el (0.272ubuntu1) over (0.271ubuntu3) ... 284s Setting up libdebconfclient0:ppc64el (0.272ubuntu1) ... 284s (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 ... 72527 files and directories currently installed.) 284s Preparing to unpack .../dpkg-dev_1.22.11ubuntu1_all.deb ... 284s Unpacking dpkg-dev (1.22.11ubuntu1) over (1.22.10ubuntu2) ... 284s Preparing to unpack .../libdpkg-perl_1.22.11ubuntu1_all.deb ... 284s Unpacking libdpkg-perl (1.22.11ubuntu1) over (1.22.10ubuntu2) ... 284s Setting up apt-utils (2.9.7) ... 284s Setting up libgomp1:ppc64el (14.2.0-1ubuntu1) ... 284s Setting up libatomic1:ppc64el (14.2.0-1ubuntu1) ... 284s Setting up libdpkg-perl (1.22.11ubuntu1) ... 284s Setting up dpkg-dev (1.22.11ubuntu1) ... 284s Processing triggers for man-db (2.12.1-2) ... 286s Processing triggers for libc-bin (2.39-0ubuntu9) ... 287s Reading package lists... 287s Building dependency tree... 287s Reading state information... 288s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 288s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 288s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 288s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 288s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 289s Reading package lists... 289s Reading package lists... 290s Building dependency tree... 290s Reading state information... 290s Calculating upgrade... 290s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 290s Reading package lists... 290s Building dependency tree... 290s Reading state information... 290s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 291s autopkgtest [18:39:46]: rebooting testbed after setup commands that affected boot 325s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 336s autopkgtest [18:40:31]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 342s autopkgtest [18:40:37]: @@@@@@@@@@@@@@@@@@@@ apt-source pam 352s Get:1 http://ftpmaster.internal/ubuntu oracular/main pam 1.5.3-7ubuntu1 (dsc) [2719 B] 352s Get:2 http://ftpmaster.internal/ubuntu oracular/main pam 1.5.3-7ubuntu1 (tar) [1020 kB] 352s Get:3 http://ftpmaster.internal/ubuntu oracular/main pam 1.5.3-7ubuntu1 (diff) [186 kB] 352s gpgv: Signature made Tue Apr 30 22:18:17 2024 UTC 352s gpgv: using RSA key D09F8A854F1055BCFC482C4B23566B906047AFC8 352s gpgv: Can't check signature: No public key 352s dpkg-source: warning: cannot verify inline signature for ./pam_1.5.3-7ubuntu1.dsc: no acceptable signature found 352s autopkgtest [18:40:47]: testing package pam version 1.5.3-7ubuntu1 353s autopkgtest [18:40:48]: build not needed 357s autopkgtest [18:40:52]: test pam-auth-update: preparing testbed 379s Reading package lists... 379s Building dependency tree... 379s Reading state information... 379s Starting pkgProblemResolver with broken count: 0 379s Starting 2 pkgProblemResolver with broken count: 0 379s Done 379s The following NEW packages will be installed: 379s autopkgtest-satdep 379s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 379s Need to get 0 B/728 B of archives. 379s After this operation, 0 B of additional disk space will be used. 379s Get:1 /tmp/autopkgtest.FkXyvs/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [728 B] 379s Selecting previously unselected package autopkgtest-satdep. 380s (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 ... 72527 files and directories currently installed.) 380s Preparing to unpack .../1-autopkgtest-satdep.deb ... 380s Unpacking autopkgtest-satdep (0) ... 380s Setting up autopkgtest-satdep (0) ... 383s (Reading database ... 72527 files and directories currently installed.) 383s Removing autopkgtest-satdep (0) ... 384s autopkgtest [18:41:19]: test pam-auth-update: [----------------------- 384s + 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 384s + pam-auth-update --enable mkhomedir 385s + grep mkhomedir /etc/pam.d/common-session 385s session optional pam_mkhomedir.so 385s + useradd -s /bin/bash pam_test 385s + su -c date pam_test 385s Tue Aug 6 18:41:20 UTC 2024 385s + test -d /home/pam_test 385s + grep -i rounds /etc/pam.d/common-password 385s + sed -i -e 's/obscure yescrypt/obscure yescrypt rounds=3/' /etc/pam.d/common-password 385s + grep rounds /etc/pam.d/common-password 385s password [success=1 default=ignore] pam_unix.so obscure yescrypt rounds=3 385s + echo get libpam-runtime/profiles 385s + debconf-communicate 385s + grep mkhomedir 385s 0 unix, systemd, mkhomedir, capability 385s + pam-auth-update --disable mkhomedir 385s + echo get libpam-runtime/profiles 385s + debconf-communicate 385s + grep mkhomedir 385s + grep mkhomedir /var/lib/pam/seen 385s mkhomedir 385s + grep mkhomedir /etc/pam.d/common-session 385s + grep rounds /etc/pam.d/common-password 385s password [success=1 default=ignore] pam_unix.so obscure yescrypt rounds=3 385s autopkgtest [18:41:20]: test pam-auth-update: -----------------------] 388s autopkgtest [18:41:23]: test pam-auth-update: - - - - - - - - - - results - - - - - - - - - - 388s pam-auth-update PASS 395s autopkgtest [18:41:30]: test pam-test: preparing testbed 400s Reading package lists... 400s Building dependency tree... 400s Reading state information... 401s Starting pkgProblemResolver with broken count: 0 401s Starting 2 pkgProblemResolver with broken count: 0 401s Done 401s The following additional packages will be installed: 401s python3-pam 401s Suggested packages: 401s python3-pam-dbg 401s The following NEW packages will be installed: 401s autopkgtest-satdep python3-pam 401s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 401s Need to get 13.1 kB/13.9 kB of archives. 401s After this operation, 92.2 kB of additional disk space will be used. 401s Get:1 /tmp/autopkgtest.FkXyvs/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [736 B] 401s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el python3-pam ppc64el 0.4.2-18build2 [13.1 kB] 401s Fetched 13.1 kB in 0s (80.7 kB/s) 401s Selecting previously unselected package python3-pam. 401s (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 ... 72527 files and directories currently installed.) 401s Preparing to unpack .../python3-pam_0.4.2-18build2_ppc64el.deb ... 401s Unpacking python3-pam (0.4.2-18build2) ... 401s Selecting previously unselected package autopkgtest-satdep. 401s Preparing to unpack .../2-autopkgtest-satdep.deb ... 401s Unpacking autopkgtest-satdep (0) ... 401s Setting up python3-pam (0.4.2-18build2) ... 401s Setting up autopkgtest-satdep (0) ... 407s (Reading database ... 72541 files and directories currently installed.) 407s Removing autopkgtest-satdep (0) ... 409s autopkgtest [18:41:44]: test pam-test: [----------------------- 410s useradd: user 'pam_test' already exists 410s autopkgtest [18:41:45]: test pam-test: -----------------------] 411s pam-test PASS 411s autopkgtest [18:41:46]: test pam-test: - - - - - - - - - - results - - - - - - - - - - 411s autopkgtest [18:41:46]: @@@@@@@@@@@@@@@@@@@@ summary 411s pam-auth-update PASS 411s pam-test PASS 475s nova [W] Using flock in prodstack6-ppc64el 475s flock: timeout while waiting to get lock 475s Creating nova instance adt-oracular-ppc64el-pam-20240806-182950-juju-7f2275-prod-proposed-migration-environment-3-00dcd170-2609-45d1-a25b-de08b1615bce from image adt/ubuntu-oracular-ppc64el-server-20240806.img (UUID ea592ace-3640-41fa-8fe5-b92f97748d03)...