0s autopkgtest [15:37:18]: starting date and time: 2025-01-09 15:37:18+0000 0s autopkgtest [15:37:18]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [15:37:18]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.7xdcuc3x/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:podman-compose --apt-upgrade podman-compose --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=podman-compose/1.2.0-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-10.secgroup --name adt-plucky-s390x-podman-compose-20250109-153718-juju-7f2275-prod-proposed-migration-environment-15-0a0dcd1a-ad59-43f4-aca0-3a9a091ccbff --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --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 [15:38:46]: testbed dpkg architecture: s390x 88s autopkgtest [15:38:46]: testbed apt version: 2.9.18 88s autopkgtest [15:38:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 89s autopkgtest [15:38:47]: testbed release detected to be: None 89s autopkgtest [15:38:47]: updating testbed package index (apt update) 90s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 90s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 90s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 90s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 90s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.6 kB] 90s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 90s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [790 kB] 90s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [126 kB] 90s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [216 kB] 90s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [756 B] 90s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [879 kB] 90s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [5048 B] 90s Fetched 2117 kB in 1s (2179 kB/s) 91s Reading package lists... 92s Reading package lists... 92s Building dependency tree... 92s Reading state information... 92s Calculating upgrade... 92s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s Reading package lists... 92s Building dependency tree... 92s Reading state information... 92s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s autopkgtest [15:38:50]: upgrading testbed (apt dist-upgrade and autopurge) 92s Reading package lists... 93s Building dependency tree... 93s Reading state information... 93s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 93s Starting 2 pkgProblemResolver with broken count: 0 93s Done 93s Entering ResolveByKeep 93s 93s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 94s Starting pkgProblemResolver with broken count: 0 94s Starting 2 pkgProblemResolver with broken count: 0 94s Done 94s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 96s autopkgtest [15:38:54]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 96s autopkgtest [15:38:54]: @@@@@@@@@@@@@@@@@@@@ apt-source podman-compose 98s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe podman-compose 1.2.0-2 (dsc) [2206 B] 98s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe podman-compose 1.2.0-2 (tar) [84.7 kB] 98s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe podman-compose 1.2.0-2 (diff) [4248 B] 98s gpgv: Signature made Tue Aug 20 14:06:40 2024 UTC 98s gpgv: using RSA key 439884E6862A429C290DF63B033C4CA276024834 98s gpgv: issuer "athoscribeiro@gmail.com" 98s gpgv: Can't check signature: No public key 98s dpkg-source: warning: cannot verify inline signature for ./podman-compose_1.2.0-2.dsc: no acceptable signature found 98s autopkgtest [15:38:56]: testing package podman-compose version 1.2.0-2 98s autopkgtest [15:38:56]: build not needed 99s autopkgtest [15:38:57]: test build.sh: preparing testbed 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Starting pkgProblemResolver with broken count: 0 99s Starting 2 pkgProblemResolver with broken count: 0 99s Done 99s The following NEW packages will be installed: 99s conmon containernetworking-plugins crun golang-github-containers-common 99s golang-github-containers-image libslirp0 libsubid4 libyajl2 netavark podman 99s podman-compose python3-dotenv slirp4netns uidmap 99s 0 upgraded, 14 newly installed, 0 to remove and 0 not upgraded. 99s Need to get 37.3 MB of archives. 99s After this operation, 168 MB of additional disk space will be used. 99s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x conmon s390x 2.1.12-3 [37.9 kB] 100s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x containernetworking-plugins s390x 1.1.1+ds1-3build1 [6697 kB] 100s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libyajl2 s390x 2.1.0-5build1 [22.2 kB] 100s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x crun s390x 1.18.2-1 [439 kB] 100s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x netavark s390x 1.12.1-7 [5540 kB] 100s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x golang-github-containers-image all 5.30.2-2 [33.5 kB] 100s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x golang-github-containers-common all 0.58.2+ds1-3ubuntu1 [40.9 kB] 100s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libslirp0 s390x 4.8.0-1ubuntu1 [66.3 kB] 100s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libsubid4 s390x 1:4.15.3-3ubuntu2 [26.1 kB] 100s Get:10 http://ftpmaster.internal/ubuntu plucky/universe s390x podman s390x 5.3.1+ds1-7 [24.2 MB] 101s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-dotenv all 1.0.1-1 [22.3 kB] 101s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x podman-compose all 1.2.0-2 [46.4 kB] 101s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x slirp4netns s390x 1.2.1-1build2 [36.2 kB] 101s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x uidmap s390x 1:4.15.3-3ubuntu2 [36.9 kB] 101s Fetched 37.3 MB in 2s (24.2 MB/s) 101s Selecting previously unselected package conmon. 101s (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 ... 55616 files and directories currently installed.) 101s Preparing to unpack .../00-conmon_2.1.12-3_s390x.deb ... 101s Unpacking conmon (2.1.12-3) ... 101s Selecting previously unselected package containernetworking-plugins. 101s Preparing to unpack .../01-containernetworking-plugins_1.1.1+ds1-3build1_s390x.deb ... 101s Unpacking containernetworking-plugins (1.1.1+ds1-3build1) ... 101s Selecting previously unselected package libyajl2:s390x. 101s Preparing to unpack .../02-libyajl2_2.1.0-5build1_s390x.deb ... 101s Unpacking libyajl2:s390x (2.1.0-5build1) ... 101s Selecting previously unselected package crun. 101s Preparing to unpack .../03-crun_1.18.2-1_s390x.deb ... 101s Unpacking crun (1.18.2-1) ... 101s Selecting previously unselected package netavark. 101s Preparing to unpack .../04-netavark_1.12.1-7_s390x.deb ... 101s Unpacking netavark (1.12.1-7) ... 102s Selecting previously unselected package golang-github-containers-image. 102s Preparing to unpack .../05-golang-github-containers-image_5.30.2-2_all.deb ... 102s Unpacking golang-github-containers-image (5.30.2-2) ... 102s Selecting previously unselected package golang-github-containers-common. 102s Preparing to unpack .../06-golang-github-containers-common_0.58.2+ds1-3ubuntu1_all.deb ... 102s Unpacking golang-github-containers-common (0.58.2+ds1-3ubuntu1) ... 102s Selecting previously unselected package libslirp0:s390x. 102s Preparing to unpack .../07-libslirp0_4.8.0-1ubuntu1_s390x.deb ... 102s Unpacking libslirp0:s390x (4.8.0-1ubuntu1) ... 102s Selecting previously unselected package libsubid4:s390x. 102s Preparing to unpack .../08-libsubid4_1%3a4.15.3-3ubuntu2_s390x.deb ... 102s Unpacking libsubid4:s390x (1:4.15.3-3ubuntu2) ... 102s Selecting previously unselected package podman. 102s Preparing to unpack .../09-podman_5.3.1+ds1-7_s390x.deb ... 102s Unpacking podman (5.3.1+ds1-7) ... 102s Selecting previously unselected package python3-dotenv. 102s Preparing to unpack .../10-python3-dotenv_1.0.1-1_all.deb ... 102s Unpacking python3-dotenv (1.0.1-1) ... 102s Selecting previously unselected package podman-compose. 102s Preparing to unpack .../11-podman-compose_1.2.0-2_all.deb ... 102s Unpacking podman-compose (1.2.0-2) ... 102s Selecting previously unselected package slirp4netns. 102s Preparing to unpack .../12-slirp4netns_1.2.1-1build2_s390x.deb ... 102s Unpacking slirp4netns (1.2.1-1build2) ... 102s Selecting previously unselected package uidmap. 102s Preparing to unpack .../13-uidmap_1%3a4.15.3-3ubuntu2_s390x.deb ... 102s Unpacking uidmap (1:4.15.3-3ubuntu2) ... 102s Setting up python3-dotenv (1.0.1-1) ... 102s Setting up libyajl2:s390x (2.1.0-5build1) ... 102s Setting up podman-compose (1.2.0-2) ... 102s Setting up libsubid4:s390x (1:4.15.3-3ubuntu2) ... 102s Setting up golang-github-containers-image (5.30.2-2) ... 102s Setting up conmon (2.1.12-3) ... 102s Setting up containernetworking-plugins (1.1.1+ds1-3build1) ... 102s Setting up netavark (1.12.1-7) ... 103s Created symlink '/etc/systemd/system/default.target.wants/netavark-dhcp-proxy.service' → '/usr/lib/systemd/system/netavark-dhcp-proxy.service'. 103s Created symlink '/etc/systemd/system/sockets.target.wants/netavark-dhcp-proxy.socket' → '/usr/lib/systemd/system/netavark-dhcp-proxy.socket'. 103s Created symlink '/etc/systemd/system/firewalld.service.wants/netavark-firewalld-reload.service' → '/usr/lib/systemd/system/netavark-firewalld-reload.service'. 103s Unit /usr/lib/systemd/system/netavark-firewalld-reload.service is added as a dependency to a non-existent unit firewalld.service. 103s Setting up libslirp0:s390x (4.8.0-1ubuntu1) ... 103s Setting up golang-github-containers-common (0.58.2+ds1-3ubuntu1) ... 103s Setting up slirp4netns (1.2.1-1build2) ... 103s Setting up crun (1.18.2-1) ... 103s Setting up uidmap (1:4.15.3-3ubuntu2) ... 103s Setting up podman (5.3.1+ds1-7) ... 103s Created symlink '/etc/systemd/user/default.target.wants/podman-auto-update.service' → '/usr/lib/systemd/user/podman-auto-update.service'. 103s Created symlink '/etc/systemd/user/timers.target.wants/podman-auto-update.timer' → '/usr/lib/systemd/user/podman-auto-update.timer'. 103s Created symlink '/etc/systemd/user/default.target.wants/podman-clean-transient.service' → '/usr/lib/systemd/user/podman-clean-transient.service'. 103s Created symlink '/etc/systemd/user/default.target.wants/podman-restart.service' → '/usr/lib/systemd/user/podman-restart.service'. 103s Created symlink '/etc/systemd/user/default.target.wants/podman.service' → '/usr/lib/systemd/user/podman.service'. 104s Created symlink '/etc/systemd/user/sockets.target.wants/podman.socket' → '/usr/lib/systemd/user/podman.socket'. 104s Created symlink '/etc/systemd/system/default.target.wants/podman-auto-update.service' → '/usr/lib/systemd/system/podman-auto-update.service'. 104s Created symlink '/etc/systemd/system/timers.target.wants/podman-auto-update.timer' → '/usr/lib/systemd/system/podman-auto-update.timer'. 104s Created symlink '/etc/systemd/system/default.target.wants/podman-clean-transient.service' → '/usr/lib/systemd/system/podman-clean-transient.service'. 104s Created symlink '/etc/systemd/system/default.target.wants/podman-restart.service' → '/usr/lib/systemd/system/podman-restart.service'. 104s Created symlink '/etc/systemd/system/default.target.wants/podman.service' → '/usr/lib/systemd/system/podman.service'. 104s Created symlink '/etc/systemd/system/sockets.target.wants/podman.socket' → '/usr/lib/systemd/system/podman.socket'. 105s Processing triggers for libc-bin (2.40-4ubuntu1) ... 105s Processing triggers for man-db (2.13.0-1) ... 107s autopkgtest [15:39:05]: test build.sh: [----------------------- 108s STEP 1/8: FROM busybox 108s Resolved "busybox" as an alias (/etc/containers/registries.conf.d/shortnames.conf) 108s Trying to pull docker.io/library/busybox:latest... 108s STEP 1/3: FROM busybox 108s STEP 1/8: FROM busybox 108s Resolved "busybox" as an alias (/etc/containers/registries.conf.d/shortnames.conf) 108s Trying to pull docker.io/library/busybox:latest... 108s Resolved "busybox" as an alias (/etc/containers/registries.conf.d/shortnames.conf) 108s Trying to pull docker.io/library/busybox:latest... 142s time="2025-01-09T15:39:40Z" level=warning msg="Failed, retrying in 2s ... (1/3). Error: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 142s time="2025-01-09T15:39:40Z" level=warning msg="Failed, retrying in 2s ... (1/3). Error: copying system image from manifest list: determining manifest MIME type for docker://busybox:latest: reading manifest sha256:1560bf2a8a1d87d1474ed6cb0f1b9cceb6dceced705af18c4b274402360e7615 in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 142s time="2025-01-09T15:39:40Z" level=warning msg="Failed, retrying in 2s ... (1/3). Error: copying system image from manifest list: determining manifest MIME type for docker://busybox:latest: reading manifest sha256:1560bf2a8a1d87d1474ed6cb0f1b9cceb6dceced705af18c4b274402360e7615 in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 178s time="2025-01-09T15:40:15Z" level=warning msg="Failed, retrying in 2s ... (2/3). Error: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 178s time="2025-01-09T15:40:15Z" level=warning msg="Failed, retrying in 2s ... (2/3). Error: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 178s time="2025-01-09T15:40:15Z" level=warning msg="Failed, retrying in 2s ... (2/3). Error: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 213s time="2025-01-09T15:40:51Z" level=warning msg="Failed, retrying in 2s ... (3/3). Error: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 213s time="2025-01-09T15:40:51Z" level=warning msg="Failed, retrying in 2s ... (3/3). Error: copying system image from manifest list: determining manifest MIME type for docker://busybox:latest: reading manifest sha256:1560bf2a8a1d87d1474ed6cb0f1b9cceb6dceced705af18c4b274402360e7615 in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 213s time="2025-01-09T15:40:51Z" level=warning msg="Failed, retrying in 2s ... (3/3). Error: copying system image from manifest list: determining manifest MIME type for docker://busybox:latest: reading manifest sha256:1560bf2a8a1d87d1474ed6cb0f1b9cceb6dceced705af18c4b274402360e7615 in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit" 248s Error: creating build container: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit 249s Error: creating build container: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit 249s Error: creating build container: initializing source docker://busybox:latest: reading manifest latest in docker.io/library/busybox: toomanyrequests: You have reached your pull rate limit. You may increase the limit by authenticating and upgrading: https://www.docker.com/increase-rate-limit 249s autopkgtest [15:41:27]: test build.sh: -----------------------] 249s autopkgtest [15:41:27]: test build.sh: - - - - - - - - - - results - - - - - - - - - - 249s build.sh FAIL non-zero exit status 125 250s autopkgtest [15:41:28]: @@@@@@@@@@@@@@@@@@@@ summary 250s build.sh FAIL non-zero exit status 125 269s nova [W] Using flock in prodstack6-s390x 269s Creating nova instance adt-plucky-s390x-podman-compose-20250109-153718-juju-7f2275-prod-proposed-migration-environment-15-0a0dcd1a-ad59-43f4-aca0-3a9a091ccbff from image adt/ubuntu-plucky-s390x-server-20250109.img (UUID b7153508-076e-4829-bc45-d48f5a74e566)... 269s nova [W] Timed out waiting for 05e12609-93d6-4a5f-b4f3-e4b485e6478a to get deleted.