0s autopkgtest [13:12:55]: starting date and time: 2024-06-03 13:12:55+0000 0s autopkgtest [13:12:55]: git checkout: 930f72e5 setup-testbed: don't install 'Recommends' for 'linux-generic' 0s autopkgtest [13:12:55]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.hvbmzf3l/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:libxml2,src:libxml-libxml-perl --apt-upgrade clamav --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libxml2/2.12.7+dfsg-3 libxml-libxml-perl/2.0207+dfsg+really+2.0134-3' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos01-ppc64el-10.secgroup --name adt-oracular-ppc64el-clamav-20240603-131254-juju-7f2275-prod-proposed-migration-environment-2-b1882af3-5737-477e-9038-07e06c1d08fa --image adt/ubuntu-oracular-ppc64el-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,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 214s autopkgtest [13:16:29]: testbed dpkg architecture: ppc64el 214s autopkgtest [13:16:29]: testbed apt version: 2.9.3 214s autopkgtest [13:16:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup 215s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 215s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.4 kB] 215s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 215s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 215s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 215s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [69.7 kB] 215s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [348 kB] 216s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1392 B] 216s Fetched 964 kB in 1s (1121 kB/s) 216s Reading package lists... 219s Reading package lists... 219s Building dependency tree... 219s Reading state information... 219s Calculating upgrade... 219s The following packages will be upgraded: 219s libxml2 219s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 219s Need to get 728 kB of archives. 219s After this operation, 516 kB disk space will be freed. 219s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libxml2 ppc64el 2.12.7+dfsg-3 [728 kB] 220s Fetched 728 kB in 1s (1449 kB/s) 220s (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 ... 72606 files and directories currently installed.) 220s Preparing to unpack .../libxml2_2.12.7+dfsg-3_ppc64el.deb ... 220s Unpacking libxml2:ppc64el (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 220s Setting up libxml2:ppc64el (2.12.7+dfsg-3) ... 220s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 220s Reading package lists... 221s Building dependency tree... 221s Reading state information... 221s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 223s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 223s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 223s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 223s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 224s Reading package lists... 224s Reading package lists... 224s Building dependency tree... 224s Reading state information... 224s Calculating upgrade... 225s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 225s Reading package lists... 225s Building dependency tree... 225s Reading state information... 225s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 234s autopkgtest [13:16:49]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 234s autopkgtest [13:16:49]: @@@@@@@@@@@@@@@@@@@@ apt-source clamav 242s Get:1 http://ftpmaster.internal/ubuntu oracular/main clamav 1.0.5+dfsg-1.1ubuntu3 (dsc) [3184 B] 242s Get:2 http://ftpmaster.internal/ubuntu oracular/main clamav 1.0.5+dfsg-1.1ubuntu3 (tar) [25.8 MB] 242s Get:3 http://ftpmaster.internal/ubuntu oracular/main clamav 1.0.5+dfsg-1.1ubuntu3 (diff) [228 kB] 242s gpgv: Signature made Sun Mar 31 17:48:59 2024 UTC 242s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 242s gpgv: issuer "steve.langasek@ubuntu.com" 242s gpgv: Can't check signature: No public key 242s dpkg-source: warning: cannot verify inline signature for ./clamav_1.0.5+dfsg-1.1ubuntu3.dsc: no acceptable signature found 245s autopkgtest [13:17:00]: testing package clamav version 1.0.5+dfsg-1.1ubuntu3 246s autopkgtest [13:17:01]: build not needed 383s autopkgtest [13:19:18]: test clamd: preparing testbed 387s Reading package lists... 387s Building dependency tree... 387s Reading state information... 387s Starting pkgProblemResolver with broken count: 0 387s Starting 2 pkgProblemResolver with broken count: 0 387s Done 388s The following additional packages will be installed: 388s clamav clamav-base clamav-daemon clamav-freshclam clamav-milter 388s libclamav11t64 libmilter1.0.1 libmspack0t64 388s Suggested packages: 388s libclamunrar clamav-docs daemon libclamunrar11 388s Recommended packages: 388s clamdscan 388s The following NEW packages will be installed: 388s autopkgtest-satdep clamav clamav-base clamav-daemon clamav-freshclam 388s clamav-milter libclamav11t64 libmilter1.0.1 libmspack0t64 388s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 388s Need to get 7403 kB/7404 kB of archives. 388s After this operation, 33.8 MB of additional disk space will be used. 388s Get:1 /tmp/autopkgtest.GTkgjY/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [724 B] 388s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el clamav-base all 1.0.5+dfsg-1.1ubuntu3 [92.6 kB] 388s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el libmspack0t64 ppc64el 0.11-1.1build1 [56.6 kB] 388s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el libclamav11t64 ppc64el 1.0.5+dfsg-1.1ubuntu3 [3992 kB] 388s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el clamav-freshclam ppc64el 1.0.5+dfsg-1.1ubuntu3 [101 kB] 388s Get:6 http://ftpmaster.internal/ubuntu oracular/main ppc64el clamav-daemon ppc64el 1.0.5+dfsg-1.1ubuntu3 [235 kB] 388s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libmilter1.0.1 ppc64el 8.18.1-3 [45.7 kB] 388s Get:8 http://ftpmaster.internal/ubuntu oracular/universe ppc64el clamav-milter ppc64el 1.0.5+dfsg-1.1ubuntu3 [121 kB] 388s Get:9 http://ftpmaster.internal/ubuntu oracular/main ppc64el clamav ppc64el 1.0.5+dfsg-1.1ubuntu3 [2759 kB] 389s Preconfiguring packages ... 389s Fetched 7403 kB in 1s (9634 kB/s) 389s Selecting previously unselected package clamav-base. 389s (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 ... 72606 files and directories currently installed.) 389s Preparing to unpack .../0-clamav-base_1.0.5+dfsg-1.1ubuntu3_all.deb ... 389s Unpacking clamav-base (1.0.5+dfsg-1.1ubuntu3) ... 389s Selecting previously unselected package libmspack0t64:ppc64el. 389s Preparing to unpack .../1-libmspack0t64_0.11-1.1build1_ppc64el.deb ... 389s Unpacking libmspack0t64:ppc64el (0.11-1.1build1) ... 389s Selecting previously unselected package libclamav11t64:ppc64el. 389s Preparing to unpack .../2-libclamav11t64_1.0.5+dfsg-1.1ubuntu3_ppc64el.deb ... 389s Unpacking libclamav11t64:ppc64el (1.0.5+dfsg-1.1ubuntu3) ... 389s Selecting previously unselected package clamav-freshclam. 389s Preparing to unpack .../3-clamav-freshclam_1.0.5+dfsg-1.1ubuntu3_ppc64el.deb ... 389s Unpacking clamav-freshclam (1.0.5+dfsg-1.1ubuntu3) ... 389s Selecting previously unselected package clamav-daemon. 389s Preparing to unpack .../4-clamav-daemon_1.0.5+dfsg-1.1ubuntu3_ppc64el.deb ... 389s Unpacking clamav-daemon (1.0.5+dfsg-1.1ubuntu3) ... 389s Selecting previously unselected package libmilter1.0.1:ppc64el. 389s Preparing to unpack .../5-libmilter1.0.1_8.18.1-3_ppc64el.deb ... 389s Unpacking libmilter1.0.1:ppc64el (8.18.1-3) ... 389s Selecting previously unselected package clamav-milter. 389s Preparing to unpack .../6-clamav-milter_1.0.5+dfsg-1.1ubuntu3_ppc64el.deb ... 389s Unpacking clamav-milter (1.0.5+dfsg-1.1ubuntu3) ... 389s Selecting previously unselected package clamav. 389s Preparing to unpack .../7-clamav_1.0.5+dfsg-1.1ubuntu3_ppc64el.deb ... 389s Unpacking clamav (1.0.5+dfsg-1.1ubuntu3) ... 389s Selecting previously unselected package autopkgtest-satdep. 389s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 389s Unpacking autopkgtest-satdep (0) ... 389s Setting up libmspack0t64:ppc64el (0.11-1.1build1) ... 389s Setting up libmilter1.0.1:ppc64el (8.18.1-3) ... 389s Setting up libclamav11t64:ppc64el (1.0.5+dfsg-1.1ubuntu3) ... 389s Setting up clamav-base (1.0.5+dfsg-1.1ubuntu3) ... 389s Setting up clamav-freshclam (1.0.5+dfsg-1.1ubuntu3) ... 390s Setting up clamav-daemon (1.0.5+dfsg-1.1ubuntu3) ... 392s Created symlink /etc/systemd/system/multi-user.target.wants/clamav-daemon.service → /usr/lib/systemd/system/clamav-daemon.service. 392s Created symlink /etc/systemd/system/sockets.target.wants/clamav-daemon.socket → /usr/lib/systemd/system/clamav-daemon.socket. 392s Setting up clamav (1.0.5+dfsg-1.1ubuntu3) ... 392s Setting up clamav-milter (1.0.5+dfsg-1.1ubuntu3) ... 693s Job for clamav-milter.service failed because a timeout was exceeded. 693s See "systemctl status clamav-milter.service" and "journalctl -xeu clamav-milter.service" for details. 693s invoke-rc.d: initscript clamav-milter, action "start" failed. 693s × clamav-milter.service - LSB: ClamAV virus milter 693s Loaded: loaded (]8;;file://autopkgtest/etc/init.d/clamav-milter/etc/init.d/clamav-milter]8;;; generated) 693s Active: failed (Result: timeout) since Mon 2024-06-03 13:24:28 UTC; 13ms ago 693s Docs: ]8;;man:systemd-sysv-generator(8)man:systemd-sysv-generator(8)]8;; 693s Process: 3027 ExecStart=/etc/init.d/clamav-milter start (code=killed, signal=TERM) 693s Tasks: 7 (limit: 1623) 693s Memory: 5.4M (peak: 12.0M) 693s CPU: 147ms 693s CGroup: /system.slice/clamav-milter.service 693s ├─3152 /usr/sbin/clamav-milter --config-file=/etc/clamav/clamav-milter.conf 693s └─3153 /usr/sbin/clamav-milter --config-file=/etc/clamav/clamav-milter.conf 693s 693s Jun 03 13:19:28 autopkgtest systemd[1]: Starting clamav-milter.service - LSB: ClamAV virus milter... 693s Jun 03 13:19:28 autopkgtest clamav-milter[3142]: touch: cannot touch '/var/run/clamav/clamav-milter.pid': No such file or directory 693s Jun 03 13:19:28 autopkgtest clamav-milter[3143]: chown: cannot access '/var/run/clamav/clamav-milter.pid': No such file or directory 693s Jun 03 13:19:28 autopkgtest clamav-milter[3027]: * Starting Sendmail milter plugin for ClamAV clamav-milter 693s Jun 03 13:19:28 autopkgtest clamav-milter[3153]: WARNING: Mon Jun 3 13:19:28 2024 -> No clamd server appears to be available 693s Jun 03 13:24:28 autopkgtest systemd[1]: clamav-milter.service: start operation timed out. Terminating. 693s Jun 03 13:24:28 autopkgtest systemd[1]: clamav-milter.service: Failed with result 'timeout'. 693s Jun 03 13:24:28 autopkgtest systemd[1]: clamav-milter.service: Unit process 3152 (clamav-milter) remains running after unit stopped. 693s Jun 03 13:24:28 autopkgtest systemd[1]: clamav-milter.service: Unit process 3153 (clamav-milter) remains running after unit stopped. 693s Jun 03 13:24:28 autopkgtest systemd[1]: Failed to start clamav-milter.service - LSB: ClamAV virus milter. 693s dpkg: error processing package clamav-milter (--configure): 693s installed clamav-milter package post-installation script subprocess returned error exit status 1 693s dpkg: dependency problems prevent configuration of autopkgtest-satdep: 693s autopkgtest-satdep depends on clamav-milter; however: 693s Package clamav-milter is not configured yet. 693s 693s dpkg: error processing package autopkgtest-satdep (--configure): 693s dependency problems - leaving unconfigured 693s Processing triggers for man-db (2.12.1-1) ... 693s No apport report written because the error message indicates its a followup error from a previous failure. 694s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 694s Errors were encountered while processing: 694s clamav-milter 694s autopkgtest-satdep 694s E: Sub-process /usr/bin/dpkg returned an error code (1) 694s autopkgtest: WARNING: Test dependencies are unsatisfiable with using apt pinning. Retrying with using all packages from oracular-proposed 695s Reading package lists... 695s Building dependency tree... 695s Reading state information... 695s Starting pkgProblemResolver with broken count: 0 696s Starting 2 pkgProblemResolver with broken count: 0 696s Done 696s autopkgtest-satdep is already the newest version (0). 696s 0 upgraded, 0 newly installed, 0 to remove and 32 not upgraded. 696s 2 not fully installed or removed. 696s After this operation, 0 B of additional disk space will be used. 696s Setting up clamav-milter (1.0.5+dfsg-1.1ubuntu3) ... 697s Setting up autopkgtest-satdep (0) ... 700s (Reading database ... 72737 files and directories currently installed.) 700s Removing autopkgtest-satdep (0) ... 702s autopkgtest [13:24:37]: test clamd: [----------------------- 703s OK 703s autopkgtest [13:24:38]: test clamd: -----------------------] 704s autopkgtest [13:24:39]: test clamd: - - - - - - - - - - results - - - - - - - - - - 704s clamd PASS 704s autopkgtest [13:24:39]: test client: preparing testbed 706s Reading package lists... 707s Building dependency tree... 707s Reading state information... 707s Starting pkgProblemResolver with broken count: 0 707s Starting 2 pkgProblemResolver with broken count: 0 707s Done 707s The following NEW packages will be installed: 707s autopkgtest-satdep 707s 0 upgraded, 1 newly installed, 0 to remove and 32 not upgraded. 707s Need to get 0 B/720 B of archives. 707s After this operation, 0 B of additional disk space will be used. 707s Get:1 /tmp/autopkgtest.GTkgjY/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 708s Selecting previously unselected package autopkgtest-satdep. 708s (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 ... 72737 files and directories currently installed.) 708s Preparing to unpack .../2-autopkgtest-satdep.deb ... 708s Unpacking autopkgtest-satdep (0) ... 708s Setting up autopkgtest-satdep (0) ... 711s (Reading database ... 72737 files and directories currently installed.) 711s Removing autopkgtest-satdep (0) ... 712s autopkgtest [13:24:47]: test client: [----------------------- 713s autopkgtest [13:24:48]: test client: -----------------------] 713s autopkgtest [13:24:48]: test client: - - - - - - - - - - results - - - - - - - - - - 713s client PASS 714s autopkgtest [13:24:49]: test milter: preparing testbed 716s Reading package lists... 716s Building dependency tree... 716s Reading state information... 717s Starting pkgProblemResolver with broken count: 0 717s Starting 2 pkgProblemResolver with broken count: 0 717s Done 717s The following NEW packages will be installed: 717s autopkgtest-satdep 717s 0 upgraded, 1 newly installed, 0 to remove and 32 not upgraded. 717s Need to get 0 B/724 B of archives. 717s After this operation, 0 B of additional disk space will be used. 717s Get:1 /tmp/autopkgtest.GTkgjY/3-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [724 B] 717s Selecting previously unselected package autopkgtest-satdep. 717s (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 ... 72737 files and directories currently installed.) 717s Preparing to unpack .../3-autopkgtest-satdep.deb ... 717s Unpacking autopkgtest-satdep (0) ... 717s Setting up autopkgtest-satdep (0) ... 720s (Reading database ... 72737 files and directories currently installed.) 720s Removing autopkgtest-satdep (0) ... 721s autopkgtest [13:24:56]: test milter: [----------------------- 721s OK 722s autopkgtest [13:24:57]: test milter: -----------------------] 722s milter PASS 722s autopkgtest [13:24:57]: test milter: - - - - - - - - - - results - - - - - - - - - - 723s autopkgtest [13:24:58]: @@@@@@@@@@@@@@@@@@@@ summary 723s clamd PASS 723s client PASS 723s milter PASS 738s Using flock in scalingstack-bos01-ppc64el 738s Creating nova instance adt-oracular-ppc64el-clamav-20240603-131254-juju-7f2275-prod-proposed-migration-environment-2-b1882af3-5737-477e-9038-07e06c1d08fa from image adt/ubuntu-oracular-ppc64el-server-20240603.img (UUID 631d6369-5b04-4fc9-bdfe-727e63df0ef9)...