0s autopkgtest [21:33:51]: starting date and time: 2024-03-21 21:33:51+0000 0s autopkgtest [21:33:51]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:33:51]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lruvbhwj/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:libselinux,src:ruby-defaults --apt-upgrade at --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libselinux/3.5-2ubuntu1 ruby-defaults/1:3.2~ubuntu1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-19.secgroup --name adt-noble-s390x-at-20240321-213351-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 107s autopkgtest [21:35:38]: testbed dpkg architecture: s390x 107s autopkgtest [21:35:38]: testbed apt version: 2.7.12 107s autopkgtest [21:35:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 109s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 109s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3795 kB] 110s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB] 110s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 110s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 110s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [662 kB] 110s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 110s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 110s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 110s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3985 kB] 111s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 111s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 111s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 114s Fetched 9170 kB in 4s (2357 kB/s) 114s Reading package lists... 117s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s Calculating upgrade... 119s The following packages will be upgraded: 119s debianutils libselinux1 119s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s Need to get 175 kB of archives. 119s After this operation, 1024 B of additional disk space will be used. 119s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 119s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libselinux1 s390x 3.5-2ubuntu1 [84.7 kB] 120s Fetched 175 kB in 1s (300 kB/s) 120s (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 ... 52171 files and directories currently installed.) 120s Preparing to unpack .../debianutils_5.17_s390x.deb ... 120s Unpacking debianutils (5.17) over (5.16) ... 120s Setting up debianutils (5.17) ... 120s (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 ... 52171 files and directories currently installed.) 120s Preparing to unpack .../libselinux1_3.5-2ubuntu1_s390x.deb ... 120s Unpacking libselinux1:s390x (3.5-2ubuntu1) over (3.5-2build1) ... 120s Setting up libselinux1:s390x (3.5-2ubuntu1) ... 120s Processing triggers for man-db (2.12.0-3) ... 122s Processing triggers for libc-bin (2.39-0ubuntu2) ... 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Unknown architecture, assuming PC-style ttyS0 124s sh: Attempting to set up Debian/Ubuntu apt sources automatically 124s sh: Distribution appears to be Ubuntu 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s eatmydata is already the newest version (131-1). 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s dbus is already the newest version (1.14.10-4ubuntu1). 127s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s Reading package lists... 128s Building dependency tree... 128s Reading state information... 128s rng-tools-debian is already the newest version (2.4). 128s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 128s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s The following packages will be REMOVED: 129s cloud-init* python3-configobj* python3-debconf* 130s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 130s After this operation, 3252 kB disk space will be freed. 130s (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 ... 52171 files and directories currently installed.) 130s Removing cloud-init (24.1.1-0ubuntu1) ... 131s Removing python3-configobj (5.0.8-3) ... 131s Removing python3-debconf (1.5.86) ... 131s Processing triggers for man-db (2.12.0-3) ... 132s (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 ... 51782 files and directories currently installed.) 132s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 133s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 133s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 133s invoke-rc.d: policy-rc.d denied execution of try-restart. 133s Reading package lists... 133s Building dependency tree... 133s Reading state information... 134s linux-generic is already the newest version (6.8.0-11.11+1). 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 134s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 135s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 135s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 138s Reading package lists... 138s Reading package lists... 139s Building dependency tree... 139s Reading state information... 139s Calculating upgrade... 140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s autopkgtest [21:36:12]: rebooting testbed after setup commands that affected boot 160s autopkgtest [21:36:31]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 163s autopkgtest [21:36:34]: @@@@@@@@@@@@@@@@@@@@ apt-source at 166s Get:1 http://ftpmaster.internal/ubuntu noble/universe at 3.2.5-1ubuntu1 (dsc) [2034 B] 166s Get:2 http://ftpmaster.internal/ubuntu noble/universe at 3.2.5-1ubuntu1 (tar) [133 kB] 166s Get:3 http://ftpmaster.internal/ubuntu noble/universe at 3.2.5-1ubuntu1 (diff) [21.6 kB] 166s gpgv: Signature made Thu Apr 14 00:27:57 2022 UTC 166s gpgv: using RSA key E564B9C275BDD52E 166s gpgv: Can't check signature: No public key 166s dpkg-source: warning: cannot verify inline signature for ./at_3.2.5-1ubuntu1.dsc: no acceptable signature found 166s autopkgtest [21:36:37]: testing package at version 3.2.5-1ubuntu1 166s autopkgtest [21:36:37]: build not needed 167s autopkgtest [21:36:38]: test basic-usage: preparing testbed 174s Reading package lists... 174s Building dependency tree... 174s Reading state information... 174s Starting pkgProblemResolver with broken count: 0 174s Starting 2 pkgProblemResolver with broken count: 0 174s Done 175s The following additional packages will be installed: 175s at 175s Suggested packages: 175s default-mta | mail-transport-agent 175s The following NEW packages will be installed: 175s at autopkgtest-satdep 175s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 175s Need to get 40.5 kB/41.2 kB of archives. 175s After this operation, 153 kB of additional disk space will be used. 175s Get:1 /tmp/autopkgtest.eWVEVh/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [700 B] 175s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x at s390x 3.2.5-1ubuntu1 [40.5 kB] 176s Fetched 40.5 kB in 0s (166 kB/s) 176s Selecting previously unselected package at. 176s (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 ... 51727 files and directories currently installed.) 176s Preparing to unpack .../at_3.2.5-1ubuntu1_s390x.deb ... 176s Unpacking at (3.2.5-1ubuntu1) ... 176s Selecting previously unselected package autopkgtest-satdep. 176s Preparing to unpack .../1-autopkgtest-satdep.deb ... 176s Unpacking autopkgtest-satdep (0) ... 176s Setting up at (3.2.5-1ubuntu1) ... 176s Created symlink /etc/systemd/system/multi-user.target.wants/atd.service → /usr/lib/systemd/system/atd.service. 177s Setting up autopkgtest-satdep (0) ... 177s Processing triggers for man-db (2.12.0-3) ... 182s (Reading database ... 51755 files and directories currently installed.) 182s Removing autopkgtest-satdep (0) ... 182s autopkgtest [21:36:53]: test basic-usage: [----------------------- 183s + TMPFILE=at.15073 183s ++ mktemp -d 183s + WORKDIR=/tmp/tmp.gOz55a3nN7 183s + trap 'rm -rf /tmp/tmp.gOz55a3nN7' 0 INT QUIT ABRT PIPE TERM 183s ++ atq 183s ++ wc -l 183s + JOBS_BEFORE=0 183s + at now + 1 minute 183s ++ date 183s warning: commands will be executed using /bin/sh 183s + echo 'echo Thu Mar 21 21:36:52 UTC 2024 > /tmp/tmp.gOz55a3nN7/at.15073' 183s job 1 at Thu Mar 21 21:37:00 2024 183s + sleep 2 185s + test -f at.15073 185s + echo 'OK, at.15073 doesn'\''t exist yet; expected..' 185s OK, at.15073 doesn't exist yet; expected.. 185s ++ atq 185s ++ wc -l 185s + JOBS_AFTER=1 185s + [[ 1 -eq 1 ]] 185s + echo 'OK, 1 new queued job exists..' 185s OK, 1 new queued job exists.. 185s + sleep 58 243s + grep -Fq UTC /tmp/tmp.gOz55a3nN7/at.15073 243s + echo 'OK, at.15073 exists and everything looks in order..' 243s OK, at.15073 exists and everything looks in order.. 243s + echo 'OK; PASS.' 243s OK; PASS. 243s + rm -rf /tmp/tmp.gOz55a3nN7 243s autopkgtest [21:37:54]: test basic-usage: -----------------------] 244s basic-usage PASS 244s autopkgtest [21:37:55]: test basic-usage: - - - - - - - - - - results - - - - - - - - - - 244s autopkgtest [21:37:55]: @@@@@@@@@@@@@@@@@@@@ summary 244s basic-usage PASS 255s Creating nova instance adt-noble-s390x-at-20240321-213351-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...