0s autopkgtest: DBG: testbed init
  0s autopkgtest [22:58:51]: starting date and time: 2025-02-15 22:58:51+0000
  0s autopkgtest [22:58:51]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production'
  0s autopkgtest [22:58:51]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.1dxxpjq0/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc,src:iproute2,src:php-twig,src:postgresql-17,src:postgresql-common,src:roundcube --apt-upgrade hashalot --debug --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu1 iproute2/6.13.0-1ubuntu1 php-twig/3.19.0-1 postgresql-17/17.3-2 postgresql-common/273 roundcube/1.6.10+dfsg-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-2.secgroup --name adt-plucky-s390x-hashalot-20250215-225851-juju-7f2275-prod-proposed-migration-environment-15-131340ae-3b4d-4893-97fe-eee4f5f40c3d --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
100s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest.faMPa1
100s autopkgtest: DBG: sending command to testbed: print-execute-command
100s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest-ssh.bk5jzcaj/runcmd
100s autopkgtest: DBG: sending command to testbed: capabilities
100s autopkgtest: DBG: got reply from testbed: ok isolation-machine revert-full-system revert reboot suggested-normal-user=ubuntu root-on-testbed
100s autopkgtest: DBG: testbed capabilities: ['isolation-machine', 'revert-full-system', 'revert', 'reboot', 'suggested-normal-user=ubuntu', 'root-on-testbed', 'has_internet']
100s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.faMPa1'], kind short, sout raw, serr pipe, env []
100s autopkgtest: DBG: testbed command exited with code 0
100s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/wrapper.sh /tmp/autopkgtest.faMPa1/wrapper.sh
101s autopkgtest: DBG: got reply from testbed: ok
101s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.faMPa1/wrapper.sh'], kind short, sout raw, serr pipe, env []
101s autopkgtest: DBG: testbed command exited with code 0
101s autopkgtest: DBG: testbed command ['dpkg', '--print-architecture'], kind short, sout pipe, serr pipe, env []
101s autopkgtest: DBG: testbed command exited with code 0
101s autopkgtest [23:00:32]: testbed dpkg architecture: s390x
101s autopkgtest: DBG: testbed command ['dpkg-query', '-W', '-f', '${Version}', 'apt'], kind short, sout pipe, serr pipe, env []
101s autopkgtest: DBG: testbed command exited with code 0
101s autopkgtest [23:00:32]: testbed apt version: 2.9.28
101s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v eatmydata'], kind short, sout pipe, serr pipe, env []
101s autopkgtest: DBG: testbed command exited with code 0
101s autopkgtest: DBG: testbed has eatmydata
101s autopkgtest: DBG: testbed command ['mkdir', '-p', '/etc/apt/preferences.d'], kind short, sout raw, serr pipe, env []
101s autopkgtest: DBG: testbed command exited with code 0
101s autopkgtest [23:00:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup
101s 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 []
102s autopkgtest: DBG: testbed command exited with code 0
102s autopkgtest [23:00:33]: testbed release detected to be: None
102s 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 []
102s autopkgtest: DBG: testbed command exited with code 0
102s 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 []
102s autopkgtest: DBG: testbed command exited with code 0
102s autopkgtest: DBG: adding APT source: Types: deb deb-src
102s URIs: http://ftpmaster.internal/ubuntu/
102s Suites: plucky-proposed
102s Components: main restricted universe multiverse
102s Signed-By: /usr/share/keyrings/ubuntu-archive-keyring.gpg
102s 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 []
102s autopkgtest: DBG: testbed command exited with code 0
102s autopkgtest: DBG: adding APT preference to autopkgtest-zz-plucky-proposed-baseline.pref:
102s Package: *
102s Pin: release plucky-proposed
102s Pin-Priority: 500
102s 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 []
102s autopkgtest: DBG: testbed command exited with code 0
102s autopkgtest [23:00:33]: updating testbed package index (apt update)
102s 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']
103s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB]
103s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
103s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
103s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
103s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B]
103s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.1 kB]
103s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [828 kB]
103s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [74.3 kB]
103s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [163 kB]
103s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B]
103s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [876 kB]
103s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3740 B]
104s Fetched 2073 kB in 1s (2012 kB/s)
104s Reading package lists...
104s autopkgtest: DBG: testbed command exited with code 0
104s autopkgtest: DBG: adding APT preference to autopkgtest-plucky-proposed.pref:
104s Package: *
104s Pin: release plucky-proposed
104s Pin-Priority: 100
104s 
104s Package: src:glibc:any src:iproute2:any src:php-twig:any src:postgresql-17:any src:postgresql-common:any src:roundcube:any
104s Pin: release plucky-proposed
104s Pin-Priority: 995
104s 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:glibc:any src:iproute2:any src:php-twig:any src:postgresql-17:any src:postgresql-common:any src:roundcube:any\nPin: release plucky-proposed\nPin-Priority: 995'], kind short, sout raw, serr pipe, env []
104s autopkgtest: DBG: testbed command exited with code 0
104s 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.faMPa1/${d//\\//_}.stamp; done'], kind short, sout raw, serr pipe, env []
104s autopkgtest: DBG: testbed command exited with code 0
104s 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']
105s + lsb_release --codename --short
105s + RELEASE=plucky
105s + cat
105s + [ plucky != trusty ]
105s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade
105s Reading package lists...
105s Building dependency tree...
105s Reading state information...
105s Calculating upgrade...
105s The following packages were automatically installed and are no longer required:
105s   libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64
105s   linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic
105s   linux-modules-6.11.0-8-generic linux-tools-6.11.0-8
105s   linux-tools-6.11.0-8-generic
105s Use 'sudo apt autoremove' to remove them.
105s The following packages will be upgraded:
105s   dash gcc-14-base libatomic1 libgcc-s1 libstdc++6 libtasn1-6 libxdmcp6
105s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
105s Need to get 1155 kB of archives.
105s After this operation, 16.4 kB of additional disk space will be used.
105s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x dash s390x 0.5.12-12ubuntu1 [100 kB]
105s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libatomic1 s390x 14.2.0-17ubuntu1 [9430 B]
105s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-17ubuntu1 [53.5 kB]
105s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++6 s390x 14.2.0-17ubuntu1 [896 kB]
106s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-s1 s390x 14.2.0-17ubuntu1 [35.9 kB]
106s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libtasn1-6 s390x 4.20.0-2 [48.6 kB]
106s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libxdmcp6 s390x 1:1.1.5-1 [11.0 kB]
106s Preconfiguring packages ...
106s Fetched 1155 kB in 1s (1840 kB/s)
106s (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 ... 80969 files and directories currently installed.)
106s Preparing to unpack .../dash_0.5.12-12ubuntu1_s390x.deb ...
106s Unpacking dash (0.5.12-12ubuntu1) over (0.5.12-9ubuntu1) ...
106s Setting up dash (0.5.12-12ubuntu1) ...
106s (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 ... 80969 files and directories currently installed.)
106s Preparing to unpack .../libatomic1_14.2.0-17ubuntu1_s390x.deb ...
106s Unpacking libatomic1:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ...
106s Preparing to unpack .../gcc-14-base_14.2.0-17ubuntu1_s390x.deb ...
106s Unpacking gcc-14-base:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ...
106s Setting up gcc-14-base:s390x (14.2.0-17ubuntu1) ...
106s (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 ... 80969 files and directories currently installed.)
106s Preparing to unpack .../libstdc++6_14.2.0-17ubuntu1_s390x.deb ...
106s Unpacking libstdc++6:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ...
106s Setting up libstdc++6:s390x (14.2.0-17ubuntu1) ...
106s (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 ... 80969 files and directories currently installed.)
106s Preparing to unpack .../libgcc-s1_14.2.0-17ubuntu1_s390x.deb ...
106s Unpacking libgcc-s1:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ...
106s Setting up libgcc-s1:s390x (14.2.0-17ubuntu1) ...
106s (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 ... 80969 files and directories currently installed.)
106s Preparing to unpack .../libtasn1-6_4.20.0-2_s390x.deb ...
106s Unpacking libtasn1-6:s390x (4.20.0-2) over (4.19.0-3build1) ...
106s Preparing to unpack .../libxdmcp6_1%3a1.1.5-1_s390x.deb ...
106s Unpacking libxdmcp6:s390x (1:1.1.5-1) over (1:1.1.3-0ubuntu6) ...
106s Setting up libxdmcp6:s390x (1:1.1.5-1) ...
106s Setting up libatomic1:s390x (14.2.0-17ubuntu1) ...
106s Setting up libtasn1-6:s390x (4.20.0-2) ...
106s Processing triggers for libc-bin (2.40-4ubuntu1) ...
106s Processing triggers for man-db (2.13.0-1) ...
107s Processing triggers for debianutils (5.21) ...
107s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref
107s + /usr/lib/apt/apt-helper analyze-pattern ?true
107s + uname -r
107s + sed s/\./\\./g
107s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.*
107s + apt list ?obsolete
107s + tail -n+2
107s + grep -v ^linux-.*6\.12\.0-15-generic.*
107s + cut -d/ -f1
107s + true
107s + obsolete_pkgs=
107s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s The following packages will be REMOVED:
107s   libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64*
107s   linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic*
107s   linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8*
107s   linux-tools-6.11.0-8-generic*
108s 0 upgraded, 0 newly installed, 9 to remove and 6 not upgraded.
108s After this operation, 167 MB disk space will be freed.
108s (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 ... 80969 files and directories currently installed.)
108s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ...
108s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ...
108s Removing libpython3.12t64:s390x (3.12.9-1) ...
108s Removing libpython3.12-stdlib:s390x (3.12.9-1) ...
108s Removing libnsl2:s390x (1.3.0-3build3) ...
108s Removing libpython3.12-minimal:s390x (3.12.9-1) ...
108s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ...
108s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ...
109s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ...
109s Processing triggers for libc-bin (2.40-4ubuntu1) ...
109s (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 ... 55869 files and directories currently installed.)
109s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ...
109s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ...
109s + grep -q trusty /etc/lsb-release
109s + [ ! -d /usr/share/doc/unattended-upgrades ]
109s + [ ! -d /usr/share/doc/lxd ]
109s + [ ! -d /usr/share/doc/lxd-client ]
109s + [ ! -d /usr/share/doc/snapd ]
109s + type iptables
109s + cat
109s + chmod 755 /etc/rc.local
109s + . /etc/rc.local
109s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
109s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP
109s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP
109s + uname -m
109s + [ s390x = ppc64le ]
109s + [ -d /run/systemd/system ]
109s + systemd-detect-virt --quiet --vm
109s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/
109s + cat
109s + grep -q lz4 /etc/initramfs-tools/initramfs.conf
109s + echo COMPRESS=lz4
109s autopkgtest: DBG: testbed command exited with code 0
109s autopkgtest [23:00:40]: upgrading testbed (apt dist-upgrade and autopurge)
109s 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']
109s Reading package lists...
109s Building dependency tree...
109s Reading state information...
109s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
109s Starting 2 pkgProblemResolver with broken count: 0
109s Done
110s Entering ResolveByKeep
110s 
110s The following packages will be upgraded:
110s   iproute2 libc-bin libc-dev-bin libc6 libc6-dev locales
110s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
110s Need to get 10.7 MB of archives.
110s After this operation, 305 kB of additional disk space will be used.
110s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu1 [24.3 kB]
110s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu1 [1679 kB]
111s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu1 [4246 kB]
113s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu1 [2891 kB]
113s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu1 [672 kB]
113s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x iproute2 s390x 6.13.0-1ubuntu1 [1174 kB]
113s Preconfiguring packages ...
113s Fetched 10.7 MB in 2s (4712 kB/s)
113s (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 ... 55867 files and directories currently installed.)
113s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu1_s390x.deb ...
113s Unpacking libc-dev-bin (2.41-1ubuntu1) over (2.40-4ubuntu1) ...
113s Preparing to unpack .../libc6-dev_2.41-1ubuntu1_s390x.deb ...
113s Unpacking libc6-dev:s390x (2.41-1ubuntu1) over (2.40-4ubuntu1) ...
113s Preparing to unpack .../locales_2.41-1ubuntu1_all.deb ...
113s Unpacking locales (2.41-1ubuntu1) over (2.40-4ubuntu1) ...
113s Preparing to unpack .../libc6_2.41-1ubuntu1_s390x.deb ...
113s Checking for services that may need to be restarted...
113s Checking init scripts...
113s Checking for services that may need to be restarted...
113s Checking init scripts...
113s Stopping some services possibly affected by the upgrade (will be restarted later):
113s   cron: stopping...done.
113s 
113s Unpacking libc6:s390x (2.41-1ubuntu1) over (2.40-4ubuntu1) ...
113s Setting up libc6:s390x (2.41-1ubuntu1) ...
113s Checking for services that may need to be restarted...
113s Checking init scripts...
113s Restarting services possibly affected by the upgrade:
113s   cron: restarting...done.
113s 
113s Services restarted successfully.
113s (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 ... 55868 files and directories currently installed.)
113s Preparing to unpack .../libc-bin_2.41-1ubuntu1_s390x.deb ...
113s Unpacking libc-bin (2.41-1ubuntu1) over (2.40-4ubuntu1) ...
113s Setting up libc-bin (2.41-1ubuntu1) ...
113s (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 ... 55868 files and directories currently installed.)
113s Preparing to unpack .../iproute2_6.13.0-1ubuntu1_s390x.deb ...
113s Unpacking iproute2 (6.13.0-1ubuntu1) over (6.10.0-2ubuntu1) ...
113s Setting up iproute2 (6.13.0-1ubuntu1) ...
113s Setting up locales (2.41-1ubuntu1) ...
113s Installing new version of config file /etc/locale.alias ...
114s Generating locales (this might take a while)...
114s   en_US.UTF-8... done
114s Generation complete.
114s Setting up libc-dev-bin (2.41-1ubuntu1) ...
114s Setting up libc6-dev:s390x (2.41-1ubuntu1) ...
114s Processing triggers for man-db (2.13.0-1) ...
115s Processing triggers for systemd (257.2-3ubuntu1) ...
116s autopkgtest: DBG: testbed command exited with code 0
116s 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']
116s Reading package lists...
117s Building dependency tree...
117s Reading state information...
117s autopkgtest: DBG: testbed command exited with code 0
117s Starting pkgProblemResolver with broken count: 0
117s Starting 2 pkgProblemResolver with broken count: 0
117s Done
117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
117s 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.faMPa1/${d//\\//_}.stamp;  [ ! -d $d ] || [ `stat -c %Y $d` = `stat -c %Y $s` ]; done'], kind short, sout raw, serr raw, env []
117s autopkgtest: DBG: testbed command exited with code 1
117s autopkgtest [23:00:48]: rebooting testbed after setup commands that affected boot
117s autopkgtest: DBG: sending command to testbed: reboot
135s autopkgtest: DBG: got reply from testbed: ok
135s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot
135s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.faMPa1'], kind short, sout raw, serr pipe, env []
135s autopkgtest: DBG: testbed command exited with code 0
135s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot.sh /tmp/autopkgtest.faMPa1/autopkgtest-reboot
135s autopkgtest: DBG: got reply from testbed: ok
135s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
135s autopkgtest: DBG: testbed command exited with code 0
135s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
135s autopkgtest: DBG: testbed command exited with code 0
135s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot', '/sbin/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
136s autopkgtest: DBG: testbed command exited with code 0
136s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.faMPa1'], kind short, sout raw, serr pipe, env []
136s autopkgtest: DBG: testbed command exited with code 0
136s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.faMPa1/autopkgtest-reboot-prepare
136s autopkgtest: DBG: got reply from testbed: ok
136s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
136s autopkgtest: DBG: testbed command exited with code 0
136s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
136s autopkgtest: DBG: testbed command exited with code 0
136s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env []
137s autopkgtest: DBG: testbed command exited with code 0
137s autopkgtest [23:01:08]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb  4 15:05:57 UTC 2025
137s autopkgtest: DBG: testbed command ['sh', '-c', 'nproc; cat /proc/cpuinfo 2>/dev/null || true'], kind short, sout pipe, serr pipe, env []
137s autopkgtest: DBG: testbed command exited with code 0
137s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.faMPa1/testbed-packages"], kind short, sout raw, serr pipe, env []
137s autopkgtest: DBG: testbed command exited with code 0
137s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/testbed-packages /tmp/autopkgtest-work.1dxxpjq0/out/testbed-packages
137s autopkgtest: DBG: got reply from testbed: ok
137s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot
137s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.faMPa1'], kind short, sout raw, serr pipe, env []
138s autopkgtest: DBG: testbed command exited with code 0
138s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot.sh /tmp/autopkgtest.faMPa1/autopkgtest-reboot
138s autopkgtest: DBG: got reply from testbed: ok
138s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
138s autopkgtest: DBG: testbed command exited with code 0
138s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
138s autopkgtest: DBG: testbed command exited with code 0
138s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot', '/sbin/autopkgtest-reboot'], kind short, sout raw, serr pipe, env []
138s autopkgtest: DBG: testbed command exited with code 0
138s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.faMPa1'], kind short, sout raw, serr pipe, env []
138s autopkgtest: DBG: testbed command exited with code 0
138s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.faMPa1/autopkgtest-reboot-prepare
139s autopkgtest: DBG: got reply from testbed: ok
139s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
139s autopkgtest: DBG: testbed command exited with code 0
139s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.faMPa1/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env []
139s autopkgtest: DBG: testbed command exited with code 0
139s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env []
139s autopkgtest: DBG: testbed command exited with code 0
139s autopkgtest: DBG: Binaries: initialising
139s autopkgtest [23:01:10]: @@@@@@@@@@@@@@@@@@@@ apt-source hashalot
139s autopkgtest: DBG: blame += hashalot
139s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=[]
139s autopkgtest: DBG: testbed command ['apt-cache', 'showsrc', '--only-source', 'hashalot'], kind short, sout pipe, serr pipe, env []
140s autopkgtest: DBG: testbed command exited with code 0
140s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^hashalot$'], kind short, sout pipe, serr raw, env []
140s autopkgtest: DBG: testbed command exited with code 0
140s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'hashalot=0.3-10'], kind short, sout pipe, serr raw, env []
140s autopkgtest: DBG: testbed command exited with code 0
140s autopkgtest: DBG: install_deps: deps_new=[]
140s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v dpkg-source'], kind short, sout pipe, serr pipe, env []
140s autopkgtest: DBG: testbed command exited with code 0
140s 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.faMPa1/build.XXX); cd $builddir; OUT=$(apt-get source -d -q --only-source hashalot=0.3-10 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 hashalot_*.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=glibc/2.41-1ubuntu1 iproute2/6.13.0-1ubuntu1 php-twig/3.19.0-1 postgresql-17/17.3-2 postgresql-common/273 roundcube/1.6.10+dfsg-1']
140s + cd /
140s + mktemp -d /tmp/autopkgtest.faMPa1/build.XXX
140s + builddir=/tmp/autopkgtest.faMPa1/build.loN
140s + cd /tmp/autopkgtest.faMPa1/build.loN
140s + apt-get source -d -q --only-source hashalot=0.3-10
141s + OUT=Reading package lists...
141s NOTICE: 'hashalot' packaging is maintained in the 'Git' version control system at:
141s https://salsa.debian.org/debian/hashalot.git
141s Please use:
141s git clone https://salsa.debian.org/debian/hashalot.git
141s to retrieve the latest (possibly unreleased) updates to the package.
141s Need to get 86.9 kB of source archives.
141s Get:1 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (dsc) [1840 B]
141s Get:2 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (tar) [79.0 kB]
141s Get:3 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (diff) [6028 B]
141s Fetched 86.9 kB in 0s (219 kB/s)
141s Download complete and in download only mode
141s + [ -n  ]
141s + echo Reading package lists...
141s NOTICE: 'hashalot' packaging is maintained in the 'Git' version control system at:
141s https://salsa.debian.org/debian/hashalot.git
141s Please use:
141s git clone https://salsa.debian.org/debian/hashalot.git
141s to retrieve the latest (possibly unreleased) updates to the package.
141s Need to get 86.9 kB of source archives.
141s Get:1 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (dsc) [1840 B]
141s Get:2 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (tar) [79.0 kB]
141s Get:3 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (diff) [6028 B]
141s Fetched 86.9 kB in 0s (219 kB/s)
141s Download complete and in download only mode
141s + grep ^Get:
141s Get:1 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (dsc) [1840 B]
141s Get:2 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (tar) [79.0 kB]
141s Get:3 http://ftpmaster.internal/ubuntu plucky/universe hashalot 0.3-10 (diff) [6028 B]
141s + dpkg-source -x hashalot_0.3-10.dsc src
141s gpgv: Signature made Mon Feb  1 22:01:34 2021 UTC
141s gpgv:                using RSA key B23862C415D6565A4E86CBD7579C160D4C9E23E8
141s gpgv: Can't check signature: No public key
141s dpkg-source: warning: cannot verify inline signature for ./hashalot_0.3-10.dsc: no acceptable signature found
141s + chmod -R a+rX .
141s + cd src/.
141s + pwd
141s + sed -n 1 {s/).*//; s/ (/\n/; p} debian/changelog
141s autopkgtest: DBG: testbed command exited with code 0
141s autopkgtest [23:01:12]: testing package hashalot version 0.3-10
141s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/build.loN/src/debian/ /tmp/autopkgtest-work.1dxxpjq0/out/pkg/debian/
142s autopkgtest: DBG: got reply from testbed: ok
142s autopkgtest: DBG: processing dependency @
142s autopkgtest: DBG: synthesised dependency hashalot
142s autopkgtest: DBG: Test defined: name command1 path None command "echo test | xargs hashalot -x -s aeiou -n 10 -q sha512" restrictions [] features [] depends ['hashalot'] 
142s autopkgtest [23:01:13]: build not needed
142s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/build.loN/src/ /tmp/autopkgtest-work.1dxxpjq0/out/tests-tree/
142s autopkgtest: DBG: got reply from testbed: ok
142s autopkgtest: DBG: processing dependency @
142s autopkgtest: DBG: synthesised dependency hashalot
142s autopkgtest: DBG: Test defined: name command1 path None command "echo test | xargs hashalot -x -s aeiou -n 10 -q sha512" restrictions [] features [] depends ['hashalot'] 
142s autopkgtest [23:01:13]: test command1: preparing testbed
142s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=['hashalot']
142s autopkgtest: DBG: Binaries: no registered binaries, not publishing anything
142s autopkgtest: DBG: install_deps: deps_new=['hashalot']
142s autopkgtest: DBG: install-deps: satisfying hashalot
142s autopkgtest: DBG: can use apt-get on testbed: True
142s 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', 'hashalot'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none']
142s Reading package lists...
143s Building dependency tree...
143s Reading state information...
143s Starting pkgProblemResolver with broken count: 0
143s Starting 2 pkgProblemResolver with broken count: 0
143s Done
143s The following NEW packages will be installed:
143s   hashalot
143s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
143s Need to get 15.9 kB of archives.
143s After this operation, 52.2 kB of additional disk space will be used.
143s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x hashalot s390x 0.3-10 [15.9 kB]
143s Fetched 15.9 kB in 0s (75.8 kB/s)
143s Selecting previously unselected package hashalot.
143s (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 ... 55869 files and directories currently installed.)
143s Preparing to unpack .../hashalot_0.3-10_s390x.deb ...
143s Unpacking hashalot (0.3-10) ...
143s Setting up hashalot (0.3-10) ...
143s Processing triggers for man-db (2.13.0-1) ...
144s autopkgtest: DBG: testbed command exited with code 0
144s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'hashalot'], kind short, sout pipe, serr pipe, env []
144s autopkgtest: DBG: testbed command exited with code 0
144s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.faMPa1/command1-packages.all"], kind short, sout raw, serr pipe, env []
145s autopkgtest: DBG: testbed command exited with code 0
145s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/command1-packages.all /tmp/autopkgtest-work.1dxxpjq0/out/command1-packages.all
145s autopkgtest: DBG: got reply from testbed: ok
145s autopkgtest: DBG: testbed command ['test', '-e', '/tmp/autopkgtest.faMPa1/build.loN/src'], kind short, sout raw, serr raw, env []
145s autopkgtest: DBG: testbed command exited with code 0
145s autopkgtest: DBG: copydown: tb path /tmp/autopkgtest.faMPa1/build.loN/src already exists
145s autopkgtest [23:01:16]: test command1: echo test | xargs hashalot -x -s aeiou -n 10 -q sha512
145s autopkgtest [23:01:16]: test command1: [-----------------------
145s autopkgtest: DBG: testbed command ['su', '-s', '/bin/bash', 'ubuntu', '-c', "set -e; exec /tmp/autopkgtest.faMPa1/wrapper.sh --debug --artifacts=/tmp/autopkgtest.faMPa1/command1-artifacts --chdir=/tmp/autopkgtest.faMPa1/build.loN/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.faMPa1/command1-stderr --stdout=/tmp/autopkgtest.faMPa1/command1-stdout --tmp=/tmp/autopkgtest.faMPa1/autopkgtest_tmp '--env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu1 iproute2/6.13.0-1ubuntu1 php-twig/3.19.0-1 postgresql-17/17.3-2 postgresql-common/273 roundcube/1.6.10+dfsg-1' -- bash -ec 'echo test | xargs hashalot -x -s aeiou -n 10 -q sha512'"], kind test, sout raw, serr raw, env []
145s /tmp/autopkgtest.faMPa1/wrapper.sh: creating AUTOPKGTEST_ARTIFACTS: /tmp/autopkgtest.faMPa1/command1-artifacts
145s /tmp/autopkgtest.faMPa1/wrapper.sh: changing to directory: /tmp/autopkgtest.faMPa1/build.loN/src
145s /tmp/autopkgtest.faMPa1/wrapper.sh: setting environment: AUTOPKGTEST_TESTBED_ARCH=s390x
145s /tmp/autopkgtest.faMPa1/wrapper.sh: setting environment: AUTOPKGTEST_TEST_ARCH=s390x
145s /tmp/autopkgtest.faMPa1/wrapper.sh: setting environment: DEB_BUILD_OPTIONS=parallel=2
145s /tmp/autopkgtest.faMPa1/wrapper.sh: setting environment: DEBIAN_FRONTEND=noninteractive
145s /tmp/autopkgtest.faMPa1/wrapper.sh: setting environment: LANG=C.UTF-8
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LANGUAGE
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_ADDRESS
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_ALL
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_COLLATE
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_CTYPE
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_IDENTIFICATION
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_MEASUREMENT
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_MESSAGES
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_MONETARY
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_NAME
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_NUMERIC
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_PAPER
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_TELEPHONE
145s /tmp/autopkgtest.faMPa1/wrapper.sh: unsetting environment: LC_TIME
145s /tmp/autopkgtest.faMPa1/wrapper.sh: will create pid file: /tmp/autopkgtest_script_pid
145s /tmp/autopkgtest.faMPa1/wrapper.sh: pretending to be a login shell
145s /tmp/autopkgtest.faMPa1/wrapper.sh: will write standard error to /tmp/autopkgtest.faMPa1/command1-stderr
145s /tmp/autopkgtest.faMPa1/wrapper.sh: will write stdout to /tmp/autopkgtest.faMPa1/command1-stdout
145s /tmp/autopkgtest.faMPa1/wrapper.sh: creating AUTOPKGTEST_TMP: /tmp/autopkgtest.faMPa1/autopkgtest_tmp
145s /tmp/autopkgtest.faMPa1/wrapper.sh: setting environment: ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu1 iproute2/6.13.0-1ubuntu1 php-twig/3.19.0-1 postgresql-17/17.3-2 postgresql-common/273 roundcube/1.6.10+dfsg-1
145s /tmp/autopkgtest.faMPa1/wrapper.sh: command to run: bash -ec echo test | xargs hashalot -x -s aeiou -n 10 -q sha512
145s /tmp/autopkgtest.faMPa1/wrapper.sh: copying /tmp/tmp.0Qo9M1zEfY/out to stdout and file: /tmp/autopkgtest.faMPa1/command1-stdout
145s /tmp/autopkgtest.faMPa1/wrapper.sh: copying /tmp/tmp.0Qo9M1zEfY/err to standard error and file: /tmp/autopkgtest.faMPa1/command1-stdout
145s /tmp/autopkgtest.faMPa1/wrapper.sh: writing script pid 1326 to /tmp/autopkgtest_script_pid
145s /tmp/autopkgtest.faMPa1/wrapper.sh: checking for leaked background processes...
145s /tmp/autopkgtest.faMPa1/wrapper.sh: waiting for tee/cat subprocesses...
145s /tmp/autopkgtest.faMPa1/wrapper.sh: cleaning up...
145s /tmp/autopkgtest.faMPa1/wrapper.sh: Exit status: 0
145s autopkgtest: DBG: testbed command exited with code 0
145s 171ace565fda67e4bec4
146s autopkgtest [23:01:17]: test command1: -----------------------]
146s autopkgtest: DBG: testbed executing test finished with exit status 0
146s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/command1-stdout /tmp/autopkgtest-work.1dxxpjq0/out/command1-stdout
146s autopkgtest: DBG: got reply from testbed: ok
146s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/command1-stderr /tmp/autopkgtest-work.1dxxpjq0/out/command1-stderr
146s autopkgtest: DBG: got reply from testbed: ok
146s autopkgtest [23:01:17]: test command1:  - - - - - - - - - - results - - - - - - - - - -
146s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.faMPa1/command1-artifacts/ /tmp/autopkgtest-work.1dxxpjq0/out/artifacts/
146s command1             PASS
148s autopkgtest: DBG: got reply from testbed: ok
148s autopkgtest: DBG: testbed command ['rm', '-rf', '/tmp/autopkgtest.faMPa1/command1-artifacts', '/tmp/autopkgtest.faMPa1/autopkgtest_tmp'], kind short, sout raw, serr pipe, env []
148s autopkgtest: DBG: testbed command exited with code 0
148s autopkgtest: DBG: needs_reset, previously=False, requested by run_tests() line 230
148s autopkgtest [23:01:19]: @@@@@@@@@@@@@@@@@@@@ summary
148s command1             PASS
148s autopkgtest: DBG: testbed stop
148s autopkgtest: DBG: testbed close, scratch=/tmp/autopkgtest.faMPa1
148s autopkgtest: DBG: sending command to testbed: close
166s autopkgtest: DBG: got reply from testbed: ok
166s autopkgtest: DBG: sending command to testbed: quit
166s nova [W] Using flock in prodstack6-s390x
166s flock: timeout while waiting to get lock
166s Creating nova instance adt-plucky-s390x-hashalot-20250215-225851-juju-7f2275-prod-proposed-migration-environment-15-131340ae-3b4d-4893-97fe-eee4f5f40c3d from image adt/ubuntu-plucky-s390x-server-20250215.img (UUID 099dc609-2061-4009-a65b-d554466baca0)...
166s nova [W] Timed out waiting for c7785414-1035-4387-a908-55b564d18b27 to get deleted.