0s autopkgtest [08:40:22]: starting date and time: 2024-11-07 08:40:22+0000 0s autopkgtest [08:40:22]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [08:40:22]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.s_0acf75/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-26.secgroup --name adt-plucky-ppc64el-at-20241107-084022-juju-7f2275-prod-proposed-migration-environment-15-80b230fa-b2aa-488e-902f-00a056302e15 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --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/ 92s autopkgtest [08:41:54]: testbed dpkg architecture: ppc64el 92s autopkgtest [08:41:54]: testbed apt version: 2.9.8 92s autopkgtest [08:41:54]: @@@@@@@@@@@@@@@@@@@@ test bed setup 93s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 93s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 94s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 94s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 94s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 94s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [220 kB] 94s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [1190 kB] 94s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [31.8 kB] 94s Fetched 3262 kB in 1s (2668 kB/s) 94s Reading package lists... 98s Reading package lists... 98s Building dependency tree... 98s Reading state information... 98s Calculating upgrade... 98s The following packages will be upgraded: 98s libpam-modules libpam-modules-bin libpam-runtime libpam0g 98s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s Need to get 500 kB of archives. 98s After this operation, 0 B of additional disk space will be used. 98s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpam0g ppc64el 1.5.3-7ubuntu3 [76.0 kB] 98s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpam-modules-bin ppc64el 1.5.3-7ubuntu3 [57.7 kB] 98s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpam-modules ppc64el 1.5.3-7ubuntu3 [325 kB] 98s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 98s Preconfiguring packages ... 98s Fetched 500 kB in 0s (1016 kB/s) 98s (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 ... 73549 files and directories currently installed.) 98s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_ppc64el.deb ... 98s Unpacking libpam0g:ppc64el (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 98s Setting up libpam0g:ppc64el (1.5.3-7ubuntu3) ... 99s (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 ... 73549 files and directories currently installed.) 99s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_ppc64el.deb ... 99s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 99s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 99s pam_namespace.service is a disabled or a static unit not running, not starting it. 99s (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 ... 73549 files and directories currently installed.) 99s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_ppc64el.deb ... 99s Unpacking libpam-modules:ppc64el (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 99s Setting up libpam-modules:ppc64el (1.5.3-7ubuntu3) ... 99s (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 ... 73549 files and directories currently installed.) 99s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 99s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 99s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 99s Processing triggers for man-db (2.12.1-3) ... 100s Processing triggers for libc-bin (2.40-1ubuntu3) ... 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 104s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 104s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 104s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 104s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 105s Reading package lists... 105s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s Calculating upgrade... 106s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 107s autopkgtest [08:42:09]: rebooting testbed after setup commands that affected boot 143s autopkgtest [08:42:45]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 146s autopkgtest [08:42:48]: @@@@@@@@@@@@@@@@@@@@ apt-source at 149s Get:1 http://ftpmaster.internal/ubuntu plucky/universe at 3.2.5-2.1ubuntu3 (dsc) [2078 B] 149s Get:2 http://ftpmaster.internal/ubuntu plucky/universe at 3.2.5-2.1ubuntu3 (tar) [133 kB] 149s Get:3 http://ftpmaster.internal/ubuntu plucky/universe at 3.2.5-2.1ubuntu3 (diff) [21.9 kB] 150s gpgv: Signature made Mon Apr 1 05:24:55 2024 UTC 150s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 150s gpgv: Can't check signature: No public key 150s dpkg-source: warning: cannot verify inline signature for ./at_3.2.5-2.1ubuntu3.dsc: no acceptable signature found 150s autopkgtest [08:42:52]: testing package at version 3.2.5-2.1ubuntu3 150s autopkgtest [08:42:52]: build not needed 153s autopkgtest [08:42:55]: test basic-usage: preparing testbed 163s Reading package lists... 163s Building dependency tree... 163s Reading state information... 164s Starting pkgProblemResolver with broken count: 0 164s Starting 2 pkgProblemResolver with broken count: 0 164s Done 164s The following additional packages will be installed: 164s at 164s Suggested packages: 164s default-mta | mail-transport-agent 164s The following NEW packages will be installed: 164s at autopkgtest-satdep 164s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 164s Need to get 42.9 kB/43.6 kB of archives. 164s After this operation, 208 kB of additional disk space will be used. 164s Get:1 /tmp/autopkgtest.Kk0lTj/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [700 B] 164s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el at ppc64el 3.2.5-2.1ubuntu3 [42.9 kB] 164s Fetched 42.9 kB in 0s (158 kB/s) 164s Selecting previously unselected package at. 165s (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 ... 73549 files and directories currently installed.) 165s Preparing to unpack .../at_3.2.5-2.1ubuntu3_ppc64el.deb ... 165s Unpacking at (3.2.5-2.1ubuntu3) ... 165s Selecting previously unselected package autopkgtest-satdep. 165s Preparing to unpack .../1-autopkgtest-satdep.deb ... 165s Unpacking autopkgtest-satdep (0) ... 165s Setting up at (3.2.5-2.1ubuntu3) ... 165s Created symlink '/etc/systemd/system/multi-user.target.wants/atd.service' → '/usr/lib/systemd/system/atd.service'. 165s Setting up autopkgtest-satdep (0) ... 165s Processing triggers for man-db (2.12.1-3) ... 168s (Reading database ... 73577 files and directories currently installed.) 168s Removing autopkgtest-satdep (0) ... 169s autopkgtest [08:43:11]: test basic-usage: [----------------------- 169s + TMPFILE=at.13152 169s ++ mktemp -d 169s + WORKDIR=/tmp/tmp.Usp3D6s4Sr 169s + trap 'rm -rf /tmp/tmp.Usp3D6s4Sr' 0 INT QUIT ABRT PIPE TERM 169s ++ atq 169s ++ wc -l 169s + JOBS_BEFORE=0 169s + at now + 1 minute 169s ++ date 169s warning: commands will be executed using /bin/sh 169s + echo 'echo Thu Nov 7 08:43:11 UTC 2024 > /tmp/tmp.Usp3D6s4Sr/at.13152' 169s job 1 at Thu Nov 7 08:44:00 2024 169s + sleep 2 171s OK, at.13152 doesn't exist yet; expected.. 171s + test -f at.13152 171s + echo 'OK, at.13152 doesn'\''t exist yet; expected..' 171s ++ atq 171s ++ wc -l 171s + JOBS_AFTER=1 171s + [[ 1 -eq 1 ]] 171s + echo 'OK, 1 new queued job exists..' 171s OK, 1 new queued job exists.. 171s + sleep 60 231s OK, at.13152 exists and everything looks in order.. 231s OK; PASS. 231s + grep -Fq UTC /tmp/tmp.Usp3D6s4Sr/at.13152 231s + echo 'OK, at.13152 exists and everything looks in order..' 231s + echo 'OK; PASS.' 231s + rm -rf /tmp/tmp.Usp3D6s4Sr 231s autopkgtest [08:44:13]: test basic-usage: -----------------------] 232s autopkgtest [08:44:14]: test basic-usage: - - - - - - - - - - results - - - - - - - - - - 232s basic-usage PASS 232s autopkgtest [08:44:14]: @@@@@@@@@@@@@@@@@@@@ summary 232s basic-usage PASS 244s nova [W] Using flock in prodstack6-ppc64el 244s Creating nova instance adt-plucky-ppc64el-at-20241107-084022-juju-7f2275-prod-proposed-migration-environment-15-80b230fa-b2aa-488e-902f-00a056302e15 from image adt/ubuntu-plucky-ppc64el-server-20241107.img (UUID c88647d2-7466-469c-8e5a-eeebb6c3f0a4)...