0s autopkgtest: DBG: testbed init 0s autopkgtest [22:22:13]: starting date and time: 2025-02-15 22:22:13+0000 0s autopkgtest [22:22:13]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [22:22:13]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.m6ljzkb0/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 firebird3.0 --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-1.secgroup --name adt-plucky-s390x-firebird3.0-20250215-222212-juju-7f2275-prod-proposed-migration-environment-15-984086de-78aa-40d0-a4d2-a1d4b7e5d1d8 --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 109s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest.e4rYCp 109s autopkgtest: DBG: sending command to testbed: print-execute-command 109s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest-ssh.f44i_6bh/runcmd 109s autopkgtest: DBG: sending command to testbed: capabilities 109s autopkgtest: DBG: got reply from testbed: ok reboot isolation-machine revert revert-full-system suggested-normal-user=ubuntu root-on-testbed 109s autopkgtest: DBG: testbed capabilities: ['reboot', 'isolation-machine', 'revert', 'revert-full-system', 'suggested-normal-user=ubuntu', 'root-on-testbed', 'has_internet'] 109s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.e4rYCp'], kind short, sout raw, serr pipe, env [] 109s autopkgtest: DBG: testbed command exited with code 0 109s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/wrapper.sh /tmp/autopkgtest.e4rYCp/wrapper.sh 109s autopkgtest: DBG: got reply from testbed: ok 109s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.e4rYCp/wrapper.sh'], kind short, sout raw, serr pipe, env [] 109s autopkgtest: DBG: testbed command exited with code 0 109s autopkgtest: DBG: testbed command ['dpkg', '--print-architecture'], kind short, sout pipe, serr pipe, env [] 110s autopkgtest: DBG: testbed command exited with code 0 110s autopkgtest [22:24:03]: testbed dpkg architecture: s390x 110s autopkgtest: DBG: testbed command ['dpkg-query', '-W', '-f', '${Version}', 'apt'], kind short, sout pipe, serr pipe, env [] 110s autopkgtest: DBG: testbed command exited with code 0 110s autopkgtest [22:24:03]: testbed apt version: 2.9.28 110s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v eatmydata'], kind short, sout pipe, serr pipe, env [] 110s autopkgtest: DBG: testbed command exited with code 0 110s autopkgtest: DBG: testbed has eatmydata 110s autopkgtest: DBG: testbed command ['mkdir', '-p', '/etc/apt/preferences.d'], kind short, sout raw, serr pipe, env [] 110s autopkgtest: DBG: testbed command exited with code 0 110s autopkgtest [22:24:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 110s 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# .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 [] 110s autopkgtest: DBG: testbed command exited with code 0 110s autopkgtest [22:24:03]: testbed release detected to be: None 110s 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 [] 110s autopkgtest: DBG: testbed command exited with code 0 110s 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 [] 111s autopkgtest: DBG: testbed command exited with code 0 111s autopkgtest: DBG: adding APT source: Types: deb deb-src 111s URIs: http://ftpmaster.internal/ubuntu/ 111s Suites: plucky-proposed 111s Components: main restricted universe multiverse 111s Signed-By: /usr/share/keyrings/ubuntu-archive-keyring.gpg 111s 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 [] 111s autopkgtest: DBG: testbed command exited with code 0 111s autopkgtest: DBG: adding APT preference to autopkgtest-zz-plucky-proposed-baseline.pref: 111s Package: * 111s Pin: release plucky-proposed 111s Pin-Priority: 500 111s 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 [] 111s autopkgtest: DBG: testbed command exited with code 0 111s autopkgtest [22:24:04]: updating testbed package index (apt update) 111s 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'] 111s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 111s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 112s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 112s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 112s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [828 kB] 112s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.1 kB] 112s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [74.3 kB] 112s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 112s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [163 kB] 112s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 112s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [876 kB] 112s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3740 B] 112s Fetched 2073 kB in 1s (2004 kB/s) 113s Reading package lists... 113s autopkgtest: DBG: testbed command exited with code 0 113s autopkgtest: DBG: adding APT preference to autopkgtest-plucky-proposed.pref: 113s Package: * 113s Pin: release plucky-proposed 113s Pin-Priority: 100 113s 113s Package: src:glibc:any src:iproute2:any src:php-twig:any src:postgresql-17:any src:postgresql-common:any src:roundcube:any 113s Pin: release plucky-proposed 113s Pin-Priority: 995 113s 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 [] 113s autopkgtest: DBG: testbed command exited with code 0 113s 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.e4rYCp/${d//\\//_}.stamp; done'], kind short, sout raw, serr pipe, env [] 113s autopkgtest: DBG: testbed command exited with code 0 113s 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 <= 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 < /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 < /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'] 113s + lsb_release --codename --short 113s Reading package lists...+ RELEASE=plucky 113s + cat 113s + [ plucky != trusty ] 113s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 113s 113s Building dependency tree... 113s Reading state information... 114s Calculating upgrade... 114s The following packages were automatically installed and are no longer required: 114s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 114s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 114s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 114s linux-tools-6.11.0-8-generic 114s Use 'sudo apt autoremove' to remove them. 114s The following packages will be upgraded: 114s dash gcc-14-base libatomic1 libgcc-s1 libstdc++6 libtasn1-6 libxdmcp6 114s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Need to get 1155 kB of archives. 114s After this operation, 16.4 kB of additional disk space will be used. 114s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x dash s390x 0.5.12-12ubuntu1 [100 kB] 114s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libatomic1 s390x 14.2.0-17ubuntu1 [9430 B] 114s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-17ubuntu1 [53.5 kB] 114s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++6 s390x 14.2.0-17ubuntu1 [896 kB] 114s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-s1 s390x 14.2.0-17ubuntu1 [35.9 kB] 114s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libtasn1-6 s390x 4.20.0-2 [48.6 kB] 114s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libxdmcp6 s390x 1:1.1.5-1 [11.0 kB] 115s Preconfiguring packages ... 115s Fetched 1155 kB in 1s (1808 kB/s) 115s (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.) 115s Preparing to unpack .../dash_0.5.12-12ubuntu1_s390x.deb ... 115s Unpacking dash (0.5.12-12ubuntu1) over (0.5.12-9ubuntu1) ... 115s Setting up dash (0.5.12-12ubuntu1) ... 115s (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.) 115s Preparing to unpack .../libatomic1_14.2.0-17ubuntu1_s390x.deb ... 115s Unpacking libatomic1:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 115s Preparing to unpack .../gcc-14-base_14.2.0-17ubuntu1_s390x.deb ... 115s Unpacking gcc-14-base:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 115s Setting up gcc-14-base:s390x (14.2.0-17ubuntu1) ... 115s (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.) 115s Preparing to unpack .../libstdc++6_14.2.0-17ubuntu1_s390x.deb ... 115s Unpacking libstdc++6:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 115s Setting up libstdc++6:s390x (14.2.0-17ubuntu1) ... 115s (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.) 115s Preparing to unpack .../libgcc-s1_14.2.0-17ubuntu1_s390x.deb ... 115s Unpacking libgcc-s1:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 115s Setting up libgcc-s1:s390x (14.2.0-17ubuntu1) ... 115s (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.) 115s Preparing to unpack .../libtasn1-6_4.20.0-2_s390x.deb ... 115s Unpacking libtasn1-6:s390x (4.20.0-2) over (4.19.0-3build1) ... 115s Preparing to unpack .../libxdmcp6_1%3a1.1.5-1_s390x.deb ... 115s Unpacking libxdmcp6:s390x (1:1.1.5-1) over (1:1.1.3-0ubuntu6) ... 115s Setting up libxdmcp6:s390x (1:1.1.5-1) ... 115s Setting up libatomic1:s390x (14.2.0-17ubuntu1) ... 115s Setting up libtasn1-6:s390x (4.20.0-2) ... 115s Processing triggers for libc-bin (2.40-4ubuntu1) ... 115s Processing triggers for man-db (2.13.0-1) ... 115s Processing triggers for debianutils (5.21) ... 116s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 116s + /usr/lib/apt/apt-helper analyze-pattern ?true 116s + uname -r 116s + sed s/\./\\./g 116s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 116s + apt list ?obsolete 116s + tail -n+2 116s + cut -d/ -f1 116s + grep -v ^linux-.*6\.12\.0-15-generic.* 116s + true 116s + obsolete_pkgs= 116s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s The following packages will be REMOVED: 116s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 116s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 116s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 116s linux-tools-6.11.0-8-generic* 116s 0 upgraded, 0 newly installed, 9 to remove and 6 not upgraded. 116s After this operation, 167 MB disk space will be freed. 116s (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.) 116s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 116s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 116s Removing libpython3.12t64:s390x (3.12.9-1) ... 116s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 116s Removing libnsl2:s390x (1.3.0-3build3) ... 116s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 116s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 116s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 117s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 117s Processing triggers for libc-bin (2.40-4ubuntu1) ... 117s (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.) 117s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 117s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 117s + grep -q trusty /etc/lsb-release 117s + [ ! -d /usr/share/doc/unattended-upgrades ] 117s + [ ! -d /usr/share/doc/lxd ] 117s + [ ! -d /usr/share/doc/lxd-client ] 117s + [ ! -d /usr/share/doc/snapd ] 117s + type iptables 117s + cat 117s + chmod 755 /etc/rc.local 117s + . /etc/rc.local 117s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 117s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 117s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 117s + uname -m 117s + [ s390x = ppc64le ] 117s + [ -d /run/systemd/system ] 117s + systemd-detect-virt --quiet --vm 117s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 117s + cat 117s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 117s + echo COMPRESS=lz4 117s autopkgtest: DBG: testbed command exited with code 0 117s autopkgtest [22:24:10]: upgrading testbed (apt dist-upgrade and autopurge) 117s 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'] 118s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 118s Starting 2 pkgProblemResolver with broken count: 0 118s Done 118s Entering ResolveByKeep 118s 118s The following packages will be upgraded: 118s iproute2 libc-bin libc-dev-bin libc6 libc6-dev locales 118s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s Need to get 10.7 MB of archives. 118s After this operation, 305 kB of additional disk space will be used. 118s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu1 [24.3 kB] 119s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu1 [1679 kB] 120s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu1 [4246 kB] 122s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu1 [2891 kB] 123s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu1 [672 kB] 123s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x iproute2 s390x 6.13.0-1ubuntu1 [1174 kB] 123s Preconfiguring packages ... 123s Fetched 10.7 MB in 5s (2256 kB/s) 123s (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.) 123s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu1_s390x.deb ... 123s Unpacking libc-dev-bin (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 123s Preparing to unpack .../libc6-dev_2.41-1ubuntu1_s390x.deb ... 123s Unpacking libc6-dev:s390x (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 123s Preparing to unpack .../locales_2.41-1ubuntu1_all.deb ... 123s Unpacking locales (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 124s Preparing to unpack .../libc6_2.41-1ubuntu1_s390x.deb ... 124s Checking for services that may need to be restarted... 124s Checking init scripts... 124s Checking for services that may need to be restarted... 124s Checking init scripts... 124s Stopping some services possibly affected by the upgrade (will be restarted later): 124s cron: stopping...done. 124s 124s Unpacking libc6:s390x (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 124s Setting up libc6:s390x (2.41-1ubuntu1) ... 124s Checking for services that may need to be restarted... 124s Checking init scripts... 124s Restarting services possibly affected by the upgrade: 124s cron: restarting...done. 124s 124s Services restarted successfully. 124s (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.) 124s Preparing to unpack .../libc-bin_2.41-1ubuntu1_s390x.deb ... 124s Unpacking libc-bin (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 124s Setting up libc-bin (2.41-1ubuntu1) ... 124s (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.) 124s Preparing to unpack .../iproute2_6.13.0-1ubuntu1_s390x.deb ... 124s Unpacking iproute2 (6.13.0-1ubuntu1) over (6.10.0-2ubuntu1) ... 124s Setting up iproute2 (6.13.0-1ubuntu1) ... 124s Setting up locales (2.41-1ubuntu1) ... 124s Installing new version of config file /etc/locale.alias ... 125s Generating locales (this might take a while)... 126s en_US.UTF-8... done 126s Generation complete. 126s Setting up libc-dev-bin (2.41-1ubuntu1) ... 126s Setting up libc6-dev:s390x (2.41-1ubuntu1) ... 126s Processing triggers for man-db (2.13.0-1) ... 126s Processing triggers for systemd (257.2-3ubuntu1) ... 127s autopkgtest: DBG: testbed command exited with code 0 127s 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'] 127s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s Starting pkgProblemResolver with broken count: 0 127s Starting 2 pkgProblemResolver with broken count: 0 127s Done 128s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 128s autopkgtest: DBG: testbed command exited with code 0 128s 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.e4rYCp/${d//\\//_}.stamp; [ ! -d $d ] || [ `stat -c %Y $d` = `stat -c %Y $s` ]; done'], kind short, sout raw, serr raw, env [] 128s autopkgtest: DBG: testbed command exited with code 1 128s autopkgtest [22:24:21]: rebooting testbed after setup commands that affected boot 128s autopkgtest: DBG: sending command to testbed: reboot 132s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 147s autopkgtest: DBG: got reply from testbed: ok 147s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot 147s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.e4rYCp'], kind short, sout raw, serr pipe, env [] 147s autopkgtest: DBG: testbed command exited with code 0 147s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot.sh /tmp/autopkgtest.e4rYCp/autopkgtest-reboot 147s autopkgtest: DBG: got reply from testbed: ok 147s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env [] 148s autopkgtest: DBG: testbed command exited with code 0 148s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env [] 148s autopkgtest: DBG: testbed command exited with code 0 148s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot', '/sbin/autopkgtest-reboot'], kind short, sout raw, serr pipe, env [] 148s autopkgtest: DBG: testbed command exited with code 0 148s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.e4rYCp'], kind short, sout raw, serr pipe, env [] 148s autopkgtest: DBG: testbed command exited with code 0 148s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.e4rYCp/autopkgtest-reboot-prepare 148s autopkgtest: DBG: got reply from testbed: ok 148s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env [] 149s autopkgtest: DBG: testbed command exited with code 0 149s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env [] 149s autopkgtest: DBG: testbed command exited with code 0 149s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env [] 149s autopkgtest: DBG: testbed command exited with code 0 149s autopkgtest [22:24:42]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 15:05:57 UTC 2025 149s autopkgtest: DBG: testbed command ['sh', '-c', 'nproc; cat /proc/cpuinfo 2>/dev/null || true'], kind short, sout pipe, serr pipe, env [] 149s autopkgtest: DBG: testbed command exited with code 0 149s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.e4rYCp/testbed-packages"], kind short, sout raw, serr pipe, env [] 149s autopkgtest: DBG: testbed command exited with code 0 149s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/testbed-packages /tmp/autopkgtest-work.m6ljzkb0/out/testbed-packages 150s autopkgtest: DBG: got reply from testbed: ok 150s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot 150s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.e4rYCp'], kind short, sout raw, serr pipe, env [] 150s autopkgtest: DBG: testbed command exited with code 0 150s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot.sh /tmp/autopkgtest.e4rYCp/autopkgtest-reboot 150s autopkgtest: DBG: got reply from testbed: ok 150s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env [] 150s autopkgtest: DBG: testbed command exited with code 0 150s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], kind short, sout raw, serr pipe, env [] 150s autopkgtest: DBG: testbed command exited with code 0 150s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot', '/sbin/autopkgtest-reboot'], kind short, sout raw, serr pipe, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.e4rYCp'], kind short, sout raw, serr pipe, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.e4rYCp/autopkgtest-reboot-prepare 151s autopkgtest: DBG: got reply from testbed: ok 151s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.e4rYCp/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: Binaries: initialising 151s autopkgtest [22:24:44]: @@@@@@@@@@@@@@@@@@@@ apt-source firebird3.0 151s autopkgtest: DBG: blame += firebird3.0 151s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=[] 151s autopkgtest: DBG: testbed command ['apt-cache', 'showsrc', '--only-source', 'firebird3.0'], kind short, sout pipe, serr pipe, env [] 152s autopkgtest: DBG: testbed command exited with code 0 152s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-examples$'], kind short, sout pipe, serr raw, env [] 152s autopkgtest: DBG: testbed command exited with code 0 152s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-examples=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 152s autopkgtest: DBG: testbed command exited with code 0 152s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-common-doc$'], kind short, sout pipe, serr raw, env [] 153s autopkgtest: DBG: testbed command exited with code 0 153s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-common-doc=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 153s autopkgtest: DBG: testbed command exited with code 0 153s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-common$'], kind short, sout pipe, serr raw, env [] 153s autopkgtest: DBG: testbed command exited with code 0 153s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-common=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 153s autopkgtest: DBG: testbed command exited with code 0 153s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-utils$'], kind short, sout pipe, serr raw, env [] 154s autopkgtest: DBG: testbed command exited with code 0 154s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-utils=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 154s autopkgtest: DBG: testbed command exited with code 0 154s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-server-core$'], kind short, sout pipe, serr raw, env [] 154s autopkgtest: DBG: testbed command exited with code 0 154s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-server-core=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 154s autopkgtest: DBG: testbed command exited with code 0 154s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-doc$'], kind short, sout pipe, serr raw, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-doc=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^firebird3\\.0-server$'], kind short, sout pipe, serr raw, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'firebird3.0-server=3.0.12.ds7-11'], kind short, sout pipe, serr raw, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: install_deps: deps_new=[] 155s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v dpkg-source'], kind short, sout pipe, serr pipe, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s 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.e4rYCp/build.XXX); cd $builddir; OUT=$(apt-get source -d -q --only-source firebird3.0=3.0.12.ds7-11 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 firebird3.0_*.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'] 155s + cd / 155s + mktemp -d /tmp/autopkgtest.e4rYCp/build.XXX 155s + builddir=/tmp/autopkgtest.e4rYCp/build.7hf 155s + cd /tmp/autopkgtest.e4rYCp/build.7hf 155s + apt-get source -d -q --only-source firebird3.0=3.0.12.ds7-11 157s + OUT=Reading package lists... 157s NOTICE: 'firebird3.0' packaging is maintained in the 'Git' version control system at: 157s https://salsa.debian.org/firebird-team/firebird3.0.git -b debian/experimental 157s Please use: 157s git clone https://salsa.debian.org/firebird-team/firebird3.0.git -b debian/experimental 157s to retrieve the latest (possibly unreleased) updates to the package. 157s Need to get 3537 kB of source archives. 157s Get:1 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (dsc) [2632 B] 157s Get:2 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (tar) [3425 kB] 157s Get:3 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (diff) [109 kB] 157s Fetched 3537 kB in 1s (2949 kB/s) 157s Download complete and in download only mode 157s + [ -n ] 157s + grep ^Get: 157s + echo Reading package lists... 157s NOTICE: 'firebird3.0' packaging is maintained in the 'Git' version control system at: 157s https://salsa.debian.org/firebird-team/firebird3.0.git -b debian/experimental 157s Please use: 157s git clone https://salsa.debian.org/firebird-team/firebird3.0.git -b debian/experimental 157s to retrieve the latest (possibly unreleased) updates to the package. 157s Need to get 3537 kB of source archives. 157s Get:1 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (dsc) [2632 B] 157s Get:2 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (tar) [3425 kB] 157s Get:3 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (diff) [109 kB] 157s Fetched 3537 kB in 1s (2949 kB/s) 157s Download complete and in download only mode 157s Get:1 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (dsc) [2632 B] 157s Get:2 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (tar) [3425 kB] 157s Get:3 http://ftpmaster.internal/ubuntu plucky/universe firebird3.0 3.0.12.ds7-11 (diff) [109 kB] 157s + dpkg-source -x firebird3.0_3.0.12.ds7-11.dsc src 157s gpgv: Signature made Sat Feb 8 11:43:11 2025 UTC 157s gpgv: using RSA key AEA0C44ECB056E93630D9D33DBBE9D4D99D2A004 157s gpgv: Can't check signature: No public key 157s dpkg-source: warning: cannot verify inline signature for ./firebird3.0_3.0.12.ds7-11.dsc: no acceptable signature found 157s + chmod -R a+rX . 157s + cd src/. 157s + pwd 157s + sed -n 1 {s/).*//; s/ (/\n/; p} debian/changelog 157s autopkgtest: DBG: testbed command exited with code 0 157s autopkgtest [22:24:50]: testing package firebird3.0 version 3.0.12.ds7-11 157s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/build.7hf/src/debian/ /tmp/autopkgtest-work.m6ljzkb0/out/pkg/debian/ 158s autopkgtest: DBG: got reply from testbed: ok 158s autopkgtest: DBG: processing dependency firebird3.0-server-core 158s autopkgtest: DBG: marked alternatives ['firebird3.0-server-core'] as a synthesised dependency 158s autopkgtest: DBG: processing dependency firebird3.0-utils 158s autopkgtest: DBG: marked alternatives ['firebird3.0-utils'] as a synthesised dependency 158s autopkgtest: DBG: processing dependency firebird3.0-examples 158s autopkgtest: DBG: marked alternatives ['firebird3.0-examples'] as a synthesised dependency 158s autopkgtest: DBG: Test defined: name assorted-tools path debian/tests/assorted-tools command "None" restrictions [] features [] depends ['firebird3.0-server-core', 'firebird3.0-utils', 'firebird3.0-examples'] 158s autopkgtest [22:24:51]: build not needed 158s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/build.7hf/src/ /tmp/autopkgtest-work.m6ljzkb0/out/tests-tree/ 160s autopkgtest: DBG: got reply from testbed: ok 160s autopkgtest: DBG: processing dependency firebird3.0-server-core 160s autopkgtest: DBG: marked alternatives ['firebird3.0-server-core'] as a synthesised dependency 160s autopkgtest: DBG: processing dependency firebird3.0-utils 160s autopkgtest: DBG: marked alternatives ['firebird3.0-utils'] as a synthesised dependency 160s autopkgtest: DBG: processing dependency firebird3.0-examples 160s autopkgtest: DBG: marked alternatives ['firebird3.0-examples'] as a synthesised dependency 160s autopkgtest: DBG: Test defined: name assorted-tools path debian/tests/assorted-tools command "None" restrictions [] features [] depends ['firebird3.0-server-core', 'firebird3.0-utils', 'firebird3.0-examples'] 160s autopkgtest [22:24:53]: test assorted-tools: preparing testbed 160s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=['firebird3.0-server-core', 'firebird3.0-utils', 'firebird3.0-examples'] 160s autopkgtest: DBG: Binaries: no registered binaries, not publishing anything 160s autopkgtest: DBG: install_deps: deps_new=['firebird3.0-server-core', 'firebird3.0-utils', 'firebird3.0-examples'] 160s autopkgtest: DBG: install-deps: satisfying firebird3.0-server-core, firebird3.0-utils, firebird3.0-examples 160s autopkgtest: DBG: can use apt-get on testbed: True 160s 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', 'firebird3.0-server-core, firebird3.0-utils, firebird3.0-examples'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none'] 160s Reading package lists... 160s Building dependency tree... 160s Reading state information... 160s Starting pkgProblemResolver with broken count: 0 160s Starting 2 pkgProblemResolver with broken count: 0 161s Done 161s The following NEW packages will be installed: 161s firebird-utils firebird3.0-common firebird3.0-common-doc 161s firebird3.0-examples firebird3.0-server-core firebird3.0-utils 161s firebird4.0-common firebird4.0-common-doc libfbclient2 libib-util 161s libtommath1 161s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 161s Need to get 5294 kB of archives. 161s After this operation, 17.0 MB of additional disk space will be used. 161s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird4.0-common-doc all 4.0.5.3140.ds6-15 [28.3 kB] 161s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird3.0-common-doc all 3.0.12.ds7-11 [30.5 kB] 161s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird3.0-common all 3.0.12.ds7-11 [17.2 kB] 161s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird4.0-common all 4.0.5.3140.ds6-15 [18.5 kB] 161s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 161s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x libfbclient2 s390x 4.0.5.3140.ds6-15 [970 kB] 161s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird3.0-utils s390x 3.0.12.ds7-11 [1081 kB] 162s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird-utils all 4.0.5.3140.ds6-15 [3714 B] 162s Get:9 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird3.0-examples all 3.0.12.ds7-11 [119 kB] 162s Get:10 http://ftpmaster.internal/ubuntu plucky/universe s390x libib-util s390x 4.0.5.3140.ds6-15 [3312 B] 162s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x firebird3.0-server-core s390x 3.0.12.ds7-11 [2964 kB] 163s Fetched 5294 kB in 2s (3268 kB/s) 163s Selecting previously unselected package firebird4.0-common-doc. 163s (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.) 163s Preparing to unpack .../00-firebird4.0-common-doc_4.0.5.3140.ds6-15_all.deb ... 163s Unpacking firebird4.0-common-doc (4.0.5.3140.ds6-15) ... 163s Selecting previously unselected package firebird3.0-common-doc. 163s Preparing to unpack .../01-firebird3.0-common-doc_3.0.12.ds7-11_all.deb ... 163s Unpacking firebird3.0-common-doc (3.0.12.ds7-11) ... 163s Selecting previously unselected package firebird3.0-common. 163s Preparing to unpack .../02-firebird3.0-common_3.0.12.ds7-11_all.deb ... 163s Unpacking firebird3.0-common (3.0.12.ds7-11) ... 163s Selecting previously unselected package firebird4.0-common. 163s Preparing to unpack .../03-firebird4.0-common_4.0.5.3140.ds6-15_all.deb ... 163s Unpacking firebird4.0-common (4.0.5.3140.ds6-15) ... 163s Selecting previously unselected package libtommath1:s390x. 163s Preparing to unpack .../04-libtommath1_1.3.0-1_s390x.deb ... 163s Unpacking libtommath1:s390x (1.3.0-1) ... 163s Selecting previously unselected package libfbclient2:s390x. 163s Preparing to unpack .../05-libfbclient2_4.0.5.3140.ds6-15_s390x.deb ... 163s Unpacking libfbclient2:s390x (4.0.5.3140.ds6-15) ... 163s Selecting previously unselected package firebird3.0-utils. 163s Preparing to unpack .../06-firebird3.0-utils_3.0.12.ds7-11_s390x.deb ... 163s Unpacking firebird3.0-utils (3.0.12.ds7-11) ... 163s Selecting previously unselected package firebird-utils. 163s Preparing to unpack .../07-firebird-utils_4.0.5.3140.ds6-15_all.deb ... 163s Unpacking firebird-utils (4.0.5.3140.ds6-15) ... 163s Selecting previously unselected package firebird3.0-examples. 163s Preparing to unpack .../08-firebird3.0-examples_3.0.12.ds7-11_all.deb ... 163s Unpacking firebird3.0-examples (3.0.12.ds7-11) ... 163s Selecting previously unselected package libib-util:s390x. 163s Preparing to unpack .../09-libib-util_4.0.5.3140.ds6-15_s390x.deb ... 163s Unpacking libib-util:s390x (4.0.5.3140.ds6-15) ... 163s Selecting previously unselected package firebird3.0-server-core:s390x. 163s Preparing to unpack .../10-firebird3.0-server-core_3.0.12.ds7-11_s390x.deb ... 163s Unpacking firebird3.0-server-core:s390x (3.0.12.ds7-11) ... 163s Setting up libtommath1:s390x (1.3.0-1) ... 163s Setting up firebird4.0-common-doc (4.0.5.3140.ds6-15) ... 163s Setting up firebird3.0-common-doc (3.0.12.ds7-11) ... 163s Setting up firebird3.0-common (3.0.12.ds7-11) ... 163s Configuring /etc/firebird/3.0/service-port.conf to use port 3050 163s Setting up firebird4.0-common (4.0.5.3140.ds6-15) ... 163s Configuring /etc/firebird/4.0/service-port.conf to use port 3051 163s Setting up libib-util:s390x (4.0.5.3140.ds6-15) ... 163s Setting up firebird3.0-examples (3.0.12.ds7-11) ... 163s Setting up libfbclient2:s390x (4.0.5.3140.ds6-15) ... 163s Setting up firebird3.0-server-core:s390x (3.0.12.ds7-11) ... 163s Setting up firebird-utils (4.0.5.3140.ds6-15) ... 163s Setting up firebird3.0-utils (3.0.12.ds7-11) ... 163s update-alternatives: using /usr/share/firebird/3.0/man/man8/firebird.8.gz to provide /usr/share/man/man8/firebird.8.gz (firebird) in auto mode 163s Processing triggers for libc-bin (2.41-1ubuntu1) ... 163s Processing triggers for man-db (2.13.0-1) ... 164s autopkgtest: DBG: testbed command exited with code 0 164s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'firebird3.0-server-core'], kind short, sout pipe, serr pipe, env [] 164s autopkgtest: DBG: testbed command exited with code 0 164s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'firebird3.0-utils'], kind short, sout pipe, serr pipe, env [] 164s autopkgtest: DBG: testbed command exited with code 0 164s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'firebird3.0-examples'], kind short, sout pipe, serr pipe, env [] 164s autopkgtest: DBG: testbed command exited with code 0 164s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.e4rYCp/assorted-tools-packages.all"], kind short, sout raw, serr pipe, env [] 165s autopkgtest: DBG: testbed command exited with code 0 165s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/assorted-tools-packages.all /tmp/autopkgtest-work.m6ljzkb0/out/assorted-tools-packages.all 165s autopkgtest: DBG: got reply from testbed: ok 165s autopkgtest: DBG: testbed command ['test', '-e', '/tmp/autopkgtest.e4rYCp/build.7hf/src'], kind short, sout raw, serr raw, env [] 165s autopkgtest: DBG: testbed command exited with code 0 165s autopkgtest: DBG: copydown: tb path /tmp/autopkgtest.e4rYCp/build.7hf/src already exists 165s autopkgtest [22:24:58]: test assorted-tools: [----------------------- 165s autopkgtest: DBG: testbed command ['su', '-s', '/bin/bash', 'ubuntu', '-c', "set -e; exec /tmp/autopkgtest.e4rYCp/wrapper.sh --debug --artifacts=/tmp/autopkgtest.e4rYCp/assorted-tools-artifacts --chdir=/tmp/autopkgtest.e4rYCp/build.7hf/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.e4rYCp/assorted-tools-stderr --stdout=/tmp/autopkgtest.e4rYCp/assorted-tools-stdout --tmp=/tmp/autopkgtest.e4rYCp/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' --make-executable=/tmp/autopkgtest.e4rYCp/build.7hf/src/debian/tests/assorted-tools -- /tmp/autopkgtest.e4rYCp/build.7hf/src/debian/tests/assorted-tools"], kind test, sout raw, serr raw, env [] 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: creating AUTOPKGTEST_ARTIFACTS: /tmp/autopkgtest.e4rYCp/assorted-tools-artifacts 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: changing to directory: /tmp/autopkgtest.e4rYCp/build.7hf/src 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: setting environment: AUTOPKGTEST_TESTBED_ARCH=s390x 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: setting environment: AUTOPKGTEST_TEST_ARCH=s390x 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: setting environment: DEB_BUILD_OPTIONS=parallel=2 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: setting environment: DEBIAN_FRONTEND=noninteractive 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: setting environment: LANG=C.UTF-8 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LANGUAGE 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_ADDRESS 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_ALL 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_COLLATE 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_CTYPE 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_IDENTIFICATION 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_MEASUREMENT 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_MESSAGES 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_MONETARY 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_NAME 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_NUMERIC 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_PAPER 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_TELEPHONE 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: unsetting environment: LC_TIME 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: will create pid file: /tmp/autopkgtest_script_pid 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: pretending to be a login shell 165s V: Creating employee.fdb via isql-fb -i 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: will write standard error to /tmp/autopkgtest.e4rYCp/assorted-tools-stderr 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: will write stdout to /tmp/autopkgtest.e4rYCp/assorted-tools-stdout 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: creating AUTOPKGTEST_TMP: /tmp/autopkgtest.e4rYCp/autopkgtest_tmp 165s /tmp/autopkgtest.e4rYCp/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 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: marking as executable: /tmp/autopkgtest.e4rYCp/build.7hf/src/debian/tests/assorted-tools 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: command to run: /tmp/autopkgtest.e4rYCp/build.7hf/src/debian/tests/assorted-tools 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: copying /tmp/tmp.mSVNcfSRQc/out to stdout and file: /tmp/autopkgtest.e4rYCp/assorted-tools-stdout 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: copying /tmp/tmp.mSVNcfSRQc/err to standard error and file: /tmp/autopkgtest.e4rYCp/assorted-tools-stdout 165s /tmp/autopkgtest.e4rYCp/wrapper.sh: writing script pid 1663 to /tmp/autopkgtest_script_pid 166s V: Backing up 166s V: Restoring the backup 166s V: Extracting definition of employee.fdb via isql-fb -a 166s V: Checking the size of employee.sql 166s -rw-rw-r-- 1 ubuntu ubuntu 21663 Feb 15 22:26 employee.sql 166s V: Running fbstat on employee.fdb 166s 166s Database "/tmp/autopkgtest.e4rYCp/assorted-tools-artifacts/employee.fdb" 166s Gstat execution time Sat Feb 15 22:26:25 2025 166s 166s Database header page information: 166s Flags 0 166s Generation 161 166s System Change Number 0 166s Page size 8192 166s ODS version 12.0 166s Oldest transaction 155 166s Oldest active 156 166s Oldest snapshot 156 166s Next transaction 156 166s Sequence number 0 166s Next attachment ID 9 166s Implementation HW=s390x big-endian OS=Linux CC=gcc 166s Shadow count 0 166s Page buffers 0 166s Next header page 0 166s Database dialect 3 166s Creation date Feb 15, 2025 22:26:24 166s Attributes force write 166s 166s Variable header data: 166s *END* 166s 166s 166s Database file sequence: 166s File /tmp/autopkgtest.e4rYCp/assorted-tools-artifacts/employee.fdb is the only file 166s 166s Analyzing database pages ... 166s COUNTRY (128) 166s Primary pointer page: 182, Index root page: 183 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 8% 166s Primary pages: 1, secondary pages: 0, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY1 (0) 166s Root page: 186, depth: 1, leaf buckets: 1, nodes: 16 166s Average node length: 10.44, total dup: 0, max dup: 0 166s Average key length: 8.62, compression ratio: 0.80 166s Average prefix length: 0.44, average data length: 6.44 166s Clustering factor: 1, ratio: 0.06 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s CUSTOMER (137) 166s Primary pointer page: 253, Index root page: 254 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 25% 166s Primary pages: 1, secondary pages: 0, swept pages: 1 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 0 166s 20 - 39% = 1 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index CUSTNAMEX (2) 166s Root page: 268, depth: 1, leaf buckets: 1, nodes: 15 166s Average node length: 19.87, total dup: 0, max dup: 0 166s Average key length: 18.27, compression ratio: 0.90 166s Average prefix length: 0.60, average data length: 15.87 166s Clustering factor: 1, ratio: 0.07 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index CUSTREGION (3) 166s Root page: 275, depth: 1, leaf buckets: 1, nodes: 15 166s Average node length: 21.27, total dup: 0, max dup: 0 166s Average key length: 20.20, compression ratio: 0.97 166s Average prefix length: 2.33, average data length: 17.27 166s Clustering factor: 1, ratio: 0.07 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN23 (1) 166s Root page: 256, depth: 1, leaf buckets: 1, nodes: 15 166s Average node length: 8.60, total dup: 4, max dup: 4 166s Average key length: 6.93, compression ratio: 0.83 166s Average prefix length: 0.87, average data length: 4.87 166s Clustering factor: 1, ratio: 0.07 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY22 (0) 166s Root page: 255, depth: 1, leaf buckets: 1, nodes: 15 166s Average node length: 4.20, total dup: 0, max dup: 0 166s Average key length: 3.13, compression ratio: 0.96 166s Average prefix length: 1.87, average data length: 1.13 166s Clustering factor: 1, ratio: 0.07 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s DEPARTMENT (130) 166s Primary pointer page: 206, Index root page: 207 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 23% 166s Primary pages: 1, secondary pages: 0, swept pages: 1 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 0 166s 20 - 39% = 1 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index BUDGETX (3) 166s Root page: 276, depth: 1, leaf buckets: 1, nodes: 21 166s Average node length: 9.05, total dup: 7, max dup: 3 166s Average key length: 8.00, compression ratio: 1.12 166s Average prefix length: 3.62, average data length: 5.38 166s Clustering factor: 1, ratio: 0.05 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$4 (0) 166s Root page: 208, depth: 1, leaf buckets: 1, nodes: 21 166s Average node length: 17.95, total dup: 0, max dup: 0 166s Average key length: 16.57, compression ratio: 1.16 166s Average prefix length: 5.29, average data length: 13.95 166s Clustering factor: 1, ratio: 0.05 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN10 (4) 166s Root page: 219, depth: 1, leaf buckets: 1, nodes: 21 166s Average node length: 4.29, total dup: 3, max dup: 3 166s Average key length: 3.24, compression ratio: 0.60 166s Average prefix length: 0.81, average data length: 1.14 166s Clustering factor: 1, ratio: 0.05 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN6 (2) 166s Root page: 210, depth: 1, leaf buckets: 1, nodes: 21 166s Average node length: 4.10, total dup: 13, max dup: 4 166s Average key length: 2.95, compression ratio: 0.97 166s Average prefix length: 2.05, average data length: 0.81 166s Clustering factor: 1, ratio: 0.05 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY5 (1) 166s Root page: 209, depth: 1, leaf buckets: 1, nodes: 21 166s Average node length: 5.24, total dup: 0, max dup: 0 166s Average key length: 4.05, compression ratio: 0.74 166s Average prefix length: 1.29, average data length: 1.71 166s Clustering factor: 1, ratio: 0.05 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s EMPLOYEE (131) 166s Primary pointer page: 212, Index root page: 213 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 42% 166s Primary pages: 1, secondary pages: 0, swept pages: 1 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 0 166s 20 - 39% = 0 166s 40 - 59% = 1 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index NAMEX (3) 166s Root page: 277, depth: 1, leaf buckets: 1, nodes: 42 166s Average node length: 19.52, total dup: 0, max dup: 0 166s Average key length: 18.50, compression ratio: 0.96 166s Average prefix length: 2.17, average data length: 15.52 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN8 (1) 166s Root page: 215, depth: 1, leaf buckets: 1, nodes: 42 166s Average node length: 4.07, total dup: 23, max dup: 4 166s Average key length: 2.98, compression ratio: 1.01 166s Average prefix length: 2.19, average data length: 0.81 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN9 (2) 166s Root page: 216, depth: 1, leaf buckets: 1, nodes: 42 166s Average node length: 10.43, total dup: 15, max dup: 4 166s Average key length: 9.40, compression ratio: 1.68 166s Average prefix length: 9.05, average data length: 6.79 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY7 (0) 166s Root page: 214, depth: 1, leaf buckets: 1, nodes: 42 166s Average node length: 4.62, total dup: 0, max dup: 0 166s Average key length: 3.60, compression ratio: 0.69 166s Average prefix length: 1.17, average data length: 1.31 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s EMPLOYEE_PROJECT (134) 166s Primary pointer page: 226, Index root page: 227 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 10% 166s Primary pages: 1, secondary pages: 0, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN15 (1) 166s Root page: 229, depth: 1, leaf buckets: 1, nodes: 28 166s Average node length: 4.29, total dup: 6, max dup: 2 166s Average key length: 3.25, compression ratio: 0.74 166s Average prefix length: 1.36, average data length: 1.04 166s Clustering factor: 1, ratio: 0.04 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN16 (2) 166s Root page: 230, depth: 1, leaf buckets: 1, nodes: 28 166s Average node length: 4.04, total dup: 23, max dup: 9 166s Average key length: 2.89, compression ratio: 1.73 166s Average prefix length: 4.14, average data length: 0.86 166s Clustering factor: 1, ratio: 0.04 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY14 (0) 166s Root page: 228, depth: 1, leaf buckets: 1, nodes: 28 166s Average node length: 13.11, total dup: 0, max dup: 0 166s Average key length: 12.07, compression ratio: 0.99 166s Average prefix length: 2.89, average data length: 9.11 166s Clustering factor: 1, ratio: 0.04 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s JOB (129) 166s Primary pointer page: 189, Index root page: 190 166s Pointer pages: 1, data page slots: 2 166s Data pages: 2, average fill: 43% 166s Primary pages: 1, secondary pages: 1, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 0 166s 20 - 39% = 1 166s 40 - 59% = 1 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index MAXSALX (2) 166s Root page: 278, depth: 1, leaf buckets: 1, nodes: 31 166s Average node length: 14.74, total dup: 5, max dup: 1 166s Average key length: 13.71, compression ratio: 1.37 166s Average prefix length: 7.87, average data length: 10.90 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index MINSALX (3) 166s Root page: 279, depth: 1, leaf buckets: 1, nodes: 31 166s Average node length: 14.06, total dup: 7, max dup: 2 166s Average key length: 13.03, compression ratio: 1.44 166s Average prefix length: 8.48, average data length: 10.29 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN3 (1) 166s Root page: 200, depth: 1, leaf buckets: 1, nodes: 31 166s Average node length: 4.61, total dup: 24, max dup: 20 166s Average key length: 3.39, compression ratio: 1.23 166s Average prefix length: 2.77, average data length: 1.39 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY2 (0) 166s Root page: 191, depth: 1, leaf buckets: 1, nodes: 31 166s Average node length: 14.45, total dup: 0, max dup: 0 166s Average key length: 13.42, compression ratio: 1.24 166s Average prefix length: 6.19, average data length: 10.45 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s PROJECT (133) 166s Primary pointer page: 220, Index root page: 221 166s Pointer pages: 1, data page slots: 2 166s Data pages: 2, average fill: 7% 166s Primary pages: 1, secondary pages: 1, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 2 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index PRODTYPEX (3) 166s Root page: 288, depth: 1, leaf buckets: 1, nodes: 6 166s Average node length: 26.50, total dup: 0, max dup: 0 166s Average key length: 25.33, compression ratio: 1.05 166s Average prefix length: 4.17, average data length: 22.50 166s Clustering factor: 1, ratio: 0.17 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$11 (0) 166s Root page: 222, depth: 1, leaf buckets: 1, nodes: 6 166s Average node length: 17.33, total dup: 0, max dup: 0 166s Average key length: 15.50, compression ratio: 0.88 166s Average prefix length: 0.33, average data length: 13.33 166s Clustering factor: 1, ratio: 0.17 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN13 (2) 166s Root page: 224, depth: 1, leaf buckets: 1, nodes: 6 166s Average node length: 4.67, total dup: 0, max dup: 0 166s Average key length: 3.50, compression ratio: 0.57 166s Average prefix length: 0.67, average data length: 1.33 166s Clustering factor: 1, ratio: 0.17 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY12 (1) 166s Root page: 223, depth: 1, leaf buckets: 1, nodes: 6 166s Average node length: 8.83, total dup: 0, max dup: 0 166s Average key length: 7.00, compression ratio: 0.71 166s Average prefix length: 0.17, average data length: 4.83 166s Clustering factor: 1, ratio: 0.17 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s PROJ_DEPT_BUDGET (135) 166s Primary pointer page: 231, Index root page: 240 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 11% 166s Primary pages: 1, secondary pages: 0, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN18 (1) 166s Root page: 242, depth: 1, leaf buckets: 1, nodes: 24 166s Average node length: 3.92, total dup: 15, max dup: 5 166s Average key length: 2.79, compression ratio: 1.07 166s Average prefix length: 2.29, average data length: 0.71 166s Clustering factor: 1, ratio: 0.04 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN19 (2) 166s Root page: 243, depth: 1, leaf buckets: 1, nodes: 24 166s Average node length: 4.21, total dup: 19, max dup: 8 166s Average key length: 3.04, compression ratio: 1.64 166s Average prefix length: 4.00, average data length: 1.00 166s Clustering factor: 1, ratio: 0.04 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY17 (0) 166s Root page: 241, depth: 1, leaf buckets: 1, nodes: 24 166s Average node length: 10.71, total dup: 0, max dup: 0 166s Average key length: 9.67, compression ratio: 1.97 166s Average prefix length: 12.17, average data length: 6.83 166s Clustering factor: 1, ratio: 0.04 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s SALARY_HISTORY (136) 166s Primary pointer page: 246, Index root page: 247 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 29% 166s Primary pages: 1, secondary pages: 0, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 0 166s 20 - 39% = 1 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index CHANGEX (2) 166s Root page: 289, depth: 1, leaf buckets: 1, nodes: 49 166s Average node length: 3.37, total dup: 46, max dup: 21 166s Average key length: 2.35, compression ratio: 2.98 166s Average prefix length: 6.69, average data length: 0.31 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN21 (1) 166s Root page: 249, depth: 1, leaf buckets: 1, nodes: 49 166s Average node length: 4.12, total dup: 16, max dup: 2 166s Average key length: 3.10, compression ratio: 0.75 166s Average prefix length: 1.43, average data length: 0.90 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY20 (0) 166s Root page: 248, depth: 1, leaf buckets: 1, nodes: 49 166s Average node length: 22.29, total dup: 0, max dup: 0 166s Average key length: 21.27, compression ratio: 1.06 166s Average prefix length: 4.31, average data length: 18.29 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index UPDATERX (3) 166s Root page: 290, depth: 1, leaf buckets: 1, nodes: 49 166s Average node length: 3.35, total dup: 46, max dup: 28 166s Average key length: 2.29, compression ratio: 2.48 166s Average prefix length: 5.39, average data length: 0.29 166s Clustering factor: 1, ratio: 0.02 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s SALES (138) 166s Primary pointer page: 259, Index root page: 260 166s Pointer pages: 1, data page slots: 1 166s Data pages: 1, average fill: 34% 166s Primary pages: 1, secondary pages: 0, swept pages: 0 166s Empty pages: 0, full pages: 0 166s Fill distribution: 166s 0 - 19% = 0 166s 20 - 39% = 1 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index NEEDX (3) 166s Root page: 291, depth: 1, leaf buckets: 1, nodes: 33 166s Average node length: 5.97, total dup: 11, max dup: 6 166s Average key length: 4.94, compression ratio: 1.10 166s Average prefix length: 2.88, average data length: 2.55 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index QTYX (4) 166s Root page: 292, depth: 1, leaf buckets: 1, nodes: 33 166s Average node length: 5.06, total dup: 11, max dup: 3 166s Average key length: 4.03, compression ratio: 3.23 166s Average prefix length: 11.18, average data length: 1.85 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN25 (1) 166s Root page: 262, depth: 1, leaf buckets: 1, nodes: 33 166s Average node length: 3.55, total dup: 18, max dup: 4 166s Average key length: 2.52, compression ratio: 1.19 166s Average prefix length: 2.48, average data length: 0.52 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$FOREIGN26 (2) 166s Root page: 263, depth: 1, leaf buckets: 1, nodes: 33 166s Average node length: 3.67, total dup: 25, max dup: 7 166s Average key length: 2.64, compression ratio: 1.01 166s Average prefix length: 2.21, average data length: 0.45 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index RDB$PRIMARY24 (0) 166s Root page: 261, depth: 1, leaf buckets: 1, nodes: 33 166s Average node length: 8.45, total dup: 0, max dup: 0 166s Average key length: 7.42, compression ratio: 1.08 166s Average prefix length: 3.52, average data length: 4.48 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Index SALESTATX (5) 166s Root page: 293, depth: 1, leaf buckets: 1, nodes: 33 166s Average node length: 4.06, total dup: 27, max dup: 14 166s Average key length: 3.03, compression ratio: 3.56 166s Average prefix length: 9.82, average data length: 0.97 166s Clustering factor: 1, ratio: 0.03 166s Fill distribution: 166s 0 - 19% = 1 166s 20 - 39% = 0 166s 40 - 59% = 0 166s 60 - 79% = 0 166s 80 - 99% = 0 166s 166s Gstat completion time Sat Feb 15 22:26:25 2025 166s 166s V: Running gfix -v 166s /tmp/autopkgtest.e4rYCp/wrapper.sh: checking for leaked background processes... 166s /tmp/autopkgtest.e4rYCp/wrapper.sh: waiting for tee/cat subprocesses... 166s /tmp/autopkgtest.e4rYCp/wrapper.sh: cleaning up... 166s /tmp/autopkgtest.e4rYCp/wrapper.sh: Exit status: 0 166s autopkgtest: DBG: testbed command exited with code 0 166s autopkgtest [22:24:59]: test assorted-tools: -----------------------] 166s autopkgtest: DBG: testbed executing test finished with exit status 0 166s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/assorted-tools-stdout /tmp/autopkgtest-work.m6ljzkb0/out/assorted-tools-stdout 167s autopkgtest: DBG: got reply from testbed: ok 167s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/assorted-tools-stderr /tmp/autopkgtest-work.m6ljzkb0/out/assorted-tools-stderr 167s autopkgtest: DBG: got reply from testbed: ok 167s assorted-tools PASS 167s autopkgtest [22:25:00]: test assorted-tools: - - - - - - - - - - results - - - - - - - - - - 167s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.e4rYCp/assorted-tools-artifacts/ /tmp/autopkgtest-work.m6ljzkb0/out/artifacts/ 168s autopkgtest: DBG: got reply from testbed: ok 168s autopkgtest: DBG: testbed command ['rm', '-rf', '/tmp/autopkgtest.e4rYCp/assorted-tools-artifacts', '/tmp/autopkgtest.e4rYCp/autopkgtest_tmp'], kind short, sout raw, serr pipe, env [] 168s autopkgtest: DBG: testbed command exited with code 0 168s autopkgtest: DBG: needs_reset, previously=False, requested by run_tests() line 230 168s autopkgtest [22:25:01]: @@@@@@@@@@@@@@@@@@@@ summary 168s assorted-tools PASS 168s autopkgtest: DBG: testbed stop 168s autopkgtest: DBG: testbed close, scratch=/tmp/autopkgtest.e4rYCp 168s autopkgtest: DBG: sending command to testbed: close 186s autopkgtest: DBG: got reply from testbed: ok 186s autopkgtest: DBG: sending command to testbed: quit 186s nova [W] Using flock in prodstack6-s390x 186s flock: timeout while waiting to get lock 186s Creating nova instance adt-plucky-s390x-firebird3.0-20250215-222212-juju-7f2275-prod-proposed-migration-environment-15-984086de-78aa-40d0-a4d2-a1d4b7e5d1d8 from image adt/ubuntu-plucky-s390x-server-20250215.img (UUID 099dc609-2061-4009-a65b-d554466baca0)... 186s nova [W] Timed out waiting for 799ccc65-8105-4a45-98c5-1b14d80d96f0 to get deleted.