0s autopkgtest [23:54:21]: starting date and time: 2024-11-12 23:54:21+0000 0s autopkgtest [23:54:21]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [23:54:21]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ilsl2wjf/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pam --apt-upgrade at --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pam/1.5.3-7ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-24.secgroup --name adt-plucky-s390x-at-20241112-235420-juju-7f2275-prod-proposed-migration-environment-15-5f466f1c-98f2-4542-9d85-26fb766b98ff --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -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/ 55s autopkgtest [23:55:16]: testbed dpkg architecture: s390x 55s autopkgtest [23:55:16]: testbed apt version: 2.9.8 55s autopkgtest [23:55:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 56s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 56s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 56s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [81.3 kB] 56s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [846 kB] 56s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 56s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [89.6 kB] 56s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [560 kB] 56s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [16.6 kB] 56s Fetched 1690 kB in 1s (2169 kB/s) 57s Reading package lists... 58s Reading package lists... 59s Building dependency tree... 59s Reading state information... 59s Calculating upgrade... 59s The following packages will be upgraded: 59s apport apport-core-dump-handler gcc-14-base libarchive13t64 libatomic1 59s libclang-cpp18 libedit2 libgcc-s1 libjson-c5 libllvm18 libpam-modules 59s libpam-modules-bin libpam-runtime libpam0g libstdc++6 python3-apport 59s python3-certifi python3-cffi-backend python3-problem-report 59s 19 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 59s Need to get 51.0 MB of archives. 59s After this operation, 306 kB disk space will be freed. 59s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam0g s390x 1.5.3-7ubuntu3 [69.9 kB] 59s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules-bin s390x 1.5.3-7ubuntu3 [56.2 kB] 59s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules s390x 1.5.3-7ubuntu3 [294 kB] 59s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-problem-report all 2.30.0-0ubuntu5 [25.0 kB] 59s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-apport all 2.30.0-0ubuntu5 [93.2 kB] 59s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x apport-core-dump-handler all 2.30.0-0ubuntu5 [17.9 kB] 59s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x apport all 2.30.0-0ubuntu5 [83.0 kB] 59s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libatomic1 s390x 14.2.0-8ubuntu1 [9430 B] 59s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-8ubuntu1 [51.6 kB] 59s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++6 s390x 14.2.0-8ubuntu1 [896 kB] 59s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-s1 s390x 14.2.0-8ubuntu1 [35.8 kB] 59s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 59s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libjson-c5 s390x 0.18+ds-1 [37.1 kB] 59s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x python3-cffi-backend s390x 1.17.1-2 [81.5 kB] 59s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libedit2 s390x 3.1-20240808-1 [107 kB] 59s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libarchive13t64 s390x 3.7.4-1.1 [416 kB] 59s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libclang-cpp18 s390x 1:18.1.8-12 [15.8 MB] 60s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libllvm18 s390x 1:18.1.8-12 [32.9 MB] 61s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x python3-certifi all 2024.8.30+dfsg-1 [9742 B] 62s Preconfiguring packages ... 62s Fetched 51.0 MB in 3s (18.9 MB/s) 62s (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 ... 55522 files and directories currently installed.) 62s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_s390x.deb ... 62s Unpacking libpam0g:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 62s Setting up libpam0g:s390x (1.5.3-7ubuntu3) ... 62s (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 ... 55522 files and directories currently installed.) 62s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_s390x.deb ... 62s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 62s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 62s pam_namespace.service is a disabled or a static unit not running, not starting it. 62s (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 ... 55522 files and directories currently installed.) 62s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_s390x.deb ... 62s Unpacking libpam-modules:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 62s Setting up libpam-modules:s390x (1.5.3-7ubuntu3) ... 62s (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 ... 55522 files and directories currently installed.) 62s Preparing to unpack .../0-python3-problem-report_2.30.0-0ubuntu5_all.deb ... 62s Unpacking python3-problem-report (2.30.0-0ubuntu5) over (2.30.0-0ubuntu4) ... 62s Preparing to unpack .../1-python3-apport_2.30.0-0ubuntu5_all.deb ... 62s Unpacking python3-apport (2.30.0-0ubuntu5) over (2.30.0-0ubuntu4) ... 62s Preparing to unpack .../2-apport-core-dump-handler_2.30.0-0ubuntu5_all.deb ... 62s Unpacking apport-core-dump-handler (2.30.0-0ubuntu5) over (2.30.0-0ubuntu4) ... 62s Preparing to unpack .../3-apport_2.30.0-0ubuntu5_all.deb ... 62s Unpacking apport (2.30.0-0ubuntu5) over (2.30.0-0ubuntu4) ... 62s Preparing to unpack .../4-libatomic1_14.2.0-8ubuntu1_s390x.deb ... 62s Unpacking libatomic1:s390x (14.2.0-8ubuntu1) over (14.2.0-7ubuntu1) ... 62s Preparing to unpack .../5-gcc-14-base_14.2.0-8ubuntu1_s390x.deb ... 62s Unpacking gcc-14-base:s390x (14.2.0-8ubuntu1) over (14.2.0-7ubuntu1) ... 62s Setting up gcc-14-base:s390x (14.2.0-8ubuntu1) ... 63s (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 ... 55511 files and directories currently installed.) 63s Preparing to unpack .../libstdc++6_14.2.0-8ubuntu1_s390x.deb ... 63s Unpacking libstdc++6:s390x (14.2.0-8ubuntu1) over (14.2.0-7ubuntu1) ... 63s Setting up libstdc++6:s390x (14.2.0-8ubuntu1) ... 63s (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 ... 55511 files and directories currently installed.) 63s Preparing to unpack .../libgcc-s1_14.2.0-8ubuntu1_s390x.deb ... 63s Unpacking libgcc-s1:s390x (14.2.0-8ubuntu1) over (14.2.0-7ubuntu1) ... 63s Setting up libgcc-s1:s390x (14.2.0-8ubuntu1) ... 63s (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 ... 55511 files and directories currently installed.) 63s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 63s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 63s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 63s (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 ... 55511 files and directories currently installed.) 63s Preparing to unpack .../0-libjson-c5_0.18+ds-1_s390x.deb ... 63s Unpacking libjson-c5:s390x (0.18+ds-1) over (0.17-1build1) ... 63s Preparing to unpack .../1-python3-cffi-backend_1.17.1-2_s390x.deb ... 63s Unpacking python3-cffi-backend:s390x (1.17.1-2) over (1.17.1-1) ... 63s Preparing to unpack .../2-libedit2_3.1-20240808-1_s390x.deb ... 63s Unpacking libedit2:s390x (3.1-20240808-1) over (3.1-20240517-1) ... 63s Preparing to unpack .../3-libarchive13t64_3.7.4-1.1_s390x.deb ... 63s Unpacking libarchive13t64:s390x (3.7.4-1.1) over (3.7.4-1ubuntu1) ... 63s Preparing to unpack .../4-libclang-cpp18_1%3a18.1.8-12_s390x.deb ... 63s Unpacking libclang-cpp18 (1:18.1.8-12) over (1:18.1.8-11) ... 63s Preparing to unpack .../5-libllvm18_1%3a18.1.8-12_s390x.deb ... 63s Unpacking libllvm18:s390x (1:18.1.8-12) over (1:18.1.8-11) ... 64s Preparing to unpack .../6-python3-certifi_2024.8.30+dfsg-1_all.deb ... 64s Unpacking python3-certifi (2024.8.30+dfsg-1) over (2024.6.2-1) ... 64s Setting up python3-problem-report (2.30.0-0ubuntu5) ... 64s Setting up libedit2:s390x (3.1-20240808-1) ... 64s Setting up python3-apport (2.30.0-0ubuntu5) ... 64s Setting up python3-certifi (2024.8.30+dfsg-1) ... 64s Setting up libatomic1:s390x (14.2.0-8ubuntu1) ... 64s Setting up libarchive13t64:s390x (3.7.4-1.1) ... 64s Setting up libjson-c5:s390x (0.18+ds-1) ... 64s Setting up python3-cffi-backend:s390x (1.17.1-2) ... 64s Setting up libllvm18:s390x (1:18.1.8-12) ... 64s Setting up libclang-cpp18 (1:18.1.8-12) ... 64s Setting up apport-core-dump-handler (2.30.0-0ubuntu5) ... 65s Setting up apport (2.30.0-0ubuntu5) ... 65s Installing new version of config file /etc/apport/crashdb.conf ... 65s apport-autoreport.service is a disabled or a static unit not running, not starting it. 65s Processing triggers for libc-bin (2.40-1ubuntu3) ... 65s Processing triggers for man-db (2.12.1-3) ... 66s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 67s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 67s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 67s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 68s Reading package lists... 68s Reading package lists... 68s Building dependency tree... 68s Reading state information... 68s Calculating upgrade... 68s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 68s Reading package lists... 68s Building dependency tree... 68s Reading state information... 69s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 69s autopkgtest [23:55:30]: rebooting testbed after setup commands that affected boot 72s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 87s autopkgtest [23:55:48]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 89s autopkgtest [23:55:50]: @@@@@@@@@@@@@@@@@@@@ apt-source at 91s Get:1 http://ftpmaster.internal/ubuntu plucky/universe at 3.2.5-2.1ubuntu3 (dsc) [2078 B] 91s Get:2 http://ftpmaster.internal/ubuntu plucky/universe at 3.2.5-2.1ubuntu3 (tar) [133 kB] 91s Get:3 http://ftpmaster.internal/ubuntu plucky/universe at 3.2.5-2.1ubuntu3 (diff) [21.9 kB] 91s gpgv: Signature made Mon Apr 1 05:24:55 2024 UTC 91s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 91s gpgv: Can't check signature: No public key 91s dpkg-source: warning: cannot verify inline signature for ./at_3.2.5-2.1ubuntu3.dsc: no acceptable signature found 91s autopkgtest [23:55:52]: testing package at version 3.2.5-2.1ubuntu3 91s autopkgtest [23:55:52]: build not needed 91s autopkgtest [23:55:52]: test basic-usage: preparing testbed 92s Reading package lists... 93s Building dependency tree... 93s Reading state information... 93s Starting pkgProblemResolver with broken count: 0 93s Starting 2 pkgProblemResolver with broken count: 0 93s Done 93s The following additional packages will be installed: 93s at 93s Suggested packages: 93s default-mta | mail-transport-agent 93s The following NEW packages will be installed: 93s at autopkgtest-satdep 93s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 93s Need to get 41.7 kB/42.4 kB of archives. 93s After this operation, 150 kB of additional disk space will be used. 93s Get:1 /tmp/autopkgtest.fBmq9V/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [700 B] 93s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x at s390x 3.2.5-2.1ubuntu3 [41.7 kB] 93s Fetched 41.7 kB in 0s (149 kB/s) 93s Selecting previously unselected package at. 93s (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 ... 55510 files and directories currently installed.) 93s Preparing to unpack .../at_3.2.5-2.1ubuntu3_s390x.deb ... 93s Unpacking at (3.2.5-2.1ubuntu3) ... 94s Selecting previously unselected package autopkgtest-satdep. 94s Preparing to unpack .../1-autopkgtest-satdep.deb ... 94s Unpacking autopkgtest-satdep (0) ... 94s Setting up at (3.2.5-2.1ubuntu3) ... 94s Created symlink '/etc/systemd/system/multi-user.target.wants/atd.service' → '/usr/lib/systemd/system/atd.service'. 94s Setting up autopkgtest-satdep (0) ... 94s Processing triggers for man-db (2.12.1-3) ... 96s (Reading database ... 55538 files and directories currently installed.) 96s Removing autopkgtest-satdep (0) ... 97s autopkgtest [23:55:58]: test basic-usage: [----------------------- 97s + TMPFILE=at.16589 97s ++ mktemp -d 97s + WORKDIR=/tmp/tmp.aJrCQ8eHiB 97s + trap 'rm -rf /tmp/tmp.aJrCQ8eHiB' 0 INT QUIT ABRT PIPE TERM 97s ++ atq 97s ++ wc -l 97s + JOBS_BEFORE=0 97s + at now + 1 minute 97s ++ date 97s warning: commands will be executed using /bin/sh 97s + echo 'echo Tue Nov 12 23:57:24 UTC 2024 > /tmp/tmp.aJrCQ8eHiB/at.16589' 97s job 1 at Tue Nov 12 23:58:00 2024 97s + sleep 2 99s OK, at.16589 doesn't exist yet; expected.. 99s + test -f at.16589 99s + echo 'OK, at.16589 doesn'\''t exist yet; expected..' 99s ++ atq 99s ++ wc -l 99s + JOBS_AFTER=1 99s OK, 1 new queued job exists.. 99s + [[ 1 -eq 1 ]] 99s + echo 'OK, 1 new queued job exists..' 99s + sleep 60 159s Either at.16589 doesn't exist or the content differs. 159s + grep -Fq UTC /tmp/tmp.aJrCQ8eHiB/at.16589 159s grep: /tmp/tmp.aJrCQ8eHiB/at.16589: No such file or directory 159s + echo 'Either at.16589 doesn'\''t exist or the content differs.' 159s + exit 1 159s + rm -rf /tmp/tmp.aJrCQ8eHiB 159s autopkgtest [23:57:00]: test basic-usage: -----------------------] 160s basic-usage FAIL non-zero exit status 1 160s autopkgtest [23:57:01]: test basic-usage: - - - - - - - - - - results - - - - - - - - - - 160s autopkgtest [23:57:01]: @@@@@@@@@@@@@@@@@@@@ summary 160s basic-usage FAIL non-zero exit status 1 164s nova [W] Using flock in prodstack6-s390x 164s Creating nova instance adt-plucky-s390x-at-20241112-235420-juju-7f2275-prod-proposed-migration-environment-15-5f466f1c-98f2-4542-9d85-26fb766b98ff from image adt/ubuntu-plucky-s390x-server-20241112.img (UUID 6071d326-3c5b-4867-8a8c-d8cb0a44deea)...