0s autopkgtest [15:04:56]: starting date and time: 2024-06-26 15:04:56+0000 0s autopkgtest [15:04:56]: git checkout: 85adf9a1 setup-testbed: prevent /tmp from getting a tmpfs mount 0s autopkgtest [15:04:56]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gnro60xm/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:containerd-app --apt-upgrade containerd-app --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=containerd-app/1.7.17-0ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@lcy02-59.secgroup --name adt-oracular-amd64-containerd-app-20240626-150456-juju-7f2275-prod-proposed-migration-environment-3-10359b1e-896f-4787-ae39-1c2098b40676 --image adt/ubuntu-oracular-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --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://ftpmaster.internal/ubuntu/ 45s autopkgtest [15:05:41]: testbed dpkg architecture: amd64 45s autopkgtest [15:05:41]: testbed apt version: 2.9.5 45s autopkgtest [15:05:41]: @@@@@@@@@@@@@@@@@@@@ test bed setup 46s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 46s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [46.3 kB] 46s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [7052 B] 46s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2452 B] 46s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [392 kB] 46s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main i386 Packages [66.1 kB] 46s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 Packages [84.8 kB] 46s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 Packages [28.9 kB] 46s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted i386 Packages [6732 B] 46s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 Packages [379 kB] 46s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe i386 Packages [181 kB] 46s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 Packages [8196 B] 46s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse i386 Packages [3676 B] 46s Fetched 1316 kB in 0s (5504 kB/s) 46s Reading package lists... 47s Reading package lists... 48s Building dependency tree... 48s Reading state information... 48s Calculating upgrade... 48s The following packages will be upgraded: 48s e2fsprogs e2fsprogs-l10n hwdata libcom-err2 libext2fs2t64 libss2 logsave 48s sensible-utils wget xfsprogs 48s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 48s Need to get 2222 kB of archives. 48s After this operation, 113 kB of additional disk space will be used. 48s Get:1 http://ftpmaster.internal/ubuntu oracular/main amd64 e2fsprogs-l10n all 1.47.1-1ubuntu1 [6798 B] 48s Get:2 http://ftpmaster.internal/ubuntu oracular/main amd64 logsave amd64 1.47.1-1ubuntu1 [25.4 kB] 48s Get:3 http://ftpmaster.internal/ubuntu oracular/main amd64 libext2fs2t64 amd64 1.47.1-1ubuntu1 [224 kB] 48s Get:4 http://ftpmaster.internal/ubuntu oracular/main amd64 e2fsprogs amd64 1.47.1-1ubuntu1 [608 kB] 48s Get:5 http://ftpmaster.internal/ubuntu oracular/main amd64 libcom-err2 amd64 1.47.1-1ubuntu1 [25.6 kB] 48s Get:6 http://ftpmaster.internal/ubuntu oracular/main amd64 libss2 amd64 1.47.1-1ubuntu1 [17.8 kB] 48s Get:7 http://ftpmaster.internal/ubuntu oracular/main amd64 sensible-utils all 0.0.24 [24.8 kB] 48s Get:8 http://ftpmaster.internal/ubuntu oracular/main amd64 wget amd64 1.24.5-1ubuntu2 [349 kB] 48s Get:9 http://ftpmaster.internal/ubuntu oracular/main amd64 hwdata all 0.383-1 [29.3 kB] 48s Get:10 http://ftpmaster.internal/ubuntu oracular/main amd64 xfsprogs amd64 6.8.0-2.2 [911 kB] 49s Fetched 2222 kB in 0s (25.9 MB/s) 49s (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 ... 74417 files and directories currently installed.) 49s Preparing to unpack .../e2fsprogs-l10n_1.47.1-1ubuntu1_all.deb ... 49s Unpacking e2fsprogs-l10n (1.47.1-1ubuntu1) over (1.47.0-2.4~exp1ubuntu4) ... 49s Preparing to unpack .../logsave_1.47.1-1ubuntu1_amd64.deb ... 49s Unpacking logsave (1.47.1-1ubuntu1) over (1.47.0-2.4~exp1ubuntu4) ... 49s Preparing to unpack .../libext2fs2t64_1.47.1-1ubuntu1_amd64.deb ... 49s Leaving 'diversion of /lib/x86_64-linux-gnu/libe2p.so.2 to /lib/x86_64-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 49s Leaving 'diversion of /lib/x86_64-linux-gnu/libe2p.so.2.3 to /lib/x86_64-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 49s Leaving 'diversion of /lib/x86_64-linux-gnu/libext2fs.so.2 to /lib/x86_64-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 49s Leaving 'diversion of /lib/x86_64-linux-gnu/libext2fs.so.2.4 to /lib/x86_64-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 49s Unpacking libext2fs2t64:amd64 (1.47.1-1ubuntu1) over (1.47.0-2.4~exp1ubuntu4) ... 49s Setting up libext2fs2t64:amd64 (1.47.1-1ubuntu1) ... 49s (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 ... 74417 files and directories currently installed.) 49s Preparing to unpack .../0-e2fsprogs_1.47.1-1ubuntu1_amd64.deb ... 49s Unpacking e2fsprogs (1.47.1-1ubuntu1) over (1.47.0-2.4~exp1ubuntu4) ... 49s Preparing to unpack .../1-libcom-err2_1.47.1-1ubuntu1_amd64.deb ... 49s Unpacking libcom-err2:amd64 (1.47.1-1ubuntu1) over (1.47.0-2.4~exp1ubuntu4) ... 49s Preparing to unpack .../2-libss2_1.47.1-1ubuntu1_amd64.deb ... 49s Unpacking libss2:amd64 (1.47.1-1ubuntu1) over (1.47.0-2.4~exp1ubuntu4) ... 49s Preparing to unpack .../3-sensible-utils_0.0.24_all.deb ... 49s Unpacking sensible-utils (0.0.24) over (0.0.23) ... 49s Preparing to unpack .../4-wget_1.24.5-1ubuntu2_amd64.deb ... 49s Unpacking wget (1.24.5-1ubuntu2) over (1.24.5-1ubuntu1) ... 49s Preparing to unpack .../5-hwdata_0.383-1_all.deb ... 49s Unpacking hwdata (0.383-1) over (0.380-1) ... 49s Preparing to unpack .../6-xfsprogs_6.8.0-2.2_amd64.deb ... 49s Unpacking xfsprogs (6.8.0-2.2) over (6.8.0-2.1) ... 49s Setting up wget (1.24.5-1ubuntu2) ... 49s Setting up libcom-err2:amd64 (1.47.1-1ubuntu1) ... 49s Setting up hwdata (0.383-1) ... 49s Setting up libss2:amd64 (1.47.1-1ubuntu1) ... 49s Setting up xfsprogs (6.8.0-2.2) ... 49s update-initramfs: deferring update (trigger activated) 50s Setting up logsave (1.47.1-1ubuntu1) ... 50s Setting up sensible-utils (0.0.24) ... 50s Setting up e2fsprogs (1.47.1-1ubuntu1) ... 50s Installing new version of config file /etc/cron.d/e2scrub_all ... 50s Installing new version of config file /etc/mke2fs.conf ... 50s update-initramfs: deferring update (trigger activated) 50s Setting up e2fsprogs-l10n (1.47.1-1ubuntu1) ... 50s Processing triggers for man-db (2.12.1-2) ... 52s Processing triggers for install-info (7.1-3build2) ... 52s Processing triggers for libc-bin (2.39-0ubuntu9) ... 52s Processing triggers for initramfs-tools (0.142ubuntu28) ... 52s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 52s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 60s Reading package lists... 61s Building dependency tree... 61s Reading state information... 61s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 61s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 61s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 61s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 61s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 62s Reading package lists... 62s Reading package lists... 63s Building dependency tree... 63s Reading state information... 63s Calculating upgrade... 63s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 63s Reading package lists... 63s Building dependency tree... 63s Reading state information... 64s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 64s autopkgtest [15:06:00]: rebooting testbed after setup commands that affected boot 67s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 78s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 82s autopkgtest [15:06:18]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 00:40:06 UTC 2024 83s autopkgtest [15:06:19]: @@@@@@@@@@@@@@@@@@@@ apt-source containerd-app 84s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main containerd-app 1.7.17-0ubuntu1 (dsc) [2177 B] 84s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main containerd-app 1.7.17-0ubuntu1 (tar) [9901 kB] 84s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main containerd-app 1.7.17-0ubuntu1 (diff) [20.3 kB] 85s gpgv: Signature made Tue Jun 4 20:37:28 2024 UTC 85s gpgv: using RSA key 8ED6C3F8BAC9DB7FC130A870F823A2729883C97C 85s gpgv: issuer "kanashiro@ubuntu.com" 85s gpgv: Can't check signature: No public key 85s dpkg-source: warning: cannot verify inline signature for ./containerd-app_1.7.17-0ubuntu1.dsc: no acceptable signature found 85s autopkgtest [15:06:21]: testing package containerd-app version 1.7.17-0ubuntu1 85s autopkgtest [15:06:21]: build not needed 86s autopkgtest [15:06:22]: test basic-smoke: preparing testbed 87s Reading package lists... 87s Building dependency tree... 87s Reading state information... 87s Starting pkgProblemResolver with broken count: 0 87s Starting 2 pkgProblemResolver with broken count: 0 87s Done 88s The following additional packages will be installed: 88s containerd runc 88s The following NEW packages will be installed: 88s autopkgtest-satdep containerd runc 88s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 88s Need to get 38.4 MB/38.4 MB of archives. 88s After this operation, 145 MB of additional disk space will be used. 88s Get:1 /tmp/autopkgtest.DrLEYJ/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [716 B] 88s Get:2 http://ftpmaster.internal/ubuntu oracular/main amd64 runc amd64 1.1.12-0ubuntu3 [8599 kB] 88s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 containerd amd64 1.7.17-0ubuntu1 [29.8 MB] 89s Fetched 38.4 MB in 1s (44.9 MB/s) 89s Selecting previously unselected package runc. 89s (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 ... 74417 files and directories currently installed.) 89s Preparing to unpack .../runc_1.1.12-0ubuntu3_amd64.deb ... 89s Unpacking runc (1.1.12-0ubuntu3) ... 89s Selecting previously unselected package containerd. 89s Preparing to unpack .../containerd_1.7.17-0ubuntu1_amd64.deb ... 89s Unpacking containerd (1.7.17-0ubuntu1) ... 90s Selecting previously unselected package autopkgtest-satdep. 90s Preparing to unpack .../1-autopkgtest-satdep.deb ... 90s Unpacking autopkgtest-satdep (0) ... 90s Setting up runc (1.1.12-0ubuntu3) ... 90s Setting up containerd (1.7.17-0ubuntu1) ... 90s Created symlink /etc/systemd/system/multi-user.target.wants/containerd.service → /usr/lib/systemd/system/containerd.service. 90s Setting up autopkgtest-satdep (0) ... 90s Processing triggers for man-db (2.12.1-2) ... 93s (Reading database ... 74480 files and directories currently installed.) 93s Removing autopkgtest-satdep (0) ... 94s autopkgtest [15:06:30]: test basic-smoke: [----------------------- 94s + ctr images pull docker.io/library/busybox:latest 94s + containerd 94s time="2024-06-26T15:06:30.316720795Z" level=info msg="starting containerd" revision= version=1.7.17 94s time="2024-06-26T15:06:30.326132905Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 94s time="2024-06-26T15:06:30.326184895Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 94s time="2024-06-26T15:06:30.326372105Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 94s time="2024-06-26T15:06:30.326398955Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 94s time="2024-06-26T15:06:30.326528035Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 94s time="2024-06-26T15:06:30.326949785Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 94s time="2024-06-26T15:06:30.327045065Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327116075Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327129445Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327379435Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327401985Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327471205Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327483415Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327542195Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 94s time="2024-06-26T15:06:30.327685635Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 94s time="2024-06-26T15:06:30.327706945Z" level=info msg="metadata content store policy set" policy=shared 104s time="2024-06-26T15:06:40.328230923Z" level=warning msg="waiting for response from boltdb open" plugin=bolt 124s ctr: rpc error: code = Unknown desc = failed to resolve image: failed to do request: Head "https://registry-1.docker.io/v2/library/busybox/manifests/latest": dial tcp 54.196.99.49:443: i/o timeout 124s /tmp/autopkgtest.DrLEYJ/wrapper.sh: Killing leaked background processes: 1420 124s PID TTY STAT TIME COMMAND 124s 1420 ? Sl 0:00 containerd 124s autopkgtest [15:07:00]: test basic-smoke: -----------------------] 124s autopkgtest [15:07:00]: test basic-smoke: - - - - - - - - - - results - - - - - - - - - - 124s basic-smoke FAIL non-zero exit status 1 124s autopkgtest [15:07:00]: @@@@@@@@@@@@@@@@@@@@ summary 124s basic-smoke FAIL non-zero exit status 1 135s nova [W] Skipping flock for amd64 135s Creating nova instance adt-oracular-amd64-containerd-app-20240626-150456-juju-7f2275-prod-proposed-migration-environment-3-10359b1e-896f-4787-ae39-1c2098b40676 from image adt/ubuntu-oracular-amd64-server-20240626.img (UUID 54cc919f-9ac3-413d-b63d-89c2f18d44d3)...