0s autopkgtest [19:00:16]: starting date and time: 2024-12-17 19:00:16+0000 0s autopkgtest [19:00:16]: git checkout: c2a78735 Merge branch 'timeout-nova-boot' into 'ubuntu/production' 0s autopkgtest [19:00:16]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.h3e8hz5j/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:dpkg --apt-upgrade clamav --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=dpkg/1.22.6ubuntu6.2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-3.secgroup --name adt-noble-s390x-clamav-20241217-190016-juju-7f2275-prod-proposed-migration-environment-2-6050390d-f49a-4182-b329-0a429c47f384 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 88s autopkgtest [19:01:44]: testbed dpkg architecture: s390x 88s autopkgtest [19:01:44]: testbed apt version: 2.7.14build2 89s autopkgtest [19:01:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 89s autopkgtest [19:01:45]: testbed release detected to be: None 90s autopkgtest [19:01:46]: updating testbed package index (apt update) 90s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB] 90s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 91s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 91s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 91s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [75.0 kB] 91s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [75.5 kB] 91s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [4008 B] 91s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [98.0 kB] 91s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3744 B] 91s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1176 B] 91s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 91s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [306 kB] 91s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [5504 B] 91s Get:14 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 93s Fetched 834 kB in 2s (500 kB/s) 94s Reading package lists... 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Calculating upgrade... 95s The following packages will be upgraded: 95s cloud-init fwupd libfwupd2 95s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 95s Need to get 5214 kB of archives. 95s After this operation, 129 kB of additional disk space will be used. 95s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libfwupd2 s390x 1.9.27-0ubuntu1~24.04.1 [134 kB] 95s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x fwupd s390x 1.9.27-0ubuntu1~24.04.1 [4479 kB] 101s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x cloud-init all 24.4-0ubuntu1~24.04.2 [602 kB] 102s Preconfiguring packages ... 102s Fetched 5214 kB in 7s (732 kB/s) 102s (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 ... 78298 files and directories currently installed.) 102s Preparing to unpack .../libfwupd2_1.9.27-0ubuntu1~24.04.1_s390x.deb ... 102s Unpacking libfwupd2:s390x (1.9.27-0ubuntu1~24.04.1) over (1.9.24-1~24.04.1) ... 102s Preparing to unpack .../fwupd_1.9.27-0ubuntu1~24.04.1_s390x.deb ... 102s Unpacking fwupd (1.9.27-0ubuntu1~24.04.1) over (1.9.24-1~24.04.1) ... 102s Preparing to unpack .../cloud-init_24.4-0ubuntu1~24.04.2_all.deb ... 103s Unpacking cloud-init (24.4-0ubuntu1~24.04.2) over (24.3.1-0ubuntu0~24.04.2) ... 103s Setting up cloud-init (24.4-0ubuntu1~24.04.2) ... 104s Setting up libfwupd2:s390x (1.9.27-0ubuntu1~24.04.1) ... 104s Setting up fwupd (1.9.27-0ubuntu1~24.04.1) ... 104s fwupd-offline-update.service is a disabled or a static unit not running, not starting it. 104s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 104s fwupd.service is a disabled or a static unit not running, not starting it. 104s Processing triggers for rsyslog (8.2312.0-3ubuntu9) ... 104s Processing triggers for man-db (2.12.0-4build2) ... 105s Processing triggers for dbus (1.14.10-4ubuntu4.1) ... 105s Processing triggers for libc-bin (2.39-0ubuntu8.3) ... 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s 0 upgraded, 0 newly installed, 0 to remove and 3 not upgraded. 105s autopkgtest [19:02:01]: upgrading testbed (apt dist-upgrade and autopurge) 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 106s Starting 2 pkgProblemResolver with broken count: 0 106s Done 106s Entering ResolveByKeep 106s 106s The following packages will be upgraded: 106s dpkg dpkg-dev libdpkg-perl 106s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s Need to get 2619 kB of archives. 106s After this operation, 0 B of additional disk space will be used. 106s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x dpkg s390x 1.22.6ubuntu6.2 [1276 kB] 108s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x dpkg-dev all 1.22.6ubuntu6.2 [1074 kB] 110s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libdpkg-perl all 1.22.6ubuntu6.2 [269 kB] 110s Fetched 2619 kB in 4s (722 kB/s) 110s (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 ... 78305 files and directories currently installed.) 110s Preparing to unpack .../dpkg_1.22.6ubuntu6.2_s390x.deb ... 110s Unpacking dpkg (1.22.6ubuntu6.2) over (1.22.6ubuntu6.1) ... 110s Setting up dpkg (1.22.6ubuntu6.2) ... 111s (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 ... 78305 files and directories currently installed.) 111s Preparing to unpack .../dpkg-dev_1.22.6ubuntu6.2_all.deb ... 111s Unpacking dpkg-dev (1.22.6ubuntu6.2) over (1.22.6ubuntu6.1) ... 111s Preparing to unpack .../libdpkg-perl_1.22.6ubuntu6.2_all.deb ... 111s Unpacking libdpkg-perl (1.22.6ubuntu6.2) over (1.22.6ubuntu6.1) ... 111s Setting up libdpkg-perl (1.22.6ubuntu6.2) ... 111s Setting up dpkg-dev (1.22.6ubuntu6.2) ... 111s Processing triggers for man-db (2.12.0-4build2) ... 111s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Starting pkgProblemResolver with broken count: 0 112s Starting 2 pkgProblemResolver with broken count: 0 112s Done 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s autopkgtest [19:02:08]: rebooting testbed after setup commands that affected boot 116s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 138s autopkgtest [19:02:34]: testbed running kernel: Linux 6.8.0-51-generic #52-Ubuntu SMP Thu Dec 5 12:14:09 UTC 2024 140s autopkgtest [19:02:36]: @@@@@@@@@@@@@@@@@@@@ apt-source clamav 185s Get:1 http://ftpmaster.internal/ubuntu noble-updates/main clamav 1.0.7+dfsg-0ubuntu0.24.04.1 (dsc) [3174 B] 185s Get:2 http://ftpmaster.internal/ubuntu noble-updates/main clamav 1.0.7+dfsg-0ubuntu0.24.04.1 (tar) [32.3 MB] 185s Get:3 http://ftpmaster.internal/ubuntu noble-updates/main clamav 1.0.7+dfsg-0ubuntu0.24.04.1 (diff) [228 kB] 185s gpgv: Signature made Mon Sep 9 11:23:03 2024 UTC 185s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 185s gpgv: Can't check signature: No public key 185s dpkg-source: warning: cannot verify inline signature for ./clamav_1.0.7+dfsg-0ubuntu0.24.04.1.dsc: no acceptable signature found 190s autopkgtest [19:03:26]: testing package clamav version 1.0.7+dfsg-0ubuntu0.24.04.1 191s autopkgtest [19:03:27]: build not needed 209s autopkgtest [19:03:45]: test clamd: preparing testbed 209s Reading package lists... 209s Building dependency tree... 209s Reading state information... 209s Starting pkgProblemResolver with broken count: 0 209s Starting 2 pkgProblemResolver with broken count: 0 209s Done 210s The following NEW packages will be installed: 210s clamav clamav-base clamav-daemon clamav-freshclam clamav-milter 210s libclamav11t64 libmilter1.0.1 libmspack0t64 210s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 210s Need to get 6740 kB of archives. 210s After this operation, 34.8 MB of additional disk space will be used. 210s Get:1 http://ftpmaster.internal/ubuntu noble-updates/main s390x clamav-base all 1.0.7+dfsg-0ubuntu0.24.04.1 [93.4 kB] 210s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libmspack0t64 s390x 0.11-1.1build1 [44.7 kB] 210s Get:3 http://ftpmaster.internal/ubuntu noble-updates/main s390x libclamav11t64 s390x 1.0.7+dfsg-0ubuntu0.24.04.1 [3449 kB] 214s Get:4 http://ftpmaster.internal/ubuntu noble-updates/main s390x clamav-freshclam s390x 1.0.7+dfsg-0ubuntu0.24.04.1 [98.5 kB] 214s Get:5 http://ftpmaster.internal/ubuntu noble-updates/main s390x clamav-daemon s390x 1.0.7+dfsg-0ubuntu0.24.04.1 [219 kB] 214s Get:6 http://ftpmaster.internal/ubuntu noble/universe s390x libmilter1.0.1 s390x 8.18.1-2 [42.3 kB] 214s Get:7 http://ftpmaster.internal/ubuntu noble-updates/universe s390x clamav-milter s390x 1.0.7+dfsg-0ubuntu0.24.04.1 [118 kB] 215s Get:8 http://ftpmaster.internal/ubuntu noble-updates/main s390x clamav s390x 1.0.7+dfsg-0ubuntu0.24.04.1 [2675 kB] 218s Preconfiguring packages ... 218s Fetched 6740 kB in 8s (817 kB/s) 218s Selecting previously unselected package clamav-base. 218s (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 ... 78305 files and directories currently installed.) 218s Preparing to unpack .../0-clamav-base_1.0.7+dfsg-0ubuntu0.24.04.1_all.deb ... 218s Unpacking clamav-base (1.0.7+dfsg-0ubuntu0.24.04.1) ... 218s Selecting previously unselected package libmspack0t64:s390x. 218s Preparing to unpack .../1-libmspack0t64_0.11-1.1build1_s390x.deb ... 218s Unpacking libmspack0t64:s390x (0.11-1.1build1) ... 218s Selecting previously unselected package libclamav11t64:s390x. 218s Preparing to unpack .../2-libclamav11t64_1.0.7+dfsg-0ubuntu0.24.04.1_s390x.deb ... 218s Unpacking libclamav11t64:s390x (1.0.7+dfsg-0ubuntu0.24.04.1) ... 218s Selecting previously unselected package clamav-freshclam. 218s Preparing to unpack .../3-clamav-freshclam_1.0.7+dfsg-0ubuntu0.24.04.1_s390x.deb ... 218s Unpacking clamav-freshclam (1.0.7+dfsg-0ubuntu0.24.04.1) ... 218s Selecting previously unselected package clamav-daemon. 218s Preparing to unpack .../4-clamav-daemon_1.0.7+dfsg-0ubuntu0.24.04.1_s390x.deb ... 218s Unpacking clamav-daemon (1.0.7+dfsg-0ubuntu0.24.04.1) ... 219s Selecting previously unselected package libmilter1.0.1:s390x. 219s Preparing to unpack .../5-libmilter1.0.1_8.18.1-2_s390x.deb ... 219s Unpacking libmilter1.0.1:s390x (8.18.1-2) ... 219s Selecting previously unselected package clamav-milter. 219s Preparing to unpack .../6-clamav-milter_1.0.7+dfsg-0ubuntu0.24.04.1_s390x.deb ... 219s Unpacking clamav-milter (1.0.7+dfsg-0ubuntu0.24.04.1) ... 219s Selecting previously unselected package clamav. 219s Preparing to unpack .../7-clamav_1.0.7+dfsg-0ubuntu0.24.04.1_s390x.deb ... 219s Unpacking clamav (1.0.7+dfsg-0ubuntu0.24.04.1) ... 219s Setting up libmspack0t64:s390x (0.11-1.1build1) ... 219s Setting up libmilter1.0.1:s390x (8.18.1-2) ... 219s Setting up libclamav11t64:s390x (1.0.7+dfsg-0ubuntu0.24.04.1) ... 219s Setting up clamav-base (1.0.7+dfsg-0ubuntu0.24.04.1) ... 219s Setting up clamav-freshclam (1.0.7+dfsg-0ubuntu0.24.04.1) ... 219s Setting up clamav-daemon (1.0.7+dfsg-0ubuntu0.24.04.1) ... 220s Created symlink /etc/systemd/system/multi-user.target.wants/clamav-daemon.service → /usr/lib/systemd/system/clamav-daemon.service. 220s Created symlink /etc/systemd/system/sockets.target.wants/clamav-daemon.socket → /usr/lib/systemd/system/clamav-daemon.socket. 220s Setting up clamav (1.0.7+dfsg-0ubuntu0.24.04.1) ... 220s Setting up clamav-milter (1.0.7+dfsg-0ubuntu0.24.04.1) ... 220s Processing triggers for man-db (2.12.0-4build2) ... 221s Processing triggers for libc-bin (2.39-0ubuntu8.3) ... 223s autopkgtest [19:03:59]: test clamd: [----------------------- 223s OK 223s autopkgtest [19:03:59]: test clamd: -----------------------] 224s clamd PASS 224s autopkgtest [19:04:00]: test clamd: - - - - - - - - - - results - - - - - - - - - - 224s autopkgtest [19:04:00]: test client: preparing testbed 225s Reading package lists... 225s Building dependency tree... 225s Reading state information... 225s Starting pkgProblemResolver with broken count: 0 225s Starting 2 pkgProblemResolver with broken count: 0 225s Done 225s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 227s autopkgtest [19:04:03]: test client: [----------------------- 228s autopkgtest [19:04:04]: test client: -----------------------] 228s autopkgtest [19:04:04]: test client: - - - - - - - - - - results - - - - - - - - - - 228s client PASS 229s autopkgtest [19:04:05]: test milter: preparing testbed 229s Reading package lists... 229s Building dependency tree... 229s Reading state information... 229s Starting pkgProblemResolver with broken count: 0 229s Starting 2 pkgProblemResolver with broken count: 0 229s Done 230s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 231s autopkgtest [19:04:07]: test milter: [----------------------- 231s OK 231s autopkgtest [19:04:07]: test milter: -----------------------] 232s autopkgtest [19:04:08]: test milter: - - - - - - - - - - results - - - - - - - - - - 232s milter PASS 232s autopkgtest [19:04:08]: @@@@@@@@@@@@@@@@@@@@ summary 232s clamd PASS 232s client PASS 232s milter PASS 241s nova [W] Using flock in prodstack6-s390x 241s Creating nova instance adt-noble-s390x-clamav-20241217-190016-juju-7f2275-prod-proposed-migration-environment-2-6050390d-f49a-4182-b329-0a429c47f384 from image adt/ubuntu-noble-s390x-server-20241217.img (UUID bed51951-726a-4af0-aa3f-31def938a35d)... 241s nova [W] Timed out waiting for 67d6339e-6e67-483c-8837-3ee8efebc089 to get deleted.