0s autopkgtest [19:35:13]: starting date and time: 2026-02-03 19:35:13+0000 0s autopkgtest [19:35:13]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [19:35:13]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.f_h8r6oc/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --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 -- lxd -r lxd-armhf-10.145.243.232 lxd-armhf-10.145.243.232:autopkgtest/ubuntu/resolute/armhf 25s autopkgtest [19:35:38]: testbed dpkg architecture: armhf 27s autopkgtest [19:35:40]: testbed apt version: 3.1.14 30s autopkgtest [19:35:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 32s autopkgtest [19:35:45]: testbed release detected to be: None 41s autopkgtest [19:35:54]: updating testbed package index (apt update) 43s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 43s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 43s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 43s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 43s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [27.9 kB] 43s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1343 kB] 44s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [242 kB] 44s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 44s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [283 kB] 44s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [1070 kB] 44s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [6336 B] 44s Get:12 http://ftpmaster.internal/ubuntu resolute/main Sources [1400 kB] 44s Get:13 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.3 MB] 45s Get:14 http://ftpmaster.internal/ubuntu resolute/multiverse Sources [310 kB] 45s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1373 kB] 45s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.0 MB] 46s Get:17 http://ftpmaster.internal/ubuntu resolute/multiverse armhf Packages [176 kB] 49s Fetched 43.1 MB in 6s (7172 kB/s) 50s Reading package lists... 56s autopkgtest [19:36:09]: upgrading testbed (apt dist-upgrade and autopurge) 57s Reading package lists... 58s Building dependency tree... 58s Reading state information... 58s Calculating upgrade... 59s The following packages will be upgraded: 59s libpam-modules libpam-modules-bin libpam-runtime libpam0g powermgmt-base 59s systemd-hwe-hwdb 59s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 59s Need to get 443 kB of archives. 59s After this operation, 1024 B of additional disk space will be used. 59s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libpam-runtime all 1.7.0-5ubuntu3 [149 kB] 59s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libpam0g armhf 1.7.0-5ubuntu3 [64.6 kB] 59s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libpam-modules-bin armhf 1.7.0-5ubuntu3 [41.1 kB] 59s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libpam-modules armhf 1.7.0-5ubuntu3 [177 kB] 59s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf systemd-hwe-hwdb all 259.0.1 [3152 B] 59s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf powermgmt-base all 1.38ubuntu2 [7878 B] 60s Preconfiguring packages ... 60s Fetched 443 kB in 1s (738 kB/s) 60s (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 ... 68394 files and directories currently installed.) 60s Preparing to unpack .../libpam-runtime_1.7.0-5ubuntu3_all.deb ... 60s Unpacking libpam-runtime (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 60s Setting up libpam-runtime (1.7.0-5ubuntu3) ... 60s (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 ... 68394 files and directories currently installed.) 60s Preparing to unpack .../libpam0g_1.7.0-5ubuntu3_armhf.deb ... 60s Unpacking libpam0g:armhf (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 60s Setting up libpam0g:armhf (1.7.0-5ubuntu3) ... 60s (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 ... 68394 files and directories currently installed.) 60s Preparing to unpack .../libpam-modules-bin_1.7.0-5ubuntu3_armhf.deb ... 60s Unpacking libpam-modules-bin (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 60s Setting up libpam-modules-bin (1.7.0-5ubuntu3) ... 61s pam_namespace.service is a disabled or a static unit not running, not starting it. 61s (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 ... 68394 files and directories currently installed.) 61s Preparing to unpack .../libpam-modules_1.7.0-5ubuntu3_armhf.deb ... 61s Unpacking libpam-modules:armhf (1.7.0-5ubuntu3) over (1.7.0-5ubuntu2) ... 61s Setting up libpam-modules:armhf (1.7.0-5ubuntu3) ... 61s (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 ... 68394 files and directories currently installed.) 61s Preparing to unpack .../systemd-hwe-hwdb_259.0.1_all.deb ... 61s Unpacking systemd-hwe-hwdb (259.0.1) over (257.7.1) ... 61s Preparing to unpack .../powermgmt-base_1.38ubuntu2_all.deb ... 61s Unpacking powermgmt-base (1.38ubuntu2) over (1.38ubuntu1) ... 61s Setting up powermgmt-base (1.38ubuntu2) ... 61s Setting up systemd-hwe-hwdb (259.0.1) ... 62s Processing triggers for man-db (2.13.1-1) ... 63s Processing triggers for udev (259-1ubuntu3) ... 64s Processing triggers for libc-bin (2.42-2ubuntu4) ... 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 69s autopkgtest [19:36:22]: rebooting testbed after setup commands that affected boot 110s autopkgtest [19:37:03]: testbed running kernel: Linux 6.8.0-87-generic #88~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 14 14:00:09 UTC 2 135s autopkgtest [19:37:28]: @@@@@@@@@@@@@@@@@@@@ apt-source at 145s Get:1 http://ftpmaster.internal/ubuntu resolute/universe at 3.2.5-2.2ubuntu1 (dsc) [2078 B] 145s Get:2 http://ftpmaster.internal/ubuntu resolute/universe at 3.2.5-2.2ubuntu1 (tar) [133 kB] 145s Get:3 http://ftpmaster.internal/ubuntu resolute/universe at 3.2.5-2.2ubuntu1 (diff) [22.2 kB] 145s gpgv: Signature made Tue Jun 3 20:24:40 2025 UTC 145s gpgv: using RSA key B8340990283D8D9BC1949AC74799A35146D12B35 145s gpgv: Can't check signature: No public key 145s dpkg-source: warning: cannot verify inline signature for ./at_3.2.5-2.2ubuntu1.dsc: no acceptable signature found 145s autopkgtest [19:37:38]: testing package at version 3.2.5-2.2ubuntu1 147s autopkgtest [19:37:40]: build not needed 150s autopkgtest [19:37:43]: test basic-usage: preparing testbed 151s Reading package lists... 152s Building dependency tree... 152s Reading state information... 152s Solving dependencies... 152s The following NEW packages will be installed: 152s at 153s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 153s Need to get 42.1 kB of archives. 153s After this operation, 206 kB of additional disk space will be used. 153s Get:1 http://ftpmaster.internal/ubuntu resolute/universe armhf at armhf 3.2.5-2.2ubuntu1 [42.1 kB] 153s Fetched 42.1 kB in 0s (142 kB/s) 153s Selecting previously unselected package at. 153s (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 ... 68395 files and directories currently installed.) 153s Preparing to unpack .../at_3.2.5-2.2ubuntu1_armhf.deb ... 153s Unpacking at (3.2.5-2.2ubuntu1) ... 153s Setting up at (3.2.5-2.2ubuntu1) ... 154s Created symlink '/etc/systemd/system/multi-user.target.wants/atd.service' → '/usr/lib/systemd/system/atd.service'. 154s Processing triggers for man-db (2.13.1-1) ... 163s autopkgtest [19:37:56]: test basic-usage: [----------------------- 165s + TMPFILE=at.12058 165s ++ mktemp -d 165s + WORKDIR=/tmp/tmp.MvAt0xDQmz 165s + trap 'rm -rf /tmp/tmp.MvAt0xDQmz' 0 INT QUIT ABRT PIPE TERM 165s ++ atq 165s ++ wc -l 165s + JOBS_BEFORE=0 165s + at now + 2 minutes 165s ++ date -u 165s warning: commands will be executed using /bin/sh 165s + echo 'echo Tue Feb 3 19:37:58 UTC 2026 > /tmp/tmp.MvAt0xDQmz/at.12058' 165s job 1 at Tue Feb 3 19:39:00 2026 165s + sleep 2 167s OK, /tmp/tmp.MvAt0xDQmz/at.12058 doesn't exist yet; expected.. 167s + test -f /tmp/tmp.MvAt0xDQmz/at.12058 167s + echo 'OK, /tmp/tmp.MvAt0xDQmz/at.12058 doesn'\''t exist yet; expected..' 167s ++ atq 167s ++ wc -l 167s + JOBS_AFTER=1 167s + [[ 1 -eq 1 ]] 167s + echo 'OK, 1 new queued job exists..' 167s + sleep 120 167s OK, 1 new queued job exists.. 287s + grep -Fq UTC /tmp/tmp.MvAt0xDQmz/at.12058 287s + echo 'OK, /tmp/tmp.MvAt0xDQmz/at.12058 exists and everything looks in order..' 287s + echo 'OK; PASS.' 287s + rm -rf /tmp/tmp.MvAt0xDQmz 287s OK, /tmp/tmp.MvAt0xDQmz/at.12058 exists and everything looks in order.. 287s OK; PASS. 287s autopkgtest [19:40:00]: test basic-usage: -----------------------] 291s autopkgtest [19:40:04]: test basic-usage: - - - - - - - - - - results - - - - - - - - - - 291s basic-usage PASS 295s autopkgtest [19:40:08]: @@@@@@@@@@@@@@@@@@@@ summary 295s basic-usage PASS