0s autopkgtest [21:18:36]: starting date and time: 2026-02-03 21:18:36+0000 0s autopkgtest [21:18:36]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [21:18:36]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.7rk4mksh/out --timeout-copy=6000 --needs-internet=try --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.7.0-5ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-ppc64el-22.secgroup --name adt-resolute-ppc64el-at-20260203-211836-juju-7f2275-prod-proposed-migration-environment-20-3ae4ebd7-89d0-4d27-af0c-f0225cfa18a3 --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-ppc64el-at-20260203-211836-juju-7f2275-prod-proposed-migration-environment-20-3ae4ebd7-89d0-4d27-af0c-f0225cfa18a3 from image adt/ubuntu-resolute-ppc64el-server-20260203.img (UUID 36e1410e-0a72-4cd6-affd-580c415d80cb)... 62s autopkgtest [21:19:38]: testbed dpkg architecture: ppc64el 62s autopkgtest [21:19:38]: testbed apt version: 3.1.14 62s autopkgtest [21:19:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 62s autopkgtest [21:19:38]: testbed release detected to be: None 63s autopkgtest [21:19:39]: updating testbed package index (apt update) 63s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 63s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 63s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 63s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 63s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 63s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1343 kB] 64s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [242 kB] 64s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [27.9 kB] 64s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [289 kB] 64s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [1151 kB] 64s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [17.8 kB] 64s Fetched 3201 kB in 1s (2981 kB/s) 65s Reading package lists... 65s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 65s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 66s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 66s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 66s Reading package lists... 66s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s Calculating upgrade... 67s The following packages will be upgraded: 67s libpam-modules libpam-modules-bin libpam-runtime libpam0g 67s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s Need to get 523 kB of archives. 67s After this operation, 0 B of additional disk space will be used. 67s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpam-runtime all 1.7.0-5ubuntu3 [149 kB] 67s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpam0g ppc64el 1.7.0-5ubuntu3 [77.3 kB] 67s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpam-modules-bin ppc64el 1.7.0-5ubuntu3 [53.2 kB] 67s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpam-modules ppc64el 1.7.0-5ubuntu3 [244 kB] 67s dpkg-preconfigure: unable to re-open stdin: No such file or directory 67s Fetched 523 kB in 0s (19.3 MB/s) 67s (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 ... 82008 files and directories currently installed.) 67s Preparing to unpack .../libpam-runtime_1.7.0-5ubuntu3_all.deb ... 67s Unpacking libpam-runtime (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 68s Setting up libpam-runtime (1.7.0-5ubuntu3) ... 68s (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 ... 82008 files and directories currently installed.) 68s Preparing to unpack .../libpam0g_1.7.0-5ubuntu3_ppc64el.deb ... 68s Unpacking libpam0g:ppc64el (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 68s Setting up libpam0g:ppc64el (1.7.0-5ubuntu3) ... 68s (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 ... 82008 files and directories currently installed.) 68s Preparing to unpack .../libpam-modules-bin_1.7.0-5ubuntu3_ppc64el.deb ... 68s Unpacking libpam-modules-bin (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 68s Setting up libpam-modules-bin (1.7.0-5ubuntu3) ... 69s pam_namespace.service is a disabled or a static unit not running, not starting it. 69s (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 ... 82008 files and directories currently installed.) 69s Preparing to unpack .../libpam-modules_1.7.0-5ubuntu3_ppc64el.deb ... 69s Unpacking libpam-modules:ppc64el (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 69s Setting up libpam-modules:ppc64el (1.7.0-5ubuntu3) ... 69s Processing triggers for man-db (2.13.1-1) ... 70s Processing triggers for libc-bin (2.42-2ubuntu4) ... 70s autopkgtest [21:19:46]: upgrading testbed (apt dist-upgrade and autopurge) 70s Reading package lists... 70s Building dependency tree... 70s Reading state information... 71s Calculating upgrade... 71s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 71s Reading package lists... 71s Building dependency tree... 71s Reading state information... 71s Solving dependencies... 71s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 71s autopkgtest [21:19:47]: rebooting testbed after setup commands that affected boot 109s autopkgtest [21:20:25]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:45:54 UTC 2026 111s autopkgtest [21:20:27]: @@@@@@@@@@@@@@@@@@@@ apt-source at 113s Get:1 http://ftpmaster.internal/ubuntu resolute/universe at 3.2.5-2.2ubuntu1 (dsc) [2078 B] 113s Get:2 http://ftpmaster.internal/ubuntu resolute/universe at 3.2.5-2.2ubuntu1 (tar) [133 kB] 113s Get:3 http://ftpmaster.internal/ubuntu resolute/universe at 3.2.5-2.2ubuntu1 (diff) [22.2 kB] 113s gpgv: Signature made Tue Jun 3 20:24:40 2025 UTC 113s gpgv: using RSA key B8340990283D8D9BC1949AC74799A35146D12B35 113s gpgv: Can't check signature: No public key 113s dpkg-source: warning: cannot verify inline signature for ./at_3.2.5-2.2ubuntu1.dsc: no acceptable signature found 113s autopkgtest [21:20:29]: testing package at version 3.2.5-2.2ubuntu1 113s autopkgtest [21:20:29]: build not needed 114s autopkgtest [21:20:30]: test basic-usage: preparing testbed 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Solving dependencies... 114s The following NEW packages will be installed: 114s at 114s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 114s Need to get 42.7 kB of archives. 114s After this operation, 208 kB of additional disk space will be used. 114s Get:1 http://ftpmaster.internal/ubuntu resolute/universe ppc64el at ppc64el 3.2.5-2.2ubuntu1 [42.7 kB] 115s Fetched 42.7 kB in 0s (743 kB/s) 115s Selecting previously unselected package at. 115s (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 ... 82008 files and directories currently installed.) 115s Preparing to unpack .../at_3.2.5-2.2ubuntu1_ppc64el.deb ... 115s Unpacking at (3.2.5-2.2ubuntu1) ... 115s Setting up at (3.2.5-2.2ubuntu1) ... 115s Created symlink '/etc/systemd/system/multi-user.target.wants/atd.service' → '/usr/lib/systemd/system/atd.service'. 116s Processing triggers for man-db (2.13.1-1) ... 119s autopkgtest [21:20:35]: test basic-usage: [----------------------- 119s + TMPFILE=at.30000 119s ++ mktemp -d 119s + WORKDIR=/tmp/tmp.r7kZZCouNV 119s + trap 'rm -rf /tmp/tmp.r7kZZCouNV' 0 INT QUIT ABRT PIPE TERM 119s ++ atq 119s ++ wc -l 119s + JOBS_BEFORE=0 119s + at now + 2 minutes 119s ++ date -u 119s warning: commands will be executed using /bin/sh 119s + echo 'echo Tue Feb 3 21:20:34 UTC 2026 > /tmp/tmp.r7kZZCouNV/at.30000' 119s job 1 at Tue Feb 3 21:22:00 2026 119s + sleep 2 121s OK, /tmp/tmp.r7kZZCouNV/at.30000 doesn't exist yet; expected.. 121s + test -f /tmp/tmp.r7kZZCouNV/at.30000 121s + echo 'OK, /tmp/tmp.r7kZZCouNV/at.30000 doesn'\''t exist yet; expected..' 121s ++ atq 121s ++ wc -l 121s + JOBS_AFTER=1 121s + [[ 1 -eq 1 ]] 121s + echo 'OK, 1 new queued job exists..' 121s + sleep 120 121s OK, 1 new queued job exists.. 241s + grep -Fq UTC /tmp/tmp.r7kZZCouNV/at.30000 241s OK, /tmp/tmp.r7kZZCouNV/at.30000 exists and everything looks in order.. 241s OK; PASS. 241s + echo 'OK, /tmp/tmp.r7kZZCouNV/at.30000 exists and everything looks in order..' 241s + echo 'OK; PASS.' 241s + rm -rf /tmp/tmp.r7kZZCouNV 241s autopkgtest [21:22:37]: test basic-usage: -----------------------] 242s autopkgtest [21:22:38]: test basic-usage: - - - - - - - - - - results - - - - - - - - - - 242s basic-usage PASS 242s autopkgtest [21:22:38]: @@@@@@@@@@@@@@@@@@@@ summary 242s basic-usage PASS