0s autopkgtest [15:35:23]: starting date and time: 2024-03-21 15:35:23+0000 0s autopkgtest [15:35:23]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [15:35:23]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.e_ghj3e0/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:db5.3 --apt-upgrade c-icap --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=db5.3/5.3.28+dfsg2-6 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-10.secgroup --name adt-noble-ppc64el-c-icap-20240321-153523-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 144s autopkgtest [15:37:47]: testbed dpkg architecture: ppc64el 144s autopkgtest [15:37:47]: testbed apt version: 2.7.12 144s autopkgtest [15:37:47]: @@@@@@@@@@@@@@@@@@@@ test bed setup 145s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 146s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 146s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 146s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 146s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3757 kB] 150s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [668 kB] 150s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 150s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 150s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 150s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4078 kB] 154s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 154s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [58.3 kB] 154s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 157s Fetched 9248 kB in 9s (1001 kB/s) 157s Reading package lists... 160s Reading package lists... 160s Building dependency tree... 160s Reading state information... 160s Calculating upgrade... 160s The following packages will be upgraded: 160s debianutils linux-firmware 161s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 161s Need to get 476 MB of archives. 161s After this operation, 5724 kB of additional disk space will be used. 161s Get:1 http://ftpmaster.internal/ubuntu noble/main ppc64el debianutils ppc64el 5.17 [90.4 kB] 161s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el linux-firmware ppc64el 20240318.git3b128b60-0ubuntu1 [475 MB] 183s Fetched 476 MB in 22s (21.7 MB/s) 183s (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 ... 70095 files and directories currently installed.) 183s Preparing to unpack .../debianutils_5.17_ppc64el.deb ... 183s Unpacking debianutils (5.17) over (5.16) ... 183s Setting up debianutils (5.17) ... 183s (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 ... 70095 files and directories currently installed.) 183s Preparing to unpack .../linux-firmware_20240318.git3b128b60-0ubuntu1_ppc64el.deb ... 183s Unpacking linux-firmware (20240318.git3b128b60-0ubuntu1) over (20240202.git36777504-0ubuntu1) ... 186s Setting up linux-firmware (20240318.git3b128b60-0ubuntu1) ... 186s Processing triggers for man-db (2.12.0-3) ... 187s Processing triggers for initramfs-tools (0.142ubuntu20) ... 187s update-initramfs: Generating /boot/initrd.img-6.8.0-11-generic 187s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 193s Reading package lists... 193s Building dependency tree... 193s Reading state information... 193s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 194s sh: Attempting to set up Debian/Ubuntu apt sources automatically 194s sh: Distribution appears to be Ubuntu 195s Reading package lists... 195s Building dependency tree... 195s Reading state information... 195s eatmydata is already the newest version (131-1). 195s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 195s Reading package lists... 195s Building dependency tree... 195s Reading state information... 195s dbus is already the newest version (1.14.10-4ubuntu1). 195s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 195s Reading package lists... 196s Building dependency tree... 196s Reading state information... 196s rng-tools-debian is already the newest version (2.4). 196s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 196s Reading package lists... 196s Building dependency tree... 196s Reading state information... 196s The following packages will be REMOVED: 196s cloud-init* python3-configobj* python3-debconf* 196s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 196s After this operation, 3252 kB disk space will be freed. 196s (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 ... 70157 files and directories currently installed.) 196s Removing cloud-init (24.1.1-0ubuntu1) ... 197s Removing python3-configobj (5.0.8-3) ... 197s Removing python3-debconf (1.5.86) ... 197s Processing triggers for man-db (2.12.0-3) ... 198s (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 ... 69768 files and directories currently installed.) 198s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 198s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 198s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 198s invoke-rc.d: policy-rc.d denied execution of try-restart. 198s Reading package lists... 198s Building dependency tree... 198s Reading state information... 199s linux-generic is already the newest version (6.8.0-11.11+1). 199s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 199s Get:1 http://ftpmaster.internal/ubuntu noble InRelease [255 kB] 200s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 200s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 200s Get:4 http://ftpmaster.internal/ubuntu noble/main Sources [1384 kB] 201s Get:5 http://ftpmaster.internal/ubuntu noble/universe Sources [19.8 MB] 201s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el Packages [1377 kB] 201s Get:7 http://ftpmaster.internal/ubuntu noble/universe ppc64el Packages [15.4 MB] 208s Fetched 38.2 MB in 8s (4903 kB/s) 209s Reading package lists... 209s Reading package lists... 209s Building dependency tree... 209s Reading state information... 209s Calculating upgrade... 209s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 209s Reading package lists... 209s Building dependency tree... 209s Reading state information... 210s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 210s autopkgtest [15:38:53]: rebooting testbed after setup commands that affected boot 377s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 384s autopkgtest [15:41:47]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 387s autopkgtest [15:41:50]: @@@@@@@@@@@@@@@@@@@@ apt-source c-icap 390s Get:1 http://ftpmaster.internal/ubuntu noble/universe c-icap 1:0.5.10-6 (dsc) [2064 B] 390s Get:2 http://ftpmaster.internal/ubuntu noble/universe c-icap 1:0.5.10-6 (tar) [695 kB] 390s Get:3 http://ftpmaster.internal/ubuntu noble/universe c-icap 1:0.5.10-6 (diff) [18.4 kB] 390s gpgv: Signature made Sat Aug 19 22:07:21 2023 UTC 390s gpgv: using RSA key 7E7729476D87D6F11D91ACCBC293E7B461825ACE 390s gpgv: Can't check signature: No public key 390s dpkg-source: warning: cannot verify inline signature for ./c-icap_0.5.10-6.dsc: no acceptable signature found 390s autopkgtest [15:41:53]: testing package c-icap version 1:0.5.10-6 391s autopkgtest [15:41:54]: build not needed 392s autopkgtest [15:41:55]: test basic: preparing testbed 400s Reading package lists... 400s Building dependency tree... 400s Reading state information... 400s Starting pkgProblemResolver with broken count: 0 400s Starting 2 pkgProblemResolver with broken count: 0 400s Done 400s The following additional packages will be installed: 400s c-icap libicapapi5 400s Suggested packages: 400s libc-icap-module squid 400s The following NEW packages will be installed: 400s autopkgtest-satdep c-icap libicapapi5 400s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 400s Need to get 231 kB/231 kB of archives. 400s After this operation, 1302 kB of additional disk space will be used. 400s Get:1 /tmp/autopkgtest.M5F5cH/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [712 B] 400s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el libicapapi5 ppc64el 1:0.5.10-6 [116 kB] 401s Get:3 http://ftpmaster.internal/ubuntu noble/universe ppc64el c-icap ppc64el 1:0.5.10-6 [115 kB] 401s Fetched 231 kB in 1s (423 kB/s) 401s Selecting previously unselected package libicapapi5:ppc64el. 401s (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 ... 69713 files and directories currently installed.) 401s Preparing to unpack .../libicapapi5_1%3a0.5.10-6_ppc64el.deb ... 401s Unpacking libicapapi5:ppc64el (1:0.5.10-6) ... 401s Selecting previously unselected package c-icap. 401s Preparing to unpack .../c-icap_1%3a0.5.10-6_ppc64el.deb ... 401s Unpacking c-icap (1:0.5.10-6) ... 401s Selecting previously unselected package autopkgtest-satdep. 401s Preparing to unpack .../1-autopkgtest-satdep.deb ... 401s Unpacking autopkgtest-satdep (0) ... 401s Setting up libicapapi5:ppc64el (1:0.5.10-6) ... 401s Setting up c-icap (1:0.5.10-6) ... 402s Setting up autopkgtest-satdep (0) ... 402s Processing triggers for man-db (2.12.0-3) ... 402s Processing triggers for libc-bin (2.39-0ubuntu2) ... 404s (Reading database ... 69748 files and directories currently installed.) 404s Removing autopkgtest-satdep (0) ... 405s autopkgtest [15:42:08]: test basic: [----------------------- 405s Enabling c-icap 405s Synchronizing state of c-icap.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 405s Executing: /usr/lib/systemd/systemd-sysv-install enable c-icap 405s Created symlink /etc/systemd/system/multi-user.target.wants/c-icap.service → /usr/lib/systemd/system/c-icap.service. 406s Starting c-icap 406s ● c-icap.service - ICAP server 406s Loaded: loaded (/usr/lib/systemd/system/c-icap.service; enabled; preset: enabled) 406s Active: active (running) since Thu 2024-03-21 15:42:08 UTC; 8ms ago 406s Process: 1500 ExecStartPre=/bin/mkdir -p /run/c-icap (code=exited, status=0/SUCCESS) 406s Process: 1501 ExecStartPre=/bin/chown c-icap:c-icap /run/c-icap (code=exited, status=0/SUCCESS) 406s Process: 1504 ExecStart=/usr/bin/c-icap (code=exited, status=0/SUCCESS) 406s Main PID: 1505 (c-icap) 406s Tasks: 37 (limit: 1623) 406s Memory: 8.6M (peak: 10.0M) 406s CPU: 7ms 406s CGroup: /system.slice/c-icap.service 406s ├─1505 /usr/bin/c-icap 406s ├─1508 /usr/bin/c-icap 406s ├─1509 /usr/bin/c-icap 406s └─1510 /usr/bin/c-icap 406s 406s Mar 21 15:42:08 autopkgtest systemd[1]: Starting c-icap.service - ICAP server... 406s Mar 21 15:42:08 autopkgtest systemd[1]: Started c-icap.service - ICAP server. 406s Testing c-icap 406s Testing echo service (Echo demo service) 407s autopkgtest [15:42:10]: test basic: -----------------------] 407s basic PASS 407s autopkgtest [15:42:10]: test basic: - - - - - - - - - - results - - - - - - - - - - 407s autopkgtest [15:42:10]: @@@@@@@@@@@@@@@@@@@@ summary 407s basic PASS 419s Creating nova instance adt-noble-ppc64el-c-icap-20240321-153523-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240321.img (UUID f9947641-425a-45c2-937a-6db4500fbfac)...