0s autopkgtest: DBG: testbed init
  0s autopkgtest [03:30:13]: starting date and time: 2025-02-19 03:30:13+0000
  0s autopkgtest [03:30:13]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production'
  0s autopkgtest [03:30:13]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ksav7miw/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ncurses --apt-upgrade tmux --debug --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=ncurses/6.5+20250216-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-5.secgroup --name adt-plucky-s390x-tmux-20250219-033013-juju-7f2275-prod-proposed-migration-environment-15-d06266c2-893e-4c1f-9231-bf5759d9ea4a --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/
  0s autopkgtest: DBG: got reply from testbed: ok
  0s autopkgtest: DBG: testbed open, scratch=None
  0s autopkgtest: DBG: sending command to testbed: open
120s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest.OlORYc
120s autopkgtest: DBG: sending command to testbed: print-execute-command
120s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest-ssh.hw7w9drl/runcmd
120s autopkgtest: DBG: sending command to testbed: capabilities
120s autopkgtest: DBG: got reply from testbed: ok isolation-machine suggested-normal-user=ubuntu reboot revert-full-system revert root-on-testbed
120s autopkgtest: DBG: testbed capabilities: ['isolation-machine', 'suggested-normal-user=ubuntu', 'reboot', 'revert-full-system', 'revert', 'root-on-testbed', 'has_internet']
120s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.OlORYc'], kind short, sout raw, serr pipe, env []
120s autopkgtest: DBG: testbed command exited with code 0
120s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/wrapper.sh /tmp/autopkgtest.OlORYc/wrapper.sh
121s autopkgtest: DBG: got reply from testbed: ok
121s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.OlORYc/wrapper.sh'], kind short, sout raw, serr pipe, env []
121s autopkgtest: DBG: testbed command exited with code 0
121s autopkgtest: DBG: testbed command ['dpkg', '--print-architecture'], kind short, sout pipe, serr pipe, env []
121s autopkgtest: DBG: testbed command exited with code 0
121s autopkgtest [03:32:14]: testbed dpkg architecture: s390x
121s autopkgtest: DBG: testbed command ['dpkg-query', '-W', '-f', '${Version}', 'apt'], kind short, sout pipe, serr pipe, env []
121s autopkgtest: DBG: testbed command exited with code 0
121s autopkgtest [03:32:14]: testbed apt version: 2.9.29
121s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v eatmydata'], kind short, sout pipe, serr pipe, env []
121s autopkgtest: DBG: testbed command exited with code 0
121s autopkgtest: DBG: testbed has eatmydata
121s autopkgtest: DBG: testbed command ['mkdir', '-p', '/etc/apt/preferences.d'], kind short, sout raw, serr pipe, env []
121s autopkgtest: DBG: testbed command exited with code 0
121s autopkgtest [03:32:14]: @@@@@@@@@@@@@@@@@@@@ test bed setup
121s autopkgtest: DBG: testbed command ['sh', '-ec', '#!/bin/sh\n# Copyright © 2006-2018 Canonical Ltd.\n# Copyright © 2015-2017 Martin Pitt\n# Copyright © 2017 Steve Langasek\n# Copyright © 2017-2022 Paul Gevers\n# Copyright © 2018 Simon McVittie\n# SPDX-License-Identifier: GPL-2.0-or-later\n\n# This script returns the first suite found in apt sources. We can\'t just rely\n# on /etc/os-release because it doesn\'t allow to distinguish between Debian\n# unstable and testing.\n#\n# This release detection logic should be kept in sync with similar code in the\n# setup-testbed script.\n#\n# For more information on APT data sources see sources.list(5).\n\n# Usage $0\n\nset -eux\n\ndistro=UNKNOWN\nif [ -r /etc/os-release ]; then\n    # shellcheck disable=SC1091\n    distro=$(. /etc/os-release && echo "${ID:-$distro}")\nfi\n\n# Try guessing the default release from deb822-style format sources.\n#\n# While not mandatory, APT upstream expects distros to follow the\n# <distro>.sources naming pattern for the default deb822 sources.\napt_sources="/etc/apt/sources.list.d/$distro.sources"\nif [ -r "$apt_sources" ]; then\n    release=$(sed -En \'s/^Suites:\\s*(\\w+).*/\\1/Ip\' "$apt_sources" | head -n1)\n    [ -n "$release" ] && echo "$release" && exit\nfi\n\n# Try guessing the default release from one-line-style format sources.\napt_sources=/etc/apt/sources.list\nif [ -r "$apt_sources" ]; then\n    release=$(sed -En \'/^(deb|deb-src) +(\\[.*\\] *)?(http|https|file):/ { s/\\[.*\\] +//; s/^[^ ]+ +[^ ]* +([^ ]+) +.*$/\\1/p }\' "$apt_sources" | head -n1)\n    [ -n "$release" ] && echo "$release" && exit\nfi\n\n# Could not guess the default release\necho "Could not guess the default release from the APT sources." >&2\nexit 1\n'], kind short, sout pipe, serr pipe, env []
122s autopkgtest: DBG: testbed command exited with code 0
122s autopkgtest [03:32:15]: testbed release detected to be: None
122s autopkgtest: DBG: testbed command ['sh', '-ec', '#!/bin/sh\n# SPDX-License-Identifier: GPL-2.0-or-later\n\n# This script returns the list of configured "deb" sources in one-line-style\n# source lists (/etc/apt/sources.list, /etc/apt/sources.list.d/*.list).\n# See sources.list(5) for more information on the APT source list formats.\n#\n# This script takes no arguments.\n\nset -eu\n\n# grep(1) returns 1 if no lines were selected from the input files, which\n# can happen if there are .list files, but they contain no "deb" entries.\n# We don\'t want to fail in this case, hence the "|| true", which we put\n# in a subshell because we want it to affect only the grep invocation.\n{\n    if [ -f /etc/apt/sources.list ]; then\n        printf \'%s\\0\' /etc/apt/sources.list\n    fi\n    if [ -d /etc/apt/sources.list.d ]; then\n        find /etc/apt/sources.list.d -maxdepth 1 -type f -regex \'.*/[a-zA-Z0-9_.-]+\\.list\' -print0 \\\n            | LC_ALL=C sort --zero-terminated\n    fi\n} | xargs -0 --no-run-if-empty -- sh -c \'grep -h "^deb " "$@" || true\' "$(basename "$0")"\n'], kind short, sout pipe, serr pipe, env []
122s autopkgtest: DBG: testbed command exited with code 0
122s autopkgtest: DBG: testbed command ['sh', '-ec', '#!/bin/sh\n# SPDX-License-Identifier: GPL-2.0-or-later\n\n# This script returns the list of configured sources in deb822-style source\n# lists (/etc/apt/sources.list.d/*.sources). Sources are not stripped out of\n# comments, extra blank lines or similar: the output of this script is meant to\n# be consumed by a deb822 parser that is able to correctly handle those.\n# See sources.list(5) for more information on the APT source list formats.\n#\n# This script takes no arguments.\n\nset -eu\n\n# Awk concatenates files making sure stanzas from separate files are separated\n# by a blank line, even if there are no newlines at EOF in the .sources files.\nif [ -d /etc/apt/sources.list.d ]; then\n    find /etc/apt/sources.list.d -maxdepth 1 -type f -regex \'.*/[a-zA-Z0-9_.-]+\\.sources\' -print0 \\\n        | LC_ALL=C sort --zero-terminated \\\n        | xargs -0 --no-run-if-empty -- awk \'FNR == 1 && NR != 1 { print "" } { print }\'\nfi\n'], kind short, sout pipe, serr pipe, env []
122s autopkgtest: DBG: testbed command exited with code 0
122s autopkgtest: DBG: adding APT source: Types: deb deb-src
122s URIs: http://ftpmaster.internal/ubuntu/
122s Suites: plucky-proposed
122s Components: main restricted universe multiverse
122s Signed-By: /usr/share/keyrings/ubuntu-archive-keyring.gpg
122s autopkgtest: DBG: testbed command ['sh', '-ec', '"$@" >> "/etc/apt/sources.list.d/autopkgtest-add-apt-release-plucky-proposed.sources"', 'add_apt_source', 'printf', '%s\\n', 'Types: deb deb-src\nURIs: http://ftpmaster.internal/ubuntu/\nSuites: plucky-proposed\nComponents: main restricted universe multiverse\nSigned-By: /usr/share/keyrings/ubuntu-archive-keyring.gpg\n'], kind short, sout raw, serr pipe, env []
122s autopkgtest: DBG: testbed command exited with code 0
122s autopkgtest: DBG: adding APT preference to autopkgtest-zz-plucky-proposed-baseline.pref:
122s Package: *
122s Pin: release plucky-proposed
122s Pin-Priority: 500
122s autopkgtest: DBG: testbed command ['sh', '-ec', '"$@" > "/etc/apt/preferences.d/autopkgtest-zz-plucky-proposed-baseline.pref"', 'add_apt_preference', 'printf', '%s\\n', 'Package: *\nPin: release plucky-proposed\nPin-Priority: 500'], kind short, sout raw, serr pipe, env []
122s autopkgtest: DBG: testbed command exited with code 0
122s autopkgtest [03:32:15]: updating testbed package index (apt update)
122s autopkgtest: DBG: testbed command ['/bin/sh', '-ec', '"$@" 3>&2 2>&1', 'run_apt_command', '/usr/bin/eatmydata', 'apt-get', '--quiet', '--assume-yes', '-o=APT::Status-Fd=3', '-o=APT::Install-Recommends=false', '-o=Dpkg::Options::=--force-confnew', '-o=Debug::pkgProblemResolver=true', 'update'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none']
123s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB]
123s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
123s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
123s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
123s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B]
123s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [788 kB]
123s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [79.1 kB]
123s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.6 kB]
123s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [98.8 kB]
123s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B]
123s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [656 kB]
123s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [5192 B]
123s Fetched 1755 kB in 1s (1939 kB/s)
124s Reading package lists...
124s autopkgtest: DBG: testbed command exited with code 0
124s autopkgtest: DBG: adding APT preference to autopkgtest-plucky-proposed.pref:
124s Package: *
124s Pin: release plucky-proposed
124s Pin-Priority: 100
124s 
124s Package: src:ncurses:any
124s Pin: release plucky-proposed
124s Pin-Priority: 995
124s autopkgtest: DBG: testbed command ['sh', '-ec', '"$@" > "/etc/apt/preferences.d/autopkgtest-plucky-proposed.pref"', 'add_apt_preference', 'printf', '%s\\n', 'Package: *\nPin: release plucky-proposed\nPin-Priority: 100\n\nPackage: src:ncurses:any\nPin: release plucky-proposed\nPin-Priority: 995'], kind short, sout raw, serr pipe, env []
124s autopkgtest: DBG: testbed command exited with code 0
124s autopkgtest: DBG: testbed command ['bash', '-ec', 'for d in /boot /boot/efi /boot/grub /etc/init /etc/init.d /etc/systemd/system /lib/systemd/system; do [ ! -d $d ] || touch -r $d /tmp/autopkgtest.OlORYc/${d//\\//_}.stamp; done'], kind short, sout raw, serr pipe, env []
124s autopkgtest: DBG: testbed command exited with code 0
124s autopkgtest: DBG: testbed command ['sh', '-ec', '#!/bin/sh\n# Canonical/Ubuntu specific testbed setup\n\nset -x\n\nRELEASE=$(lsb_release --codename --short 2>/dev/null)\n\n# Make the installed package versions match what is available from the configured\n# repositories, even if a downgrade is required.\n#\n# We may end up with images with packages newer than those available from the\n# repositories when opening a new release (images are created using the\n# previous release dailies as a base, which may include 0-day SRUs, or\n# Launchpad may fail to copy packages, see openssl 3.0.8-1ubuntu2), or when\n# package versions are pulled from the archive.\n#\n# Note: \'release a=\' matches the Suite field in the repository Release file.\ncat >/etc/apt/preferences.d/force-downgrade-to-release.pref <<EOF\nPackage: *\nPin: release a=$RELEASE\nPin-Priority: 1001\n\nPackage: *\nPin: release a=$RELEASE-updates\nPin-Priority: 1001\n\nPackage: *\nPin: release n=$RELEASE-security\nPin-Priority: 1001\nEOF\n# This is going to be a downgrade-only (or no-op) dist-upgrade, as setup-testbed\n# already dist-upgraded the system. Force the usage of the conf files of the\n# package being installed (--force-confnew), as conf files "from the future"\n# may not be supported by the older package version we\'re downgrading to.\nif [ "${RELEASE}" != "trusty" ]; then\n    DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::="--force-confnew" dist-upgrade\nfi\nrm /etc/apt/preferences.d/force-downgrade-to-release.pref\n\n# Remove packages not available from any configured repository, e.g.\n# packages fully removed from the archive. This requires the analyze-pattern\n# feature, which has been introduced in apt 2.0.1 (>= Focal).\n#\n# Note: autoremove/--autoremove always runs on the entire set of installed\n# packages. Together with --autopurge/purge each removal becomes a purge.\n#\n# Removing \'?obsolete\' packages may remove the running kernel. This is not\n# expected to be an issue.\nif /usr/lib/apt/apt-helper analyze-pattern \'?true\' >/dev/null 2>&1; then\n    # DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove \'?obsolete\'\n   # Avoid removing the running kernel\n   running_kernel_pattern="^linux-.*$(uname -r | sed \'s/\\./\\\\./g\').*"\n   obsolete_pkgs="$(apt list \'?obsolete\' 2>/dev/null \\\n     | tail -n+2 \\\n     | cut -d\'/\' -f1 \\\n     | grep -v "${running_kernel_pattern}" || true)"\n   DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ${obsolete_pkgs}\nelse\n    # We\'re on an older release (analyze-pattern is not available).\n    # Still do an autopurge to get rid of packages that got orphaned\n    # by downgrades.\n    eatmydata apt-get -y autoremove --purge\nfi\n\n# compatibility with old Jenkins testbeds for trusty\nif grep -q trusty /etc/lsb-release; then apt-get update || (sleep 10; apt-get update); apt-get install -y build-essential; fi\n\n# these packages really break tests, purge them if we use standard cloud images\n# (on lcy01)\nfor p in unattended-upgrades lxd lxd-client snapd; do\n    [ ! -d /usr/share/doc/$p ] || $(which eatmydata || true) apt-get purge --auto-remove -y $p\ndone\n\nif type iptables >/dev/null 2>&1; then\n    cat <<EOF > /etc/rc.local\n#!/bin/sh\n\n# work around broken PTMU; LP: #1572026, RT#90771\niptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu || true\n\n# These addresses are used at least by rust-reqwest and should not be reachable\n# See https://bugs.launchpad.net/ubuntu/+source/rust-reqwest/+bug/2080346\niptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP || true\niptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP || true\nEOF\n    chmod 755 /etc/rc.local\n    # shellcheck disable=SC1091\n    . /etc/rc.local\nfi\n\n# work around broken tty on ppc64el instances (LP: #1607075)\nif [ "$(uname -m)" = ppc64le ] && [ "$(systemctl is-active keyboard-setup.service 2>/dev/null|| true)" = failed ]; then\n    systemctl reset-failed keyboard-setup.service\n    systemctl mask keyboard-setup.service\nfi\n\n# work around late urandom pool initialization (LP: #1622893)\nif [ -d /run/systemd/system ] && systemd-detect-virt --quiet --vm; then\n    mkdir -p /etc/systemd/system/systemd-random-seed.service.d/\n    cat <<EOF > /etc/systemd/system/systemd-random-seed.service.d/fakeentropy.conf\n[Service]\nExecStart=/usr/bin/perl -E \'open \\$\\$f, "/bin/bash" or die; open \\$\\$rnd, ">/dev/random" or die; for (\\$\\$i = 0; \\$\\$i < 10; ++\\$\\$i) {read \\$\\$f, \\$\\$d, 64; ioctl \\$\\$rnd, 0x40085203, pack("ii", 64*8, 64) . \\$\\$d}\'\nEOF\nfi\n\n# set compressor to lz4 where supported, we care about compression speed\nif grep -q lz4 /etc/initramfs-tools/initramfs.conf; then\n    echo COMPRESS=lz4 > /etc/initramfs-tools/conf.d/lz4\nfi'], kind install, sout raw, serr raw, env ['AUTOPKGTEST_IS_SETUP_COMMAND=1', 'AUTOPKGTEST_NORMAL_USER=ubuntu', 'ADT_NORMAL_USER=ubuntu']
125s + Reading package lists...lsb_release --codename --short
125s + RELEASE=plucky
125s + cat
125s + [ plucky != trusty ]
125s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade
125s 
125s Building dependency tree...
125s Reading state information...
125s Calculating upgrade...
125s The following packages were automatically installed and are no longer required:
125s   libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64
125s   linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic
125s   linux-modules-6.11.0-8-generic linux-tools-6.11.0-8
125s   linux-tools-6.11.0-8-generic
125s Use 'sudo apt autoremove' to remove them.
125s The following packages will be upgraded:
125s   dhcpcd-base s390-tools s390-tools-data s390-tools-signed
125s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
125s Need to get 5637 kB of archives.
125s After this operation, 54.3 kB of additional disk space will be used.
125s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x dhcpcd-base s390x 1:10.1.0-7 [219 kB]
126s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x s390-tools-data all 2.37.0-0ubuntu2 [20.4 kB]
126s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x s390-tools s390x 2.37.0-0ubuntu2 [5385 kB]
126s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x s390-tools-signed all 2.37.0-0ubuntu2 [12.4 kB]
126s Fetched 5637 kB in 1s (7068 kB/s)
126s (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 ... 80980 files and directories currently installed.)
126s Preparing to unpack .../dhcpcd-base_1%3a10.1.0-7_s390x.deb ...
126s Unpacking dhcpcd-base (1:10.1.0-7) over (1:10.1.0-6) ...
126s Preparing to unpack .../s390-tools-data_2.37.0-0ubuntu2_all.deb ...
126s Unpacking s390-tools-data (2.37.0-0ubuntu2) over (2.37.0-0ubuntu1) ...
126s Preparing to unpack .../s390-tools_2.37.0-0ubuntu2_s390x.deb ...
126s Unpacking s390-tools (2.37.0-0ubuntu2) over (2.37.0-0ubuntu1) ...
126s Preparing to unpack .../s390-tools-signed_2.37.0-0ubuntu2_all.deb ...
126s Unpacking s390-tools-signed (2.37.0-0ubuntu2) over (2.37.0-0ubuntu1) ...
126s Setting up s390-tools-signed (2.37.0-0ubuntu2) ...
126s Setting up dhcpcd-base (1:10.1.0-7) ...
126s Setting up s390-tools-data (2.37.0-0ubuntu2) ...
126s Setting up s390-tools (2.37.0-0ubuntu2) ...
127s Processing triggers for man-db (2.13.0-1) ...
128s Processing triggers for initramfs-tools (0.145ubuntu2) ...
128s update-initramfs: Generating /boot/initrd.img-6.12.0-15-generic
128s W: No lz4 in /usr/bin:/sbin:/bin, using gzip
133s Using config file '/etc/zipl.conf'
133s Building bootmap in '/boot'
133s Adding IPL section 'ubuntu' (default)
133s Preparing boot device for LD-IPL: vda (0000).
133s Done.
133s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref
133s + /usr/lib/apt/apt-helper analyze-pattern ?true
133s + uname -r
133s + sed s/\./\\./g
133s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.*
133s + apt list ?obsolete
133s + tail -n+2
133s + cut -d/ -f1
133s + grep -v ^linux-.*6\.12\.0-15-generic.*
133s + true
133s + obsolete_pkgs=
133s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove
133s Reading package lists...
133s Building dependency tree...
133s Reading state information...
134s The following packages will be REMOVED:
134s   libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64*
134s   linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic*
134s   linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8*
134s   linux-tools-6.11.0-8-generic*
134s 0 upgraded, 0 newly installed, 9 to remove and 6 not upgraded.
134s After this operation, 167 MB disk space will be freed.
134s (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 ... 80981 files and directories currently installed.)
134s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ...
134s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ...
134s Removing libpython3.12t64:s390x (3.12.9-1) ...
134s Removing libpython3.12-stdlib:s390x (3.12.9-1) ...
134s Removing libnsl2:s390x (1.3.0-3build3) ...
134s Removing libpython3.12-minimal:s390x (3.12.9-1) ...
134s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ...
134s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ...
135s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ...
135s Processing triggers for libc-bin (2.40-4ubuntu1) ...
135s (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 ... 55881 files and directories currently installed.)
135s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ...
135s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ...
136s + grep -q trusty /etc/lsb-release
136s + [ ! -d /usr/share/doc/unattended-upgrades ]
136s + [ ! -d /usr/share/doc/lxd ]
136s + [ ! -d /usr/share/doc/lxd-client ]
136s + [ ! -d /usr/share/doc/snapd ]
136s + type iptables
136s + cat
136s + chmod 755 /etc/rc.local
136s + . /etc/rc.local
136s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
136s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP
136s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP
136s + uname -m
136s + [ s390x = ppc64le ]
136s + [ -d /run/systemd/system ]
136s + systemd-detect-virt --quiet --vm
136s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/
136s + cat
136s + grep -q lz4 /etc/initramfs-tools/initramfs.conf
136s + echo COMPRESS=lz4
136s autopkgtest: DBG: testbed command exited with code 0
136s autopkgtest [03:32:29]: upgrading testbed (apt dist-upgrade and autopurge)
136s autopkgtest: DBG: testbed command ['/bin/sh', '-ec', '"$@" 3>&2 2>&1', 'run_apt_command', '/usr/bin/eatmydata', 'apt-get', '--quiet', '--assume-yes', '-o=APT::Status-Fd=3', '-o=APT::Install-Recommends=false', '-o=Dpkg::Options::=--force-confnew', '-o=Debug::pkgProblemResolver=true', 'dist-upgrade'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none']
136s Reading package lists...
136s Building dependency tree...
136s Reading state information...
136s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
136s Starting 2 pkgProblemResolver with broken count: 0
136s Done
136s Entering ResolveByKeep
137s 
137s The following packages will be upgraded:
137s   libncurses6 libncursesw6 libtinfo6 ncurses-base ncurses-bin ncurses-term
137s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
137s Need to get 896 kB of archives.
137s After this operation, 12.3 kB of additional disk space will be used.
137s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x ncurses-bin s390x 6.5+20250216-1 [197 kB]
137s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x ncurses-base all 6.5+20250216-1 [25.9 kB]
137s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x ncurses-term all 6.5+20250216-1 [276 kB]
138s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libncursesw6 s390x 6.5+20250216-1 [158 kB]
138s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libncurses6 s390x 6.5+20250216-1 [122 kB]
138s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libtinfo6 s390x 6.5+20250216-1 [117 kB]
138s Fetched 896 kB in 1s (1406 kB/s)
138s (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 ... 55879 files and directories currently installed.)
138s Preparing to unpack .../ncurses-bin_6.5+20250216-1_s390x.deb ...
138s Unpacking ncurses-bin (6.5+20250216-1) over (6.5+20250125-2) ...
138s Setting up ncurses-bin (6.5+20250216-1) ...
138s (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 ... 55879 files and directories currently installed.)
138s Preparing to unpack .../ncurses-base_6.5+20250216-1_all.deb ...
138s Unpacking ncurses-base (6.5+20250216-1) over (6.5+20250125-2) ...
138s Setting up ncurses-base (6.5+20250216-1) ...
138s (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 ... 55879 files and directories currently installed.)
138s Preparing to unpack .../ncurses-term_6.5+20250216-1_all.deb ...
138s Unpacking ncurses-term (6.5+20250216-1) over (6.5+20250125-2) ...
138s Preparing to unpack .../libncursesw6_6.5+20250216-1_s390x.deb ...
138s Unpacking libncursesw6:s390x (6.5+20250216-1) over (6.5+20250125-2) ...
138s Preparing to unpack .../libncurses6_6.5+20250216-1_s390x.deb ...
138s Unpacking libncurses6:s390x (6.5+20250216-1) over (6.5+20250125-2) ...
138s Preparing to unpack .../libtinfo6_6.5+20250216-1_s390x.deb ...
138s Unpacking libtinfo6:s390x (6.5+20250216-1) over (6.5+20250125-2) ...
138s Setting up libtinfo6:s390x (6.5+20250216-1) ...
138s Setting up libncurses6:s390x (6.5+20250216-1) ...
138s Setting up libncursesw6:s390x (6.5+20250216-1) ...
138s Setting up ncurses-term (6.5+20250216-1) ...
138s Processing triggers for man-db (2.13.0-1) ...
139s Processing triggers for libc-bin (2.40-4ubuntu1) ...
139s autopkgtest: DBG: testbed command exited with code 0
139s autopkgtest: DBG: testbed command ['/bin/sh', '-ec', '"$@" 3>&2 2>&1', 'run_apt_command', '/usr/bin/eatmydata', 'apt-get', '--quiet', '--assume-yes', '-o=APT::Status-Fd=3', '-o=APT::Install-Recommends=false', '-o=Dpkg::Options::=--force-confnew', '-o=Debug::pkgProblemResolver=true', '--purge', 'autoremove'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none']
139s Reading package lists...
139s Building dependency tree...
139s Reading state information...
139s Starting pkgProblemResolver with broken count: 0
139s Starting 2 pkgProblemResolver with broken count: 0
139s Done
140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
140s autopkgtest: DBG: testbed command exited with code 0
140s autopkgtest: DBG: testbed command ['bash', '-ec', '[ ! -e /run/autopkgtest_no_reboot.stamp ] || exit 0;for d in /boot /boot/efi /boot/grub /etc/init /etc/init.d /etc/systemd/system /lib/systemd/system; do s=/tmp/autopkgtest.OlORYc/${d//\\//_}.stamp;  [ ! -d $d ] || [ `stat -c %Y $d` = `stat -c %Y $s` ]; done'], kind short, sout raw, serr raw, env []
140s autopkgtest: DBG: testbed command exited with code 1
140s autopkgtest [03:32:33]: rebooting testbed after setup commands that affected boot
140s autopkgtest: DBG: sending command to testbed: reboot
160s autopkgtest: DBG: got reply from testbed: ok
160s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot
160s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.OlORYc'], kind short, sout raw, serr pipe, env []
160s autopkgtest: DBG: testbed command exited with code 0
160s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot.sh /tmp/autopkgtest.OlORYc/autopkgtest-reboot
161s autopkgtest: DBG: got reply from testbed: ok
161s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
161s autopkgtest: DBG: testbed command exited with code 0
161s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
161s autopkgtest: DBG: testbed command exited with code 0
161s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot', '/sbin/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
161s autopkgtest: DBG: testbed command exited with code 0
161s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.OlORYc'], kind short, sout raw, serr pipe, env []
161s autopkgtest: DBG: testbed command exited with code 0
161s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.OlORYc/autopkgtest-reboot-prepare
162s autopkgtest: DBG: got reply from testbed: ok
162s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
162s autopkgtest: DBG: testbed command exited with code 0
162s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
162s autopkgtest: DBG: testbed command exited with code 0
162s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env []
162s autopkgtest: DBG: testbed command exited with code 0
162s autopkgtest [03:32:55]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb  4 15:05:57 UTC 2025
162s autopkgtest: DBG: testbed command ['sh', '-c', 'nproc; cat /proc/cpuinfo 2>/dev/null || true'], kind short, sout pipe, serr pipe, env []
162s autopkgtest: DBG: testbed command exited with code 0
162s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.OlORYc/testbed-packages"], kind short, sout raw, serr pipe, env []
162s autopkgtest: DBG: testbed command exited with code 0
162s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/testbed-packages /tmp/autopkgtest-work.ksav7miw/out/testbed-packages
163s autopkgtest: DBG: got reply from testbed: ok
163s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot
163s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.OlORYc'], kind short, sout raw, serr pipe, env []
163s autopkgtest: DBG: testbed command exited with code 0
163s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot.sh /tmp/autopkgtest.OlORYc/autopkgtest-reboot
163s autopkgtest: DBG: got reply from testbed: ok
163s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
163s autopkgtest: DBG: testbed command exited with code 0
163s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
163s autopkgtest: DBG: testbed command exited with code 0
163s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot', '/sbin/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
163s autopkgtest: DBG: testbed command exited with code 0
163s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.OlORYc'], kind short, sout raw, serr pipe, env []
164s autopkgtest: DBG: testbed command exited with code 0
164s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.OlORYc/autopkgtest-reboot-prepare
164s autopkgtest: DBG: got reply from testbed: ok
164s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
164s autopkgtest: DBG: testbed command exited with code 0
164s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.OlORYc/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
164s autopkgtest: DBG: testbed command exited with code 0
164s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env []
165s autopkgtest: DBG: testbed command exited with code 0
165s autopkgtest: DBG: Binaries: initialising
165s autopkgtest [03:32:58]: @@@@@@@@@@@@@@@@@@@@ apt-source tmux
165s autopkgtest: DBG: blame += tmux
165s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=[]
165s autopkgtest: DBG: testbed command ['apt-cache', 'showsrc', '--only-source', 'tmux'], kind short, sout pipe, serr pipe, env []
165s autopkgtest: DBG: testbed command exited with code 0
165s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^tmux$'], kind short, sout pipe, serr raw, env []
165s autopkgtest: DBG: testbed command exited with code 0
165s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'tmux=3.4-7'], kind short, sout pipe, serr raw, env []
166s autopkgtest: DBG: testbed command exited with code 0
166s autopkgtest: DBG: install_deps: deps_new=[]
166s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v dpkg-source'], kind short, sout pipe, serr pipe, env []
166s autopkgtest: DBG: testbed command exited with code 0
166s autopkgtest: DBG: testbed command ['sh', '-ec', 'su --shell=/bin/sh ubuntu -c \'set -e; exec 3>&1 >&2; set -x; cd /; builddir=$(mktemp -d /tmp/autopkgtest.OlORYc/build.XXX); cd $builddir; OUT=$(apt-get source -d -q --only-source tmux=3.4-7 2>&1) || RC=$?;if [ -n "$RC" ]; then  if echo "$OUT" | grep -q "Unable to find a source package"; then    exit 1;  else    exit $RC;  fi;fi;echo "$OUT" | grep ^Get: || true;dpkg-source -x tmux_*.dsc src >/dev/null; chmod -R a+rX .; cd [a-z0-9]*/.; pwd >&3; sed -n "1 {s/).*//; s/ (/\\n/; p}" debian/changelog >&3\''], kind build, sout pipe, serr raw, env ['ADT_TEST_TRIGGERS=ncurses/6.5+20250216-1']
166s + cd /
166s + mktemp -d /tmp/autopkgtest.OlORYc/build.XXX
166s + builddir=/tmp/autopkgtest.OlORYc/build.ITP
166s + cd /tmp/autopkgtest.OlORYc/build.ITP
166s + apt-get source -d -q --only-source tmux=3.4-7
167s + OUT=Reading package lists...
167s NOTICE: 'tmux' packaging is maintained in the 'Git' version control system at:
167s https://salsa.debian.org/rfrancoise/tmux.git
167s Please use:
167s git clone https://salsa.debian.org/rfrancoise/tmux.git
167s to retrieve the latest (possibly unreleased) updates to the package.
167s Need to get 729 kB of source archives.
167s Get:1 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (dsc) [1954 B]
167s Get:2 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (tar) [707 kB]
167s Get:3 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (diff) [20.1 kB]
167s Fetched 729 kB in 1s (813 kB/s)
167s Download complete and in download only mode
167s + [ -n  ]
167s + echo Reading package lists...
167s NOTICE: 'tmux' packaging is maintained in the 'Git' version control system at:
167s https://salsa.debian.org/rfrancoise/tmux.git
167s Please use:
167s git clone https://salsa.debian.org/rfrancoise/tmux.git
167s to retrieve the latest (possibly unreleased) updates to the package.
167s Need to get 729 kB of source archives.
167s Get:1 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (dsc) [1954 B]
167s Get:2 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (tar) [707 kB]
167s Get:3 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (diff) [20.1 kB]
167s Fetched 729 kB in 1s (813 kB/s)
167s Download complete and in download only mode+ 
167s grep ^Get:
167s Get:1 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (dsc) [1954 B]
167s Get:2 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (tar) [707 kB]
167s Get:3 http://ftpmaster.internal/ubuntu plucky/main tmux 3.4-7 (diff) [20.1 kB]
167s + dpkg-source -x tmux_3.4-7.dsc src
167s gpgv: Signature made Mon Jun 24 19:15:47 2024 UTC
167s gpgv:                using RSA key BE349742CA987ECD7E77E42DAD15F435F05F962D
167s gpgv: Can't check signature: No public key
167s dpkg-source: warning: cannot verify inline signature for ./tmux_3.4-7.dsc: no acceptable signature found
167s + chmod -R a+rX .
167s + cd src/.
167s + pwd
167s + sed -n 1 {s/).*//; s/ (/\n/; p} debian/changelog
167s autopkgtest: DBG: testbed command exited with code 0
167s autopkgtest [03:33:00]: testing package tmux version 3.4-7
167s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/build.ITP/src/debian/ /tmp/autopkgtest-work.ksav7miw/out/pkg/debian/
167s autopkgtest: DBG: got reply from testbed: ok
168s autopkgtest: DBG: processing dependency @
168s autopkgtest: DBG: synthesised dependency tmux
168s autopkgtest: DBG: Test defined: name smoketest path debian/tests/smoketest command "None" restrictions [] features [] depends ['tmux'] 
168s autopkgtest: DBG: Test defined: name utmp path debian/tests/utmp command "None" restrictions [] features [] depends ['tmux'] 
168s autopkgtest [03:33:01]: build not needed
168s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/build.ITP/src/ /tmp/autopkgtest-work.ksav7miw/out/tests-tree/
169s autopkgtest: DBG: got reply from testbed: ok
169s autopkgtest: DBG: processing dependency @
169s autopkgtest: DBG: synthesised dependency tmux
169s autopkgtest: DBG: Test defined: name smoketest path debian/tests/smoketest command "None" restrictions [] features [] depends ['tmux'] 
169s autopkgtest: DBG: Test defined: name utmp path debian/tests/utmp command "None" restrictions [] features [] depends ['tmux'] 
169s autopkgtest [03:33:02]: test smoketest: preparing testbed
169s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=['tmux']
169s autopkgtest: DBG: Binaries: no registered binaries, not publishing anything
169s autopkgtest: DBG: install_deps: deps_new=['tmux']
169s autopkgtest: DBG: install-deps: satisfying tmux
169s autopkgtest: DBG: can use apt-get on testbed: True
169s autopkgtest: DBG: testbed command ['/bin/sh', '-ec', '"$@" 3>&2 2>&1', 'run_apt_command', '/usr/bin/eatmydata', 'apt-get', '--quiet', '--assume-yes', '-o=APT::Status-Fd=3', '-o=APT::Install-Recommends=false', '-o=Dpkg::Options::=--force-confnew', '-o=Debug::pkgProblemResolver=true', 'satisfy', 'tmux'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none']
169s Reading package lists...
169s Building dependency tree...
169s Reading state information...
169s Starting pkgProblemResolver with broken count: 0
169s Starting 2 pkgProblemResolver with broken count: 0
169s Done
170s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
170s autopkgtest: DBG: testbed command exited with code 0
170s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'tmux'], kind short, sout pipe, serr pipe, env []
170s autopkgtest: DBG: testbed command exited with code 0
170s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.OlORYc/smoketest-packages.all"], kind short, sout raw, serr pipe, env []
170s autopkgtest: DBG: testbed command exited with code 0
170s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/smoketest-packages.all /tmp/autopkgtest-work.ksav7miw/out/smoketest-packages.all
170s autopkgtest: DBG: got reply from testbed: ok
170s autopkgtest: DBG: testbed command ['test', '-e', '/tmp/autopkgtest.OlORYc/build.ITP/src'], kind short, sout raw, serr raw, env []
170s autopkgtest: DBG: testbed command exited with code 0
170s autopkgtest: DBG: copydown: tb path /tmp/autopkgtest.OlORYc/build.ITP/src already exists
170s autopkgtest [03:33:03]: test smoketest: [-----------------------
170s autopkgtest: DBG: testbed command ['su', '-s', '/bin/bash', 'ubuntu', '-c', 'set -e; exec /tmp/autopkgtest.OlORYc/wrapper.sh --debug --artifacts=/tmp/autopkgtest.OlORYc/smoketest-artifacts --chdir=/tmp/autopkgtest.OlORYc/build.ITP/src --env=AUTOPKGTEST_TESTBED_ARCH=s390x --env=AUTOPKGTEST_TEST_ARCH=s390x --env=DEB_BUILD_OPTIONS=parallel=2 --env=DEBIAN_FRONTEND=noninteractive --env=LANG=C.UTF-8 --unset-env=LANGUAGE --unset-env=LC_ADDRESS --unset-env=LC_ALL --unset-env=LC_COLLATE --unset-env=LC_CTYPE --unset-env=LC_IDENTIFICATION --unset-env=LC_MEASUREMENT --unset-env=LC_MESSAGES --unset-env=LC_MONETARY --unset-env=LC_NAME --unset-env=LC_NUMERIC --unset-env=LC_PAPER --unset-env=LC_TELEPHONE --unset-env=LC_TIME --script-pid-file=/tmp/autopkgtest_script_pid --source-profile --stderr=/tmp/autopkgtest.OlORYc/smoketest-stderr --stdout=/tmp/autopkgtest.OlORYc/smoketest-stdout --tmp=/tmp/autopkgtest.OlORYc/autopkgtest_tmp --env=ADT_TEST_TRIGGERS=ncurses/6.5+20250216-1 --make-executable=/tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/smoketest -- /tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/smoketest'], kind test, sout raw, serr raw, env []
170s /tmp/autopkgtest.OlORYc/wrapper.sh: creating AUTOPKGTEST_ARTIFACTS: /tmp/autopkgtest.OlORYc/smoketest-artifacts
170s /tmp/autopkgtest.OlORYc/wrapper.sh: changing to directory: /tmp/autopkgtest.OlORYc/build.ITP/src
170s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: AUTOPKGTEST_TESTBED_ARCH=s390x
170s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: AUTOPKGTEST_TEST_ARCH=s390x
170s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: DEB_BUILD_OPTIONS=parallel=2
170s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: DEBIAN_FRONTEND=noninteractive
170s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: LANG=C.UTF-8
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LANGUAGE
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_ADDRESS
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_ALL
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_COLLATE
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_CTYPE
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_IDENTIFICATION
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_MEASUREMENT
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_MESSAGES
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_MONETARY
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_NAME
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_NUMERIC
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_PAPER
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_TELEPHONE
170s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_TIME
170s /tmp/autopkgtest.OlORYc/wrapper.sh: will create pid file: /tmp/autopkgtest_script_pid
170s /tmp/autopkgtest.OlORYc/wrapper.sh: pretending to be a login shell
170s /tmp/autopkgtest.OlORYc/wrapper.sh: will write standard error to /tmp/autopkgtest.OlORYc/smoketest-stderr
170s /tmp/autopkgtest.OlORYc/wrapper.sh: will write stdout to /tmp/autopkgtest.OlORYc/smoketest-stdout
170s /tmp/autopkgtest.OlORYc/wrapper.sh: creating AUTOPKGTEST_TMP: /tmp/autopkgtest.OlORYc/autopkgtest_tmp
170s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: ADT_TEST_TRIGGERS=ncurses/6.5+20250216-1
170s /tmp/autopkgtest.OlORYc/wrapper.sh: marking as executable: /tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/smoketest
170s /tmp/autopkgtest.OlORYc/wrapper.sh: command to run: /tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/smoketest
170s /tmp/autopkgtest.OlORYc/wrapper.sh: copying /tmp/tmp.VJxWvsFdPa/out to stdout and file: /tmp/autopkgtest.OlORYc/smoketest-stdout
170s /tmp/autopkgtest.OlORYc/wrapper.sh: copying /tmp/tmp.VJxWvsFdPa/err to standard error and file: /tmp/autopkgtest.OlORYc/smoketest-stdout
170s /tmp/autopkgtest.OlORYc/wrapper.sh: writing script pid 1266 to /tmp/autopkgtest_script_pid
170s /tmp/autopkgtest.OlORYc/wrapper.sh: checking for leaked background processes...
170s /tmp/autopkgtest.OlORYc/wrapper.sh: waiting for tee/cat subprocesses...
170s /tmp/autopkgtest.OlORYc/wrapper.sh: cleaning up...
170s /tmp/autopkgtest.OlORYc/wrapper.sh: Exit status: 0
171s autopkgtest: DBG: testbed command exited with code 0
171s autopkgtest [03:33:04]: test smoketest: -----------------------]
171s autopkgtest: DBG: testbed executing test finished with exit status 0
171s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/smoketest-stdout /tmp/autopkgtest-work.ksav7miw/out/smoketest-stdout
171s autopkgtest: DBG: got reply from testbed: ok
171s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/smoketest-stderr /tmp/autopkgtest-work.ksav7miw/out/smoketest-stderr
171s autopkgtest: DBG: got reply from testbed: ok
171s smoketest            PASS
171s autopkgtest [03:33:04]: test smoketest:  - - - - - - - - - - results - - - - - - - - - -
171s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/smoketest-artifacts/ /tmp/autopkgtest-work.ksav7miw/out/artifacts/
171s autopkgtest: DBG: got reply from testbed: ok
171s autopkgtest: DBG: testbed command ['rm', '-rf', '/tmp/autopkgtest.OlORYc/smoketest-artifacts', '/tmp/autopkgtest.OlORYc/autopkgtest_tmp'], kind short, sout raw, serr pipe, env []
172s autopkgtest: DBG: testbed command exited with code 0
172s autopkgtest [03:33:05]: test utmp: preparing testbed
172s autopkgtest: DBG: testbed reset: modified=False, deps_installed=['tmux'], deps_new=['tmux']
172s autopkgtest: DBG: Binaries: no registered binaries, not publishing anything
172s autopkgtest: DBG: install_deps: deps_new=['tmux']
172s autopkgtest: DBG: install-deps: satisfying tmux
172s autopkgtest: DBG: can use apt-get on testbed: True
172s autopkgtest: DBG: testbed command ['/bin/sh', '-ec', '"$@" 3>&2 2>&1', 'run_apt_command', '/usr/bin/eatmydata', 'apt-get', '--quiet', '--assume-yes', '-o=APT::Status-Fd=3', '-o=APT::Install-Recommends=false', '-o=Dpkg::Options::=--force-confnew', '-o=Debug::pkgProblemResolver=true', 'satisfy', 'tmux'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none']
172s Reading package lists...
172s Building dependency tree...
172s Reading state information...
172s Starting pkgProblemResolver with broken count: 0
172s Starting 2 pkgProblemResolver with broken count: 0
172s Done
172s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
172s autopkgtest: DBG: testbed command exited with code 0
172s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'tmux'], kind short, sout pipe, serr pipe, env []
172s autopkgtest: DBG: testbed command exited with code 0
172s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.OlORYc/utmp-packages.all"], kind short, sout raw, serr pipe, env []
173s autopkgtest: DBG: testbed command exited with code 0
173s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/utmp-packages.all /tmp/autopkgtest-work.ksav7miw/out/utmp-packages.all
173s autopkgtest: DBG: got reply from testbed: ok
173s autopkgtest: DBG: testbed command ['test', '-e', '/tmp/autopkgtest.OlORYc/build.ITP/src'], kind short, sout raw, serr raw, env []
173s autopkgtest: DBG: testbed command exited with code 0
173s autopkgtest: DBG: copydown: tb path /tmp/autopkgtest.OlORYc/build.ITP/src already exists
173s autopkgtest [03:33:06]: test utmp: [-----------------------
173s autopkgtest: DBG: testbed command ['su', '-s', '/bin/bash', 'ubuntu', '-c', 'set -e; exec /tmp/autopkgtest.OlORYc/wrapper.sh --debug --artifacts=/tmp/autopkgtest.OlORYc/utmp-artifacts --chdir=/tmp/autopkgtest.OlORYc/build.ITP/src --env=AUTOPKGTEST_TESTBED_ARCH=s390x --env=AUTOPKGTEST_TEST_ARCH=s390x --env=DEB_BUILD_OPTIONS=parallel=2 --env=DEBIAN_FRONTEND=noninteractive --env=LANG=C.UTF-8 --unset-env=LANGUAGE --unset-env=LC_ADDRESS --unset-env=LC_ALL --unset-env=LC_COLLATE --unset-env=LC_CTYPE --unset-env=LC_IDENTIFICATION --unset-env=LC_MEASUREMENT --unset-env=LC_MESSAGES --unset-env=LC_MONETARY --unset-env=LC_NAME --unset-env=LC_NUMERIC --unset-env=LC_PAPER --unset-env=LC_TELEPHONE --unset-env=LC_TIME --script-pid-file=/tmp/autopkgtest_script_pid --source-profile --stderr=/tmp/autopkgtest.OlORYc/utmp-stderr --stdout=/tmp/autopkgtest.OlORYc/utmp-stdout --tmp=/tmp/autopkgtest.OlORYc/autopkgtest_tmp --env=ADT_TEST_TRIGGERS=ncurses/6.5+20250216-1 --make-executable=/tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/utmp -- /tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/utmp'], kind test, sout raw, serr raw, env []
173s /tmp/autopkgtest.OlORYc/wrapper.sh: creating AUTOPKGTEST_ARTIFACTS: /tmp/autopkgtest.OlORYc/utmp-artifacts
173s /tmp/autopkgtest.OlORYc/wrapper.sh: changing to directory: /tmp/autopkgtest.OlORYc/build.ITP/src
173s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: AUTOPKGTEST_TESTBED_ARCH=s390x
173s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: AUTOPKGTEST_TEST_ARCH=s390x
173s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: DEB_BUILD_OPTIONS=parallel=2
173s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: DEBIAN_FRONTEND=noninteractive
173s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: LANG=C.UTF-8
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LANGUAGE
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_ADDRESS
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_ALL
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_COLLATE
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_CTYPE
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_IDENTIFICATION
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_MEASUREMENT
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_MESSAGES
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_MONETARY
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_NAME
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_NUMERIC
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_PAPER
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_TELEPHONE
173s /tmp/autopkgtest.OlORYc/wrapper.sh: unsetting environment: LC_TIME
173s /tmp/autopkgtest.OlORYc/wrapper.sh: will create pid file: /tmp/autopkgtest_script_pid
173s /tmp/autopkgtest.OlORYc/wrapper.sh: pretending to be a login shell
173s /tmp/autopkgtest.OlORYc/wrapper.sh: will write standard error to /tmp/autopkgtest.OlORYc/utmp-stderr
173s /tmp/autopkgtest.OlORYc/wrapper.sh: will write stdout to /tmp/autopkgtest.OlORYc/utmp-stdout
173s /tmp/autopkgtest.OlORYc/wrapper.sh: creating AUTOPKGTEST_TMP: /tmp/autopkgtest.OlORYc/autopkgtest_tmp
173s /tmp/autopkgtest.OlORYc/wrapper.sh: setting environment: ADT_TEST_TRIGGERS=ncurses/6.5+20250216-1
173s /tmp/autopkgtest.OlORYc/wrapper.sh: marking as executable: /tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/utmp
173s /tmp/autopkgtest.OlORYc/wrapper.sh: command to run: /tmp/autopkgtest.OlORYc/build.ITP/src/debian/tests/utmp
173s /tmp/autopkgtest.OlORYc/wrapper.sh: copying /tmp/tmp.EzARwoJXC3/out to stdout and file: /tmp/autopkgtest.OlORYc/utmp-stdout
173s /tmp/autopkgtest.OlORYc/wrapper.sh: copying /tmp/tmp.EzARwoJXC3/err to standard error and file: /tmp/autopkgtest.OlORYc/utmp-stdout
173s /tmp/autopkgtest.OlORYc/wrapper.sh: writing script pid 1422 to /tmp/autopkgtest_script_pid
173s no utmp entries found for tmux
173s /tmp/autopkgtest.OlORYc/wrapper.sh: checking for leaked background processes...
173s /tmp/autopkgtest.OlORYc/wrapper.sh: waiting for tee/cat subprocesses...
173s /tmp/autopkgtest.OlORYc/wrapper.sh: cleaning up...
173s /tmp/autopkgtest.OlORYc/wrapper.sh: Exit status: 1
173s autopkgtest: DBG: testbed command exited with code 1
173s autopkgtest [03:33:06]: test utmp: -----------------------]
173s autopkgtest: DBG: testbed executing test finished with exit status 1
173s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/utmp-stdout /tmp/autopkgtest-work.ksav7miw/out/utmp-stdout
174s autopkgtest: DBG: got reply from testbed: ok
174s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/utmp-stderr /tmp/autopkgtest-work.ksav7miw/out/utmp-stderr
174s autopkgtest: DBG: got reply from testbed: ok
174s autopkgtest [03:33:07]: test utmp:  - - - - - - - - - - results - - - - - - - - - -
174s utmp                 FAIL non-zero exit status 1
174s autopkgtest [03:33:07]: test utmp:  - - - - - - - - - - stderr - - - - - - - - - -
174s no utmp entries found for tmux
174s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.OlORYc/utmp-artifacts/ /tmp/autopkgtest-work.ksav7miw/out/artifacts/
174s autopkgtest: DBG: got reply from testbed: ok
174s autopkgtest: DBG: testbed command ['rm', '-rf', '/tmp/autopkgtest.OlORYc/utmp-artifacts', '/tmp/autopkgtest.OlORYc/autopkgtest_tmp'], kind short, sout raw, serr pipe, env []
174s autopkgtest: DBG: testbed command exited with code 0
174s autopkgtest: DBG: needs_reset, previously=False, requested by run_tests() line 230
174s autopkgtest [03:33:07]: @@@@@@@@@@@@@@@@@@@@ summary
174s smoketest            PASS
174s utmp                 FAIL non-zero exit status 1
174s autopkgtest: DBG: testbed stop
174s autopkgtest: DBG: testbed close, scratch=/tmp/autopkgtest.OlORYc
174s autopkgtest: DBG: sending command to testbed: close
191s autopkgtest: DBG: got reply from testbed: ok
191s autopkgtest: DBG: sending command to testbed: quit
191s nova [W] Using flock in prodstack6-s390x
191s Creating nova instance adt-plucky-s390x-tmux-20250219-033013-juju-7f2275-prod-proposed-migration-environment-15-d06266c2-893e-4c1f-9231-bf5759d9ea4a from image adt/ubuntu-plucky-s390x-server-20250218.img (UUID d4b3949f-1d31-4fe6-9e99-080adec067af)...
191s nova [W] Timed out waiting for 0f16bc10-51b6-4dac-938d-9a1429a490dc to get deleted.