0s autopkgtest: DBG: testbed init 0s autopkgtest [00:31:19]: starting date and time: 2025-02-16 00:31:19+0000 0s autopkgtest [00:31:19]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [00:31:19]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.sreybbfn/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 librdkafka --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-8.secgroup --name adt-plucky-s390x-librdkafka-20250216-003119-juju-7f2275-prod-proposed-migration-environment-15-4f42dd4f-7af8-4991-853f-3de641615ae6 --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 102s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest.z7lEof 102s autopkgtest: DBG: sending command to testbed: print-execute-command 102s autopkgtest: DBG: got reply from testbed: ok /tmp/autopkgtest-ssh.muukj197/runcmd 102s autopkgtest: DBG: sending command to testbed: capabilities 102s autopkgtest: DBG: got reply from testbed: ok revert-full-system reboot isolation-machine revert suggested-normal-user=ubuntu root-on-testbed 102s autopkgtest: DBG: testbed capabilities: ['revert-full-system', 'reboot', 'isolation-machine', 'revert', 'suggested-normal-user=ubuntu', 'root-on-testbed', 'has_internet'] 102s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.z7lEof'], kind short, sout raw, serr pipe, env [] 102s autopkgtest: DBG: testbed command exited with code 0 102s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/wrapper.sh /tmp/autopkgtest.z7lEof/wrapper.sh 103s autopkgtest: DBG: got reply from testbed: ok 103s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.z7lEof/wrapper.sh'], kind short, sout raw, serr pipe, env [] 103s autopkgtest: DBG: testbed command exited with code 0 103s autopkgtest: DBG: testbed command ['dpkg', '--print-architecture'], kind short, sout pipe, serr pipe, env [] 103s autopkgtest: DBG: testbed command exited with code 0 103s autopkgtest [00:33:02]: testbed dpkg architecture: s390x 103s autopkgtest: DBG: testbed command ['dpkg-query', '-W', '-f', '${Version}', 'apt'], kind short, sout pipe, serr pipe, env [] 103s autopkgtest: DBG: testbed command exited with code 0 103s autopkgtest [00:33:02]: testbed apt version: 2.9.28 103s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v eatmydata'], kind short, sout pipe, serr pipe, env [] 103s autopkgtest: DBG: testbed command exited with code 0 103s autopkgtest: DBG: testbed has eatmydata 103s autopkgtest: DBG: testbed command ['mkdir', '-p', '/etc/apt/preferences.d'], kind short, sout raw, serr pipe, env [] 104s autopkgtest: DBG: testbed command exited with code 0 104s autopkgtest [00:33:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 104s 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 [] 104s autopkgtest: DBG: testbed command exited with code 0 104s autopkgtest [00:33:03]: testbed release detected to be: None 104s 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 [] 104s autopkgtest: DBG: testbed command exited with code 0 104s 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 [] 104s autopkgtest: DBG: testbed command exited with code 0 104s autopkgtest: DBG: adding APT source: Types: deb deb-src 104s URIs: http://ftpmaster.internal/ubuntu/ 104s Suites: plucky-proposed 104s Components: main restricted universe multiverse 104s Signed-By: /usr/share/keyrings/ubuntu-archive-keyring.gpg 104s 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 [] 104s autopkgtest: DBG: testbed command exited with code 0 104s autopkgtest: DBG: adding APT preference to autopkgtest-zz-plucky-proposed-baseline.pref: 104s Package: * 104s Pin: release plucky-proposed 104s Pin-Priority: 500 104s 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 [] 104s autopkgtest: DBG: testbed command exited with code 0 104s autopkgtest [00:33:03]: updating testbed package index (apt update) 104s 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'] 105s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 105s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 105s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 105s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 105s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.1 kB] 105s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [823 kB] 105s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [73.1 kB] 105s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 105s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [164 kB] 105s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 105s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [871 kB] 106s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3740 B] 106s Fetched 2062 kB in 1s (1881 kB/s) 106s Reading package lists... 106s autopkgtest: DBG: testbed command exited with code 0 106s autopkgtest: DBG: adding APT preference to autopkgtest-plucky-proposed.pref: 106s Package: * 106s Pin: release plucky-proposed 106s Pin-Priority: 100 106s 106s Package: src:glibc:any src:iproute2:any src:php-twig:any src:postgresql-17:any src:postgresql-common:any src:roundcube:any 106s Pin: release plucky-proposed 106s Pin-Priority: 995 106s 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 [] 107s autopkgtest: DBG: testbed command exited with code 0 107s 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.z7lEof/${d//\\//_}.stamp; done'], kind short, sout raw, serr pipe, env [] 107s autopkgtest: DBG: testbed command exited with code 0 107s 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'] 107s + lsb_release --codename --short 107s + RELEASE=plucky 107s + cat 107s + [ plucky != trusty ] 107s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 107s Reading package lists... 107s Building dependency tree... 107s Reading state information... 107s Calculating upgrade... 107s The following packages were automatically installed and are no longer required: 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 107s Use 'sudo apt autoremove' to remove them. 107s The following packages will be upgraded: 107s dash gcc-14-base libatomic1 libgcc-s1 libstdc++6 libtasn1-6 libxdmcp6 107s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 107s Need to get 1155 kB of archives. 107s After this operation, 16.4 kB of additional disk space will be used. 107s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x dash s390x 0.5.12-12ubuntu1 [100 kB] 108s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libatomic1 s390x 14.2.0-17ubuntu1 [9430 B] 108s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-17ubuntu1 [53.5 kB] 108s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++6 s390x 14.2.0-17ubuntu1 [896 kB] 108s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-s1 s390x 14.2.0-17ubuntu1 [35.9 kB] 108s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libtasn1-6 s390x 4.20.0-2 [48.6 kB] 108s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libxdmcp6 s390x 1:1.1.5-1 [11.0 kB] 108s Preconfiguring packages ... 108s Fetched 1155 kB in 1s (1738 kB/s) 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 Preparing to unpack .../dash_0.5.12-12ubuntu1_s390x.deb ... 108s Unpacking dash (0.5.12-12ubuntu1) over (0.5.12-9ubuntu1) ... 108s Setting up dash (0.5.12-12ubuntu1) ... 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 Preparing to unpack .../libatomic1_14.2.0-17ubuntu1_s390x.deb ... 108s Unpacking libatomic1:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 108s Preparing to unpack .../gcc-14-base_14.2.0-17ubuntu1_s390x.deb ... 108s Unpacking gcc-14-base:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 108s Setting up gcc-14-base:s390x (14.2.0-17ubuntu1) ... 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 Preparing to unpack .../libstdc++6_14.2.0-17ubuntu1_s390x.deb ... 108s Unpacking libstdc++6:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 108s Setting up libstdc++6:s390x (14.2.0-17ubuntu1) ... 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 Preparing to unpack .../libgcc-s1_14.2.0-17ubuntu1_s390x.deb ... 108s Unpacking libgcc-s1:s390x (14.2.0-17ubuntu1) over (14.2.0-16ubuntu1) ... 108s Setting up libgcc-s1:s390x (14.2.0-17ubuntu1) ... 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 Preparing to unpack .../libtasn1-6_4.20.0-2_s390x.deb ... 108s Unpacking libtasn1-6:s390x (4.20.0-2) over (4.19.0-3build1) ... 109s Preparing to unpack .../libxdmcp6_1%3a1.1.5-1_s390x.deb ... 109s Unpacking libxdmcp6:s390x (1:1.1.5-1) over (1:1.1.3-0ubuntu6) ... 109s Setting up libxdmcp6:s390x (1:1.1.5-1) ... 109s Setting up libatomic1:s390x (14.2.0-17ubuntu1) ... 109s Setting up libtasn1-6:s390x (4.20.0-2) ... 109s Processing triggers for libc-bin (2.40-4ubuntu1) ... 109s Processing triggers for man-db (2.13.0-1) ... 109s Processing triggers for debianutils (5.21) ... 109s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 109s + /usr/lib/apt/apt-helper analyze-pattern ?true 109s + uname -r 109s + sed s/\./\\./g 109s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 109s + apt list ?obsolete 109s + tail+ cut -d/ -f1 109s -n+2 109s + grep -v ^linux-.*6\.12\.0-15-generic.* 109s + true 109s + obsolete_pkgs= 109s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 109s Reading package lists... 109s Building dependency tree... 109s Reading state information... 110s The following packages will be REMOVED: 110s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 110s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 110s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 110s linux-tools-6.11.0-8-generic* 110s 0 upgraded, 0 newly installed, 9 to remove and 6 not upgraded. 110s After this operation, 167 MB disk space will be freed. 110s (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.) 110s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 110s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 110s Removing libpython3.12t64:s390x (3.12.9-1) ... 110s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 110s Removing libnsl2:s390x (1.3.0-3build3) ... 110s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 110s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 110s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 111s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 111s Processing triggers for libc-bin (2.40-4ubuntu1) ... 111s (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.) 111s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 111s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 111s + grep -q trusty /etc/lsb-release 111s + [ ! -d /usr/share/doc/unattended-upgrades ] 111s + [ ! -d /usr/share/doc/lxd ] 111s + [ ! -d /usr/share/doc/lxd-client ] 111s + [ ! -d /usr/share/doc/snapd ] 111s + type iptables 111s + cat 111s + chmod 755 /etc/rc.local 111s + . /etc/rc.local 111s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 111s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 111s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 111s + uname -m 111s + [ s390x = ppc64le ] 111s + [ -d /run/systemd/system ] 111s + systemd-detect-virt --quiet --vm 111s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 111s + cat 111s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 111s + echo COMPRESS=lz4 111s autopkgtest: DBG: testbed command exited with code 0 111s autopkgtest [00:33:10]: upgrading testbed (apt dist-upgrade and autopurge) 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', 'dist-upgrade'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none'] 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 111s Starting 2 pkgProblemResolver with broken count: 0 111s Done 112s Entering ResolveByKeep 112s 112s The following packages will be upgraded: 112s iproute2 libc-bin libc-dev-bin libc6 libc6-dev locales 112s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 10.7 MB of archives. 112s After this operation, 305 kB of additional disk space will be used. 112s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu1 [24.3 kB] 112s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu1 [1679 kB] 112s 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 1s (10.3 MB/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): 114s cron: stopping...done. 114s 114s Unpacking libc6:s390x (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 114s Setting up libc6:s390x (2.41-1ubuntu1) ... 114s Checking for services that may need to be restarted... 114s Checking init scripts... 114s Restarting services possibly affected by the upgrade: 114s cron: restarting...done. 114s 114s Services restarted successfully. 114s (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.) 114s Preparing to unpack .../libc-bin_2.41-1ubuntu1_s390x.deb ... 114s Unpacking libc-bin (2.41-1ubuntu1) over (2.40-4ubuntu1) ... 114s Setting up libc-bin (2.41-1ubuntu1) ... 114s (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.) 114s Preparing to unpack .../iproute2_6.13.0-1ubuntu1_s390x.deb ... 114s Unpacking iproute2 (6.13.0-1ubuntu1) over (6.10.0-2ubuntu1) ... 114s Setting up iproute2 (6.13.0-1ubuntu1) ... 114s Setting up locales (2.41-1ubuntu1) ... 114s Installing new version of config file /etc/locale.alias ... 114s Generating locales (this might take a while)... 115s en_US.UTF-8... done 115s Generation complete. 115s Setting up libc-dev-bin (2.41-1ubuntu1) ... 115s Setting up libc6-dev:s390x (2.41-1ubuntu1) ... 115s Processing triggers for man-db (2.13.0-1) ... 116s Processing triggers for systemd (257.2-3ubuntu1) ... 117s autopkgtest: DBG: testbed command exited with code 0 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', '--purge', 'autoremove'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none'] 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 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 exited with code 0 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.z7lEof/${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 [00:33:16]: 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.z7lEof'], 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.z7lEof/autopkgtest-reboot 136s autopkgtest: DBG: got reply from testbed: ok 136s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.z7lEof/autopkgtest-reboot'], 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.z7lEof/autopkgtest-reboot', '/tmp/autopkgtest-reboot'], 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.z7lEof/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.z7lEof'], 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.z7lEof/autopkgtest-reboot-prepare 137s autopkgtest: DBG: got reply from testbed: ok 137s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.z7lEof/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env [] 137s autopkgtest: DBG: testbed command exited with code 0 137s autopkgtest: DBG: testbed command ['ln', '-fns', '/tmp/autopkgtest.z7lEof/autopkgtest-reboot-prepare', '/tmp/autopkgtest-reboot-prepare'], kind short, sout raw, serr pipe, env [] 137s autopkgtest: DBG: testbed command exited with code 0 137s autopkgtest: DBG: testbed command ['uname', '-srv'], kind short, sout pipe, serr pipe, env [] 137s autopkgtest: DBG: testbed command exited with code 0 137s autopkgtest [00:33:36]: 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.z7lEof/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.z7lEof/testbed-packages /tmp/autopkgtest-work.sreybbfn/out/testbed-packages 138s autopkgtest: DBG: got reply from testbed: ok 138s autopkgtest: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot 138s autopkgtest: DBG: testbed command ['mkdir', '-p', '/tmp/autopkgtest.z7lEof'], 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.z7lEof/autopkgtest-reboot 138s autopkgtest: DBG: got reply from testbed: ok 138s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.z7lEof/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.z7lEof/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.z7lEof/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.z7lEof'], kind short, sout raw, serr pipe, env [] 139s autopkgtest: DBG: testbed command exited with code 0 139s autopkgtest: DBG: sending command to testbed: copydown /home/ubuntu/autopkgtest/lib/in-testbed/reboot-prepare.sh /tmp/autopkgtest.z7lEof/autopkgtest-reboot-prepare 139s autopkgtest: DBG: got reply from testbed: ok 139s autopkgtest: DBG: testbed command ['chmod', '-R', '0755', '--', '/tmp/autopkgtest.z7lEof/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.z7lEof/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 [00:33:38]: @@@@@@@@@@@@@@@@@@@@ apt-source librdkafka 139s autopkgtest: DBG: blame += librdkafka 139s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=[] 139s autopkgtest: DBG: testbed command ['apt-cache', 'showsrc', '--only-source', 'librdkafka'], kind short, sout pipe, serr pipe, env [] 139s autopkgtest: DBG: testbed command exited with code 0 139s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^librdkafka1$'], kind short, sout pipe, serr raw, env [] 140s autopkgtest: DBG: testbed command exited with code 0 140s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'librdkafka1=2.6.0-1'], kind short, sout pipe, serr raw, env [] 140s autopkgtest: DBG: testbed command exited with code 0 140s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^librdkafka\\+\\+1$'], kind short, sout pipe, serr raw, env [] 140s autopkgtest: DBG: testbed command exited with code 0 140s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'librdkafka++1=2.6.0-1'], kind short, sout pipe, serr raw, env [] 140s autopkgtest: DBG: testbed command exited with code 0 140s autopkgtest: DBG: testbed command ['apt-cache', 'policy', '^librdkafka-dev$'], kind short, sout pipe, serr raw, env [] 141s autopkgtest: DBG: testbed command exited with code 0 141s autopkgtest: DBG: testbed command ['apt-cache', 'show', 'librdkafka-dev=2.6.0-1'], kind short, sout pipe, serr raw, env [] 141s autopkgtest: DBG: testbed command exited with code 0 141s autopkgtest: DBG: install_deps: deps_new=[] 141s autopkgtest: DBG: testbed command ['sh', '-ec', 'command -v dpkg-source'], kind short, sout pipe, serr pipe, env [] 141s autopkgtest: DBG: testbed command exited with code 0 141s 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.z7lEof/build.XXX); cd $builddir; OUT=$(apt-get source -d -q --only-source librdkafka=2.6.0-1 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 librdkafka_*.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'] 141s + cd / 141s + mktemp -d /tmp/autopkgtest.z7lEof/build.XXX 141s + builddir=/tmp/autopkgtest.z7lEof/build.dfd 141s + cd /tmp/autopkgtest.z7lEof/build.dfd 141s + apt-get source -d -q --only-source librdkafka=2.6.0-1 142s + OUT=Reading package lists... 142s NOTICE: 'librdkafka' packaging is maintained in the 'Git' version control system at: 142s https://salsa.debian.org/kafka-team/librdkafka.git 142s Please use: 142s git clone https://salsa.debian.org/kafka-team/librdkafka.git 142s to retrieve the latest (possibly unreleased) updates to the package. 142s Need to get 4569 kB of source archives. 142s Get:1 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (dsc) [2178 B] 142s Get:2 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (tar) [4554 kB] 142s Get:3 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (diff) [12.8 kB] 142s Fetched 4569 kB in 1s (5796 kB/s) 142s Download complete and in download only mode 142s + [ -n ] 142s + echo Reading package lists... 142s NOTICE: 'librdkafka' packaging is maintained in the 'Git' version control system at: 142s https://salsa.debian.org/kafka-team/librdkafka.git 142s Please use: 142s git clone https://salsa.debian.org/kafka-team/librdkafka.git 142s to retrieve the latest (possibly unreleased) updates to the package. 142s Need to get 4569 kB of source archives. 142s Get:1 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (dsc) [2178 B] 142s Get:2 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (tar) [4554 kB] 142s Get:3 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (diff) [12.8 kB] 142s Fetched 4569 kB in 1s (5796 kB/s) 142s Download complete and in download only mode 142s + grep ^Get: 142s Get:1 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (dsc) [2178 B] 142s Get:2 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (tar) [4554 kB] 142s Get:3 http://ftpmaster.internal/ubuntu plucky/universe librdkafka 2.6.0-1 (diff) [12.8 kB] 142s + dpkg-source -x librdkafka_2.6.0-1.dsc src 142s gpgv: Signature made Sat Oct 12 09:12:53 2024 UTC 142s gpgv: using RSA key A9592C521CB904077D6598009D0B5E5B1EEC8F0E 142s gpgv: Can't check signature: No public key 142s dpkg-source: warning: cannot verify inline signature for ./librdkafka_2.6.0-1.dsc: no acceptable signature found 142s + chmod -R a+rX . 142s + cd src/. 142s + pwd 142s + sed -n 1 {s/).*//; s/ (/\n/; p} debian/changelog 142s autopkgtest: DBG: testbed command exited with code 0 142s autopkgtest [00:33:41]: testing package librdkafka version 2.6.0-1 142s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/build.dfd/src/debian/ /tmp/autopkgtest-work.sreybbfn/out/pkg/debian/ 143s autopkgtest: DBG: got reply from testbed: ok 143s autopkgtest: DBG: processing dependency librdkafka-dev 143s autopkgtest: DBG: marked alternatives ['librdkafka-dev'] as a synthesised dependency 143s autopkgtest: DBG: processing dependency build-essential 143s autopkgtest: DBG: processing dependency pkgconf 143s autopkgtest: DBG: Test defined: name examples path debian/tests/examples command "None" restrictions ['allow-stderr'] features [] depends ['librdkafka-dev', 'build-essential', 'pkgconf'] 143s autopkgtest: DBG: processing dependency librdkafka-dev 143s autopkgtest: DBG: marked alternatives ['librdkafka-dev'] as a synthesised dependency 143s autopkgtest: DBG: processing dependency build-essential 143s autopkgtest: DBG: processing dependency pkgconf 143s autopkgtest: DBG: Test defined: name unittest path debian/tests/unittest command "None" restrictions ['allow-stderr'] features [] depends ['librdkafka-dev', 'build-essential', 'pkgconf'] 143s autopkgtest [00:33:42]: build not needed 143s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/build.dfd/src/ /tmp/autopkgtest-work.sreybbfn/out/tests-tree/ 144s autopkgtest: DBG: got reply from testbed: ok 144s autopkgtest: DBG: processing dependency librdkafka-dev 144s autopkgtest: DBG: marked alternatives ['librdkafka-dev'] as a synthesised dependency 144s autopkgtest: DBG: processing dependency build-essential 144s autopkgtest: DBG: processing dependency pkgconf 144s autopkgtest: DBG: Test defined: name examples path debian/tests/examples command "None" restrictions ['allow-stderr'] features [] depends ['librdkafka-dev', 'build-essential', 'pkgconf'] 144s autopkgtest: DBG: processing dependency librdkafka-dev 144s autopkgtest: DBG: marked alternatives ['librdkafka-dev'] as a synthesised dependency 144s autopkgtest: DBG: processing dependency build-essential 144s autopkgtest: DBG: processing dependency pkgconf 144s autopkgtest: DBG: Test defined: name unittest path debian/tests/unittest command "None" restrictions ['allow-stderr'] features [] depends ['librdkafka-dev', 'build-essential', 'pkgconf'] 144s autopkgtest [00:33:43]: test examples: preparing testbed 144s autopkgtest: DBG: testbed reset: modified=False, deps_installed=[], deps_new=['librdkafka-dev', 'build-essential', 'pkgconf'] 144s autopkgtest: DBG: Binaries: no registered binaries, not publishing anything 144s autopkgtest: DBG: install_deps: deps_new=['librdkafka-dev', 'build-essential', 'pkgconf'] 144s autopkgtest: DBG: install-deps: satisfying librdkafka-dev, build-essential, pkgconf 144s autopkgtest: DBG: can use apt-get on testbed: True 144s 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', 'librdkafka-dev, build-essential, pkgconf'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none'] 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 145s Starting pkgProblemResolver with broken count: 0 145s Starting 2 pkgProblemResolver with broken count: 0 145s Done 145s The following NEW packages will be installed: 145s build-essential comerr-dev cpp cpp-14 cpp-14-s390x-linux-gnu 145s cpp-s390x-linux-gnu g++ g++-14 g++-14-s390x-linux-gnu g++-s390x-linux-gnu 145s gcc gcc-14 gcc-14-s390x-linux-gnu gcc-s390x-linux-gnu krb5-multidev libasan8 145s libbrotli-dev libcc1-0 libcurl4-openssl-dev libevent-2.1-7t64 libgcc-14-dev 145s libgmp-dev libgmpxx4ldbl libgnutls-dane0t64 libgnutls-openssl27t64 145s libgnutls28-dev libgomp1 libgssrpc4t64 libidn2-dev libisl23 libitm1 145s libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 libkrb5-dev libldap-dev 145s liblz4-dev libmpc3 libnghttp2-dev libp11-kit-dev libpkgconf3 libpsl-dev 145s librdkafka++1 librdkafka-dev librdkafka1 librtmp-dev libsasl2-dev 145s libssh2-1-dev libssl-dev libstdc++-14-dev libtasn1-6-dev libubsan1 145s libunbound8 libxxhash-dev libzstd-dev nettle-dev pkgconf pkgconf-bin 145s zlib1g-dev 145s 0 upgraded, 59 newly installed, 0 to remove and 0 not upgraded. 145s Need to get 60.7 MB of archives. 145s After this operation, 204 MB of additional disk space will be used. 145s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libisl23 s390x 0.27-1 [704 kB] 145s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libmpc3 s390x 1.3.1-1build2 [57.8 kB] 145s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-14-s390x-linux-gnu s390x 14.2.0-17ubuntu1 [9571 kB] 146s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-14 s390x 14.2.0-17ubuntu1 [1030 B] 146s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [5556 B] 146s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x cpp s390x 4:14.2.0-1ubuntu1 [22.4 kB] 146s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libcc1-0 s390x 14.2.0-17ubuntu1 [50.7 kB] 146s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libgomp1 s390x 14.2.0-17ubuntu1 [151 kB] 146s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libitm1 s390x 14.2.0-17ubuntu1 [30.9 kB] 146s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libasan8 s390x 14.2.0-17ubuntu1 [2964 kB] 146s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libubsan1 s390x 14.2.0-17ubuntu1 [1184 kB] 146s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-14-dev s390x 14.2.0-17ubuntu1 [1037 kB] 146s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-s390x-linux-gnu s390x 14.2.0-17ubuntu1 [18.7 MB] 146s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14 s390x 14.2.0-17ubuntu1 [526 kB] 146s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [1204 B] 146s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x gcc s390x 4:14.2.0-1ubuntu1 [5004 B] 146s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++-14-dev s390x 14.2.0-17ubuntu1 [2611 kB] 146s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x g++-14-s390x-linux-gnu s390x 14.2.0-17ubuntu1 [11.0 MB] 147s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x g++-14 s390x 14.2.0-17ubuntu1 [21.8 kB] 147s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x g++-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [956 B] 147s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x g++ s390x 4:14.2.0-1ubuntu1 [1080 B] 147s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 147s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x comerr-dev s390x 2.1-1.47.2-1ubuntu1 [44.7 kB] 147s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu1 [59.1 kB] 147s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu1 [40.5 kB] 147s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu1 [41.9 kB] 147s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu1 [55.6 kB] 147s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-multidev s390x 1.21.3-4ubuntu1 [125 kB] 147s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libbrotli-dev s390x 1.1.0-2build3 [395 kB] 147s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x libidn2-dev s390x 2.3.7-2build2 [122 kB] 147s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libkrb5-dev s390x 1.21.3-4ubuntu1 [11.9 kB] 147s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x libldap-dev s390x 2.6.9+dfsg-1~exp2ubuntu1 [316 kB] 147s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x libpkgconf3 s390x 1.8.1-4 [31.2 kB] 147s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x pkgconf-bin s390x 1.8.1-4 [21.5 kB] 147s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x pkgconf s390x 1.8.1-4 [16.7 kB] 147s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x libnghttp2-dev s390x 1.64.0-1 [121 kB] 147s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libpsl-dev s390x 0.21.2-1.1build1 [77.3 kB] 147s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x libgmpxx4ldbl s390x 2:6.3.0+dfsg-2ubuntu7 [10.1 kB] 147s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x libgmp-dev s390x 2:6.3.0+dfsg-2ubuntu7 [424 kB] 147s Get:40 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 147s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x libunbound8 s390x 1.22.0-1ubuntu1 [462 kB] 147s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x libgnutls-dane0t64 s390x 3.8.9-2ubuntu1 [24.5 kB] 147s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x libgnutls-openssl27t64 s390x 3.8.9-2ubuntu1 [24.5 kB] 147s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x libp11-kit-dev s390x 0.25.5-2ubuntu1 [22.7 kB] 147s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x libtasn1-6-dev s390x 4.20.0-2 [94.8 kB] 147s Get:46 http://ftpmaster.internal/ubuntu plucky/main s390x nettle-dev s390x 3.10-1 [1197 kB] 147s Get:47 http://ftpmaster.internal/ubuntu plucky/main s390x libgnutls28-dev s390x 3.8.9-2ubuntu1 [1016 kB] 147s Get:48 http://ftpmaster.internal/ubuntu plucky/main s390x zlib1g-dev s390x 1:1.3.dfsg+really1.3.1-1ubuntu1 [898 kB] 147s Get:49 http://ftpmaster.internal/ubuntu plucky/main s390x librtmp-dev s390x 2.4+20151223.gitfa8646d.1-2build7 [69.2 kB] 147s Get:50 http://ftpmaster.internal/ubuntu plucky/main s390x libssl-dev s390x 3.4.0-1ubuntu2 [2427 kB] 147s Get:51 http://ftpmaster.internal/ubuntu plucky/main s390x libssh2-1-dev s390x 1.11.1-1 [287 kB] 147s Get:52 http://ftpmaster.internal/ubuntu plucky/main s390x libzstd-dev s390x 1.5.6+dfsg-2 [408 kB] 147s Get:53 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl4-openssl-dev s390x 8.12.0+git20250209.89ed161+ds-1ubuntu1 [513 kB] 147s Get:54 http://ftpmaster.internal/ubuntu plucky/universe s390x librdkafka1 s390x 2.6.0-1 [901 kB] 147s Get:55 http://ftpmaster.internal/ubuntu plucky/universe s390x librdkafka++1 s390x 2.6.0-1 [44.4 kB] 147s Get:56 http://ftpmaster.internal/ubuntu plucky/main s390x libxxhash-dev s390x 0.8.2-2build1 [82.5 kB] 147s Get:57 http://ftpmaster.internal/ubuntu plucky/main s390x liblz4-dev s390x 1.10.0-3 [110 kB] 147s Get:58 http://ftpmaster.internal/ubuntu plucky/main s390x libsasl2-dev s390x 2.1.28+dfsg1-8build1 [260 kB] 147s Get:59 http://ftpmaster.internal/ubuntu plucky/universe s390x librdkafka-dev s390x 2.6.0-1 [1102 kB] 148s Fetched 60.7 MB in 3s (23.9 MB/s) 148s Selecting previously unselected package libisl23:s390x. 148s (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.) 148s Preparing to unpack .../00-libisl23_0.27-1_s390x.deb ... 148s Unpacking libisl23:s390x (0.27-1) ... 148s Selecting previously unselected package libmpc3:s390x. 148s Preparing to unpack .../01-libmpc3_1.3.1-1build2_s390x.deb ... 148s Unpacking libmpc3:s390x (1.3.1-1build2) ... 148s Selecting previously unselected package cpp-14-s390x-linux-gnu. 148s Preparing to unpack .../02-cpp-14-s390x-linux-gnu_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking cpp-14-s390x-linux-gnu (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package cpp-14. 148s Preparing to unpack .../03-cpp-14_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking cpp-14 (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package cpp-s390x-linux-gnu. 148s Preparing to unpack .../04-cpp-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 148s Unpacking cpp-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 148s Selecting previously unselected package cpp. 148s Preparing to unpack .../05-cpp_4%3a14.2.0-1ubuntu1_s390x.deb ... 148s Unpacking cpp (4:14.2.0-1ubuntu1) ... 148s Selecting previously unselected package libcc1-0:s390x. 148s Preparing to unpack .../06-libcc1-0_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libcc1-0:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package libgomp1:s390x. 148s Preparing to unpack .../07-libgomp1_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libgomp1:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package libitm1:s390x. 148s Preparing to unpack .../08-libitm1_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libitm1:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package libasan8:s390x. 148s Preparing to unpack .../09-libasan8_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libasan8:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package libubsan1:s390x. 148s Preparing to unpack .../10-libubsan1_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libubsan1:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package libgcc-14-dev:s390x. 148s Preparing to unpack .../11-libgcc-14-dev_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libgcc-14-dev:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package gcc-14-s390x-linux-gnu. 148s Preparing to unpack .../12-gcc-14-s390x-linux-gnu_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking gcc-14-s390x-linux-gnu (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package gcc-14. 148s Preparing to unpack .../13-gcc-14_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking gcc-14 (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package gcc-s390x-linux-gnu. 148s Preparing to unpack .../14-gcc-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 148s Unpacking gcc-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 148s Selecting previously unselected package gcc. 148s Preparing to unpack .../15-gcc_4%3a14.2.0-1ubuntu1_s390x.deb ... 148s Unpacking gcc (4:14.2.0-1ubuntu1) ... 148s Selecting previously unselected package libstdc++-14-dev:s390x. 148s Preparing to unpack .../16-libstdc++-14-dev_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking libstdc++-14-dev:s390x (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package g++-14-s390x-linux-gnu. 148s Preparing to unpack .../17-g++-14-s390x-linux-gnu_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking g++-14-s390x-linux-gnu (14.2.0-17ubuntu1) ... 148s Selecting previously unselected package g++-14. 148s Preparing to unpack .../18-g++-14_14.2.0-17ubuntu1_s390x.deb ... 148s Unpacking g++-14 (14.2.0-17ubuntu1) ... 149s Selecting previously unselected package g++-s390x-linux-gnu. 149s Preparing to unpack .../19-g++-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 149s Unpacking g++-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 149s Selecting previously unselected package g++. 149s Preparing to unpack .../20-g++_4%3a14.2.0-1ubuntu1_s390x.deb ... 149s Unpacking g++ (4:14.2.0-1ubuntu1) ... 149s Selecting previously unselected package build-essential. 149s Preparing to unpack .../21-build-essential_12.10ubuntu1_s390x.deb ... 149s Unpacking build-essential (12.10ubuntu1) ... 149s Selecting previously unselected package comerr-dev:s390x. 149s Preparing to unpack .../22-comerr-dev_2.1-1.47.2-1ubuntu1_s390x.deb ... 149s Unpacking comerr-dev:s390x (2.1-1.47.2-1ubuntu1) ... 149s Selecting previously unselected package libgssrpc4t64:s390x. 149s Preparing to unpack .../23-libgssrpc4t64_1.21.3-4ubuntu1_s390x.deb ... 149s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 149s Selecting previously unselected package libkadm5clnt-mit12:s390x. 149s Preparing to unpack .../24-libkadm5clnt-mit12_1.21.3-4ubuntu1_s390x.deb ... 149s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 149s Selecting previously unselected package libkdb5-10t64:s390x. 149s Preparing to unpack .../25-libkdb5-10t64_1.21.3-4ubuntu1_s390x.deb ... 149s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 149s Selecting previously unselected package libkadm5srv-mit12:s390x. 149s Preparing to unpack .../26-libkadm5srv-mit12_1.21.3-4ubuntu1_s390x.deb ... 149s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 149s Selecting previously unselected package krb5-multidev:s390x. 149s Preparing to unpack .../27-krb5-multidev_1.21.3-4ubuntu1_s390x.deb ... 149s Unpacking krb5-multidev:s390x (1.21.3-4ubuntu1) ... 149s Selecting previously unselected package libbrotli-dev:s390x. 149s Preparing to unpack .../28-libbrotli-dev_1.1.0-2build3_s390x.deb ... 149s Unpacking libbrotli-dev:s390x (1.1.0-2build3) ... 149s Selecting previously unselected package libidn2-dev:s390x. 149s Preparing to unpack .../29-libidn2-dev_2.3.7-2build2_s390x.deb ... 149s Unpacking libidn2-dev:s390x (2.3.7-2build2) ... 149s Selecting previously unselected package libkrb5-dev:s390x. 149s Preparing to unpack .../30-libkrb5-dev_1.21.3-4ubuntu1_s390x.deb ... 149s Unpacking libkrb5-dev:s390x (1.21.3-4ubuntu1) ... 149s Selecting previously unselected package libldap-dev:s390x. 149s Preparing to unpack .../31-libldap-dev_2.6.9+dfsg-1~exp2ubuntu1_s390x.deb ... 149s Unpacking libldap-dev:s390x (2.6.9+dfsg-1~exp2ubuntu1) ... 149s Selecting previously unselected package libpkgconf3:s390x. 149s Preparing to unpack .../32-libpkgconf3_1.8.1-4_s390x.deb ... 149s Unpacking libpkgconf3:s390x (1.8.1-4) ... 149s Selecting previously unselected package pkgconf-bin. 149s Preparing to unpack .../33-pkgconf-bin_1.8.1-4_s390x.deb ... 149s Unpacking pkgconf-bin (1.8.1-4) ... 149s Selecting previously unselected package pkgconf:s390x. 149s Preparing to unpack .../34-pkgconf_1.8.1-4_s390x.deb ... 149s Unpacking pkgconf:s390x (1.8.1-4) ... 149s Selecting previously unselected package libnghttp2-dev:s390x. 149s Preparing to unpack .../35-libnghttp2-dev_1.64.0-1_s390x.deb ... 149s Unpacking libnghttp2-dev:s390x (1.64.0-1) ... 149s Selecting previously unselected package libpsl-dev:s390x. 149s Preparing to unpack .../36-libpsl-dev_0.21.2-1.1build1_s390x.deb ... 149s Unpacking libpsl-dev:s390x (0.21.2-1.1build1) ... 149s Selecting previously unselected package libgmpxx4ldbl:s390x. 149s Preparing to unpack .../37-libgmpxx4ldbl_2%3a6.3.0+dfsg-2ubuntu7_s390x.deb ... 149s Unpacking libgmpxx4ldbl:s390x (2:6.3.0+dfsg-2ubuntu7) ... 149s Selecting previously unselected package libgmp-dev:s390x. 149s Preparing to unpack .../38-libgmp-dev_2%3a6.3.0+dfsg-2ubuntu7_s390x.deb ... 149s Unpacking libgmp-dev:s390x (2:6.3.0+dfsg-2ubuntu7) ... 149s Selecting previously unselected package libevent-2.1-7t64:s390x. 149s Preparing to unpack .../39-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 149s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 149s Selecting previously unselected package libunbound8:s390x. 149s Preparing to unpack .../40-libunbound8_1.22.0-1ubuntu1_s390x.deb ... 149s Unpacking libunbound8:s390x (1.22.0-1ubuntu1) ... 149s Selecting previously unselected package libgnutls-dane0t64:s390x. 149s Preparing to unpack .../41-libgnutls-dane0t64_3.8.9-2ubuntu1_s390x.deb ... 149s Unpacking libgnutls-dane0t64:s390x (3.8.9-2ubuntu1) ... 149s Selecting previously unselected package libgnutls-openssl27t64:s390x. 149s Preparing to unpack .../42-libgnutls-openssl27t64_3.8.9-2ubuntu1_s390x.deb ... 149s Unpacking libgnutls-openssl27t64:s390x (3.8.9-2ubuntu1) ... 149s Selecting previously unselected package libp11-kit-dev:s390x. 149s Preparing to unpack .../43-libp11-kit-dev_0.25.5-2ubuntu1_s390x.deb ... 149s Unpacking libp11-kit-dev:s390x (0.25.5-2ubuntu1) ... 149s Selecting previously unselected package libtasn1-6-dev:s390x. 149s Preparing to unpack .../44-libtasn1-6-dev_4.20.0-2_s390x.deb ... 149s Unpacking libtasn1-6-dev:s390x (4.20.0-2) ... 149s Selecting previously unselected package nettle-dev:s390x. 149s Preparing to unpack .../45-nettle-dev_3.10-1_s390x.deb ... 149s Unpacking nettle-dev:s390x (3.10-1) ... 149s Selecting previously unselected package libgnutls28-dev:s390x. 149s Preparing to unpack .../46-libgnutls28-dev_3.8.9-2ubuntu1_s390x.deb ... 149s Unpacking libgnutls28-dev:s390x (3.8.9-2ubuntu1) ... 149s Selecting previously unselected package zlib1g-dev:s390x. 149s Preparing to unpack .../47-zlib1g-dev_1%3a1.3.dfsg+really1.3.1-1ubuntu1_s390x.deb ... 149s Unpacking zlib1g-dev:s390x (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 149s Selecting previously unselected package librtmp-dev:s390x. 149s Preparing to unpack .../48-librtmp-dev_2.4+20151223.gitfa8646d.1-2build7_s390x.deb ... 149s Unpacking librtmp-dev:s390x (2.4+20151223.gitfa8646d.1-2build7) ... 149s Selecting previously unselected package libssl-dev:s390x. 149s Preparing to unpack .../49-libssl-dev_3.4.0-1ubuntu2_s390x.deb ... 149s Unpacking libssl-dev:s390x (3.4.0-1ubuntu2) ... 149s Selecting previously unselected package libssh2-1-dev:s390x. 149s Preparing to unpack .../50-libssh2-1-dev_1.11.1-1_s390x.deb ... 149s Unpacking libssh2-1-dev:s390x (1.11.1-1) ... 149s Selecting previously unselected package libzstd-dev:s390x. 149s Preparing to unpack .../51-libzstd-dev_1.5.6+dfsg-2_s390x.deb ... 149s Unpacking libzstd-dev:s390x (1.5.6+dfsg-2) ... 149s Selecting previously unselected package libcurl4-openssl-dev:s390x. 149s Preparing to unpack .../52-libcurl4-openssl-dev_8.12.0+git20250209.89ed161+ds-1ubuntu1_s390x.deb ... 149s Unpacking libcurl4-openssl-dev:s390x (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 149s Selecting previously unselected package librdkafka1:s390x. 149s Preparing to unpack .../53-librdkafka1_2.6.0-1_s390x.deb ... 149s Unpacking librdkafka1:s390x (2.6.0-1) ... 149s Selecting previously unselected package librdkafka++1:s390x. 149s Preparing to unpack .../54-librdkafka++1_2.6.0-1_s390x.deb ... 149s Unpacking librdkafka++1:s390x (2.6.0-1) ... 149s Selecting previously unselected package libxxhash-dev:s390x. 149s Preparing to unpack .../55-libxxhash-dev_0.8.2-2build1_s390x.deb ... 149s Unpacking libxxhash-dev:s390x (0.8.2-2build1) ... 149s Selecting previously unselected package liblz4-dev:s390x. 149s Preparing to unpack .../56-liblz4-dev_1.10.0-3_s390x.deb ... 149s Unpacking liblz4-dev:s390x (1.10.0-3) ... 149s Selecting previously unselected package libsasl2-dev. 149s Preparing to unpack .../57-libsasl2-dev_2.1.28+dfsg1-8build1_s390x.deb ... 149s Unpacking libsasl2-dev (2.1.28+dfsg1-8build1) ... 149s Selecting previously unselected package librdkafka-dev:s390x. 149s Preparing to unpack .../58-librdkafka-dev_2.6.0-1_s390x.deb ... 149s Unpacking librdkafka-dev:s390x (2.6.0-1) ... 149s Setting up libgnutls-openssl27t64:s390x (3.8.9-2ubuntu1) ... 149s Setting up libzstd-dev:s390x (1.5.6+dfsg-2) ... 149s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 149s Setting up libgomp1:s390x (14.2.0-17ubuntu1) ... 149s Setting up libpsl-dev:s390x (0.21.2-1.1build1) ... 149s Setting up libunbound8:s390x (1.22.0-1ubuntu1) ... 149s Setting up libpkgconf3:s390x (1.8.1-4) ... 149s Setting up libgmpxx4ldbl:s390x (2:6.3.0+dfsg-2ubuntu7) ... 149s Setting up libgnutls-dane0t64:s390x (3.8.9-2ubuntu1) ... 149s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 149s Setting up libldap-dev:s390x (2.6.9+dfsg-1~exp2ubuntu1) ... 149s Setting up comerr-dev:s390x (2.1-1.47.2-1ubuntu1) ... 149s Setting up libssl-dev:s390x (3.4.0-1ubuntu2) ... 149s Setting up libmpc3:s390x (1.3.1-1build2) ... 149s Setting up pkgconf-bin (1.8.1-4) ... 149s Setting up libidn2-dev:s390x (2.3.7-2build2) ... 149s Setting up librdkafka1:s390x (2.6.0-1) ... 149s Setting up libxxhash-dev:s390x (0.8.2-2build1) ... 149s Setting up libubsan1:s390x (14.2.0-17ubuntu1) ... 149s Setting up zlib1g-dev:s390x (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 149s Setting up libasan8:s390x (14.2.0-17ubuntu1) ... 149s Setting up libisl23:s390x (0.27-1) ... 149s Setting up libtasn1-6-dev:s390x (4.20.0-2) ... 149s Setting up libcc1-0:s390x (14.2.0-17ubuntu1) ... 149s Setting up libbrotli-dev:s390x (1.1.0-2build3) ... 149s Setting up libp11-kit-dev:s390x (0.25.5-2ubuntu1) ... 149s Setting up libitm1:s390x (14.2.0-17ubuntu1) ... 149s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 149s Setting up libgmp-dev:s390x (2:6.3.0+dfsg-2ubuntu7) ... 149s Setting up nettle-dev:s390x (3.10-1) ... 149s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 149s Setting up librdkafka++1:s390x (2.6.0-1) ... 149s Setting up pkgconf:s390x (1.8.1-4) ... 149s Setting up libsasl2-dev (2.1.28+dfsg1-8build1) ... 149s Setting up liblz4-dev:s390x (1.10.0-3) ... 149s Setting up libssh2-1-dev:s390x (1.11.1-1) ... 149s Setting up cpp-14-s390x-linux-gnu (14.2.0-17ubuntu1) ... 149s Setting up cpp-14 (14.2.0-17ubuntu1) ... 149s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 149s Setting up libgcc-14-dev:s390x (14.2.0-17ubuntu1) ... 149s Setting up libstdc++-14-dev:s390x (14.2.0-17ubuntu1) ... 149s Setting up krb5-multidev:s390x (1.21.3-4ubuntu1) ... 149s Setting up libgnutls28-dev:s390x (3.8.9-2ubuntu1) ... 149s Setting up libnghttp2-dev:s390x (1.64.0-1) ... 149s Setting up cpp-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 149s Setting up libkrb5-dev:s390x (1.21.3-4ubuntu1) ... 149s Setting up gcc-14-s390x-linux-gnu (14.2.0-17ubuntu1) ... 149s Setting up gcc-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 149s Setting up librtmp-dev:s390x (2.4+20151223.gitfa8646d.1-2build7) ... 149s Setting up g++-14-s390x-linux-gnu (14.2.0-17ubuntu1) ... 149s Setting up cpp (4:14.2.0-1ubuntu1) ... 149s Setting up g++-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 149s Setting up gcc-14 (14.2.0-17ubuntu1) ... 149s Setting up libcurl4-openssl-dev:s390x (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 149s Setting up librdkafka-dev:s390x (2.6.0-1) ... 149s Setting up g++-14 (14.2.0-17ubuntu1) ... 149s Setting up gcc (4:14.2.0-1ubuntu1) ... 149s Setting up g++ (4:14.2.0-1ubuntu1) ... 149s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 149s Setting up build-essential (12.10ubuntu1) ... 149s Processing triggers for libc-bin (2.41-1ubuntu1) ... 149s Processing triggers for man-db (2.13.0-1) ... 150s Processing triggers for install-info (7.1.1-1) ... 150s autopkgtest: DBG: testbed command exited with code 0 150s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'librdkafka-dev'], kind short, sout pipe, serr pipe, env [] 150s autopkgtest: DBG: testbed command exited with code 0 150s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.z7lEof/examples-packages.all"], kind short, sout raw, serr pipe, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/examples-packages.all /tmp/autopkgtest-work.sreybbfn/out/examples-packages.all 151s autopkgtest: DBG: got reply from testbed: ok 151s autopkgtest: DBG: testbed command ['test', '-e', '/tmp/autopkgtest.z7lEof/build.dfd/src'], kind short, sout raw, serr raw, env [] 151s autopkgtest: DBG: testbed command exited with code 0 151s autopkgtest: DBG: copydown: tb path /tmp/autopkgtest.z7lEof/build.dfd/src already exists 151s autopkgtest [00:33:50]: test examples: [----------------------- 151s autopkgtest: DBG: testbed command ['su', '-s', '/bin/bash', 'ubuntu', '-c', "set -e; exec /tmp/autopkgtest.z7lEof/wrapper.sh --debug --artifacts=/tmp/autopkgtest.z7lEof/examples-artifacts --chdir=/tmp/autopkgtest.z7lEof/build.dfd/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.z7lEof/examples-stderr --stdout=/tmp/autopkgtest.z7lEof/examples-stdout --tmp=/tmp/autopkgtest.z7lEof/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.z7lEof/build.dfd/src/debian/tests/examples -- /tmp/autopkgtest.z7lEof/build.dfd/src/debian/tests/examples"], kind test, sout raw, serr raw, env [] 151s /tmp/autopkgtest.z7lEof/wrapper.sh: creating AUTOPKGTEST_ARTIFACTS: /tmp/autopkgtest.z7lEof/examples-artifacts 151s /tmp/autopkgtest.z7lEof/wrapper.sh: changing to directory: /tmp/autopkgtest.z7lEof/build.dfd/src 151s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: AUTOPKGTEST_TESTBED_ARCH=s390x 151s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: AUTOPKGTEST_TEST_ARCH=s390x 151s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: DEB_BUILD_OPTIONS=parallel=2 151s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: DEBIAN_FRONTEND=noninteractive 151s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: LANG=C.UTF-8 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LANGUAGE 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_ADDRESS 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_ALL 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_COLLATE 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_CTYPE 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_IDENTIFICATION 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_MEASUREMENT 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_MESSAGES 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_MONETARY 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_NAME 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_NUMERIC 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_PAPER 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_TELEPHONE 151s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_TIME 151s /tmp/autopkgtest.z7lEof/wrapper.sh: will create pid file: /tmp/autopkgtest_script_pid 151s /tmp/autopkgtest.z7lEof/wrapper.sh: pretending to be a login shell 151s /tmp/autopkgtest.z7lEof/wrapper.sh: will write standard error to /tmp/autopkgtest.z7lEof/examples-stderr 151s /tmp/autopkgtest.z7lEof/wrapper.sh: will write stdout to /tmp/autopkgtest.z7lEof/examples-stdout 151s /tmp/autopkgtest.z7lEof/wrapper.sh: creating AUTOPKGTEST_TMP: /tmp/autopkgtest.z7lEof/autopkgtest_tmp 151s /tmp/autopkgtest.z7lEof/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 151s /tmp/autopkgtest.z7lEof/wrapper.sh: marking as executable: /tmp/autopkgtest.z7lEof/build.dfd/src/debian/tests/examples 151s /tmp/autopkgtest.z7lEof/wrapper.sh: command to run: /tmp/autopkgtest.z7lEof/build.dfd/src/debian/tests/examples 151s /tmp/autopkgtest.z7lEof/wrapper.sh: copying /tmp/tmp.Ax2WluqhCH/out to stdout and file: /tmp/autopkgtest.z7lEof/examples-stdout 151s /tmp/autopkgtest.z7lEof/wrapper.sh: copying /tmp/tmp.Ax2WluqhCH/err to standard error and file: /tmp/autopkgtest.z7lEof/examples-stdout 151s /tmp/autopkgtest.z7lEof/wrapper.sh: writing script pid 1996 to /tmp/autopkgtest_script_pid 151s Info pkgconf (rdkafka): -I/usr/include/s390x-linux-gnu -isystem /usr/include/mit-krb5 -I/usr/include/p11-kit-1 -lrdkafka 151s Info pkgconf (rdkafka++): -I/usr/include/s390x-linux-gnu -isystem /usr/include/mit-krb5 -I/usr/include/p11-kit-1 -lrdkafka++ 151s Build consumer.c 151s Build producer.c 151s Build rdkafka_example.c 151s Build rdkafka_complex_consumer_example.cpp 152s Build rdkafka_example.cpp 154s Run consumer.c.out 154s /tmp/autopkgtest.z7lEof/wrapper.sh: checking for leaked background processes... 154s Run producer.c.out 154s Run rdkafka_complex_consumer_example.cpp.out 154s Run rdkafka_example.c.out 154s Run rdkafka_example.cpp.out 154s All OK 154s /tmp/autopkgtest.z7lEof/wrapper.sh: waiting for tee/cat subprocesses... 154s /tmp/autopkgtest.z7lEof/wrapper.sh: cleaning up... 154s /tmp/autopkgtest.z7lEof/wrapper.sh: Exit status: 0 154s autopkgtest: DBG: testbed command exited with code 0 154s autopkgtest [00:33:51]: test examples: -----------------------] 154s autopkgtest: DBG: testbed executing test finished with exit status 0 154s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/examples-stdout /tmp/autopkgtest-work.sreybbfn/out/examples-stdout 154s autopkgtest: DBG: got reply from testbed: ok 154s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/examples-stderr /tmp/autopkgtest-work.sreybbfn/out/examples-stderr 154s autopkgtest: DBG: got reply from testbed: ok 154s examples PASS 154s autopkgtest [00:33:53]: test examples: - - - - - - - - - - results - - - - - - - - - - 154s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/examples-artifacts/ /tmp/autopkgtest-work.sreybbfn/out/artifacts/ 154s autopkgtest: DBG: got reply from testbed: ok 154s autopkgtest: DBG: testbed command ['rm', '-rf', '/tmp/autopkgtest.z7lEof/examples-artifacts', '/tmp/autopkgtest.z7lEof/autopkgtest_tmp'], kind short, sout raw, serr pipe, env [] 154s autopkgtest: DBG: testbed command exited with code 0 154s autopkgtest [00:33:53]: test unittest: preparing testbed 154s autopkgtest: DBG: testbed reset: modified=False, deps_installed=['librdkafka-dev', 'build-essential', 'pkgconf'], deps_new=['librdkafka-dev', 'build-essential', 'pkgconf'] 154s autopkgtest: DBG: Binaries: no registered binaries, not publishing anything 154s autopkgtest: DBG: install_deps: deps_new=['librdkafka-dev', 'build-essential', 'pkgconf'] 154s autopkgtest: DBG: install-deps: satisfying librdkafka-dev, build-essential, pkgconf 154s autopkgtest: DBG: can use apt-get on testbed: True 154s 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', 'librdkafka-dev, build-essential, pkgconf'], kind install, sout raw, serr pipe, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'APT_LISTCHANGES_FRONTEND=none'] 155s Reading package lists... 155s Building dependency tree... 155s Reading state information... 155s Starting pkgProblemResolver with broken count: 0 155s Starting 2 pkgProblemResolver with broken count: 0 155s Done 155s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: testbed command ['dpkg-query', '--show', '-f', '${Status}', 'librdkafka-dev'], kind short, sout pipe, serr pipe, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/autopkgtest.z7lEof/unittest-packages.all"], kind short, sout raw, serr pipe, env [] 155s autopkgtest: DBG: testbed command exited with code 0 155s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/unittest-packages.all /tmp/autopkgtest-work.sreybbfn/out/unittest-packages.all 155s autopkgtest: DBG: got reply from testbed: ok 155s autopkgtest: DBG: testbed command ['test', '-e', '/tmp/autopkgtest.z7lEof/build.dfd/src'], kind short, sout raw, serr raw, env [] 156s autopkgtest: DBG: testbed command exited with code 0 156s autopkgtest: DBG: copydown: tb path /tmp/autopkgtest.z7lEof/build.dfd/src already exists 156s autopkgtest [00:33:55]: test unittest: [----------------------- 156s autopkgtest: DBG: testbed command ['su', '-s', '/bin/bash', 'ubuntu', '-c', "set -e; exec /tmp/autopkgtest.z7lEof/wrapper.sh --debug --artifacts=/tmp/autopkgtest.z7lEof/unittest-artifacts --chdir=/tmp/autopkgtest.z7lEof/build.dfd/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.z7lEof/unittest-stderr --stdout=/tmp/autopkgtest.z7lEof/unittest-stdout --tmp=/tmp/autopkgtest.z7lEof/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.z7lEof/build.dfd/src/debian/tests/unittest -- /tmp/autopkgtest.z7lEof/build.dfd/src/debian/tests/unittest"], kind test, sout raw, serr raw, env [] 156s /tmp/autopkgtest.z7lEof/wrapper.sh: creating AUTOPKGTEST_ARTIFACTS: /tmp/autopkgtest.z7lEof/unittest-artifacts 156s /tmp/autopkgtest.z7lEof/wrapper.sh: changing to directory: /tmp/autopkgtest.z7lEof/build.dfd/src 156s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: AUTOPKGTEST_TESTBED_ARCH=s390x 156s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: AUTOPKGTEST_TEST_ARCH=s390x 156s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: DEB_BUILD_OPTIONS=parallel=2 156s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: DEBIAN_FRONTEND=noninteractive 156s /tmp/autopkgtest.z7lEof/wrapper.sh: setting environment: LANG=C.UTF-8 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LANGUAGE 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_ADDRESS 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_ALL 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_COLLATE 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_CTYPE 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_IDENTIFICATION 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_MEASUREMENT 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_MESSAGES 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_MONETARY 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_NAME 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_NUMERIC 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_PAPER 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_TELEPHONE 156s /tmp/autopkgtest.z7lEof/wrapper.sh: unsetting environment: LC_TIME 156s /tmp/autopkgtest.z7lEof/wrapper.sh: will create pid file: /tmp/autopkgtest_script_pid 156s /tmp/autopkgtest.z7lEof/wrapper.sh: pretending to be a login shell 156s /tmp/autopkgtest.z7lEof/wrapper.sh: will write standard error to /tmp/autopkgtest.z7lEof/unittest-stderr 156s /tmp/autopkgtest.z7lEof/wrapper.sh: will write stdout to /tmp/autopkgtest.z7lEof/unittest-stdout 156s /tmp/autopkgtest.z7lEof/wrapper.sh: creating AUTOPKGTEST_TMP: /tmp/autopkgtest.z7lEof/autopkgtest_tmp 156s /tmp/autopkgtest.z7lEof/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 156s /tmp/autopkgtest.z7lEof/wrapper.sh: marking as executable: /tmp/autopkgtest.z7lEof/build.dfd/src/debian/tests/unittest 156s /tmp/autopkgtest.z7lEof/wrapper.sh: command to run: /tmp/autopkgtest.z7lEof/build.dfd/src/debian/tests/unittest 156s /tmp/autopkgtest.z7lEof/wrapper.sh: copying /tmp/tmp.v75HUpVlt6/out to stdout and file: /tmp/autopkgtest.z7lEof/unittest-stdout 156s /tmp/autopkgtest.z7lEof/wrapper.sh: copying /tmp/tmp.v75HUpVlt6/err to standard error and file: /tmp/autopkgtest.z7lEof/unittest-stdout 156s /tmp/autopkgtest.z7lEof/wrapper.sh: writing script pid 2190 to /tmp/autopkgtest_script_pid 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: empty tqh[0] 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: prepend 1,0 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: prepend 2,1,0 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: insert 1 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: insert 1,2 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: append 1 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: append 1,2 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: insert 1,0,2 156s RDUT: INFO: rdunittest.c:159: ut_tq_test: Testing TAILQ: insert 2,0,1 156s RDUT: PASS: rdunittest.c:346: unittest_sysqueue 156s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: sysqueue: PASS 156s RDUT: INFO: rdstring.c:409: ut_strcasestr: BEGIN:  156s RDUT: PASS: rdstring.c:425: ut_strcasestr 156s RDUT: INFO: rdstring.c:606: ut_string_split: BEGIN:  156s RDUT: PASS: rdstring.c:632: ut_string_split 156s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: string: PASS 156s RDUT: INFO: rdmap.c:473: unittest_untyped_map: 500000 map_get iterations took 151.596ms = 0us/get 156s RDUT: INFO: rdmap.c:490: unittest_untyped_map: Total time over 100000 entries took 199.075ms 156s RDUT: PASS: rdmap.c:493: unittest_untyped_map 156s RDUT: INFO: rdmap.c:321: unittest_typed_map: enumerated key 2 person Hedvig Lindahl 156s RDUT: INFO: rdmap.c:321: unittest_typed_map: enumerated key 1 person Roy McPhearsome 156s RDUT: PASS: rdmap.c:339: unittest_typed_map 156s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: map: PASS 156s RDUT: PASS: rdbuf.c:1357: do_unittest_write_read 156s RDUT: PASS: rdbuf.c:1522: do_unittest_write_split_seek 156s RDUT: PASS: rdbuf.c:1612: do_unittest_write_read_payload_correctness 156s RDUT: PASS: rdbuf.c:1680: do_unittest_write_iov 156s RDUT: PASS: rdbuf.c:1870: do_unittest_erase 156s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: rdbuf: PASS 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 156s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: rdvarint: PASS 156s RDUT: INFO: crc32c.c:411: unittest_rd_crc32c: Calculate CRC32C using software 156s RDUT: INFO: crc32c.c:422: unittest_rd_crc32c: Calculate CRC32C using software 156s RDUT: PASS: crc32c.c:429: unittest_rd_crc32c 156s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: crc32c: PASS 156s RDUT: INFO: rdkafka_msg.c:2046: unittest_msgq_order: FIFO: testing in FIFO mode 156s RDUT: PASS: rdkafka_msg.c:2241: unittest_msg_seq_wrap 156s RDUT: INFO: rdkafka_msg.c:2286: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: get baseline insert time 156s RDUT: INFO: rdkafka_msg.c:2319: unittest_msgq_insert_all_sort: Begin insert of 2 messages into destq with 2 messages 156s RDUT: INFO: rdkafka_msg.c:2327: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 156s RDUT: PASS: rdkafka_msg.c:2358: unittest_msgq_insert_all_sort 156s RDUT: INFO: rdkafka_msg.c:2382: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: get baseline insert time 156s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 2 messages 156s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 156s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 3 messages 156s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 156s RDUT: INFO: rdkafka_msg.c:2455: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 2 messages in 0us 156s RDUT: PASS: rdkafka_msg.c:2471: unittest_msgq_insert_each_sort 156s RDUT: INFO: rdkafka_msg.c:2286: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: single-message ranges 156s RDUT: INFO: rdkafka_msg.c:2319: unittest_msgq_insert_all_sort: Begin insert of 4 messages into destq with 5 messages 156s RDUT: INFO: rdkafka_msg.c:2327: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 156s RDUT: PASS: rdkafka_msg.c:2358: unittest_msgq_insert_all_sort 156s RDUT: INFO: rdkafka_msg.c:2382: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: single-message ranges 156s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 5 messages 156s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 156s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 6 messages 156s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 156s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 7 messages 156s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 156s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 8 messages 156s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 156s RDUT: INFO: rdkafka_msg.c:2455: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 4 messages in 0us 156s RDUT: PASS: rdkafka_msg.c:2471: unittest_msgq_insert_each_sort 156s RDUT: INFO: rdkafka_msg.c:2286: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: many messages 157s RDUT: INFO: rdkafka_msg.c:2319: unittest_msgq_insert_all_sort: Begin insert of 4315956 messages into destq with 165288 messages 157s RDUT: INFO: rdkafka_msg.c:2327: unittest_msgq_insert_all_sort: Done: took 8866us, 0.0020us/msg 157s RDUT: PASS: rdkafka_msg.c:2358: unittest_msgq_insert_all_sort 157s RDUT: INFO: rdkafka_msg.c:2382: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: many messages 157s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 100001 messages into destq with 165288 messages 157s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 2us, 0.0000us/msg 157s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 50001 messages into destq with 265289 messages 157s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 540us, 0.0108us/msg 157s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 20001 messages into destq with 315290 messages 157s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 785us, 0.0392us/msg 157s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 335291 messages 157s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 889us, 0.0150us/msg 157s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 394420 messages 157s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 1975us, 0.0227us/msg 157s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 4000001 messages into destq with 481243 messages 157s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 17001us, 0.0043us/msg 158s RDUT: INFO: rdkafka_msg.c:2455: unittest_msgq_insert_each_sort: Total: 0.0049us/msg over 4315956 messages in 21192us 158s RDUT: PASS: rdkafka_msg.c:2471: unittest_msgq_insert_each_sort 158s RDUT: INFO: rdkafka_msg.c:2286: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2508 158s RDUT: INFO: rdkafka_msg.c:2319: unittest_msgq_insert_all_sort: Begin insert of 145952 messages into destq with 154875 messages 158s RDUT: INFO: rdkafka_msg.c:2327: unittest_msgq_insert_all_sort: Done: took 1104us, 0.0037us/msg 158s RDUT: PASS: rdkafka_msg.c:2358: unittest_msgq_insert_all_sort 158s RDUT: INFO: rdkafka_msg.c:2382: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2508 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 154875 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 214004 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2455: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 145952 messages in 0us 158s RDUT: PASS: rdkafka_msg.c:2471: unittest_msgq_insert_each_sort 158s RDUT: INFO: rdkafka_msg.c:2286: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2450 (v1.2.1 regression) 158s RDUT: INFO: rdkafka_msg.c:2319: unittest_msgq_insert_all_sort: Begin insert of 86 messages into destq with 199999 messages 158s RDUT: INFO: rdkafka_msg.c:2327: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 158s RDUT: PASS: rdkafka_msg.c:2358: unittest_msgq_insert_all_sort 158s RDUT: INFO: rdkafka_msg.c:2382: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2450 (v1.2.1 regression) 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 199999 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 5 messages into destq with 200001 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 4 messages into destq with 200006 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200010 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200012 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 61 messages into destq with 200015 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200076 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200078 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200080 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2419: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200082 messages 158s RDUT: INFO: rdkafka_msg.c:2430: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 158s RDUT: INFO: rdkafka_msg.c:2455: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 86 messages in 0us 158s RDUT: PASS: rdkafka_msg.c:2471: unittest_msgq_insert_each_sort 158s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: msg: PASS 158s RDUT: PASS: rdmurmur2.c:166: unittest_murmur2 158s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: murmurhash: PASS 158s RDUT: PASS: rdfnv1a.c:112: unittest_fnv1a 158s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: fnv1a: PASS 158s RDUT: PASS: rdhdrhistogram.c:468: ut_high_sigfig 158s RDUT: PASS: rdhdrhistogram.c:495: ut_quantile 158s RDUT: PASS: rdhdrhistogram.c:514: ut_mean 158s RDUT: PASS: rdhdrhistogram.c:536: ut_stddev 158s RDUT: PASS: rdhdrhistogram.c:555: ut_totalcount 158s RDUT: PASS: rdhdrhistogram.c:573: ut_max 158s RDUT: PASS: rdhdrhistogram.c:590: ut_min 158s RDUT: PASS: rdhdrhistogram.c:609: ut_reset 158s RDUT: PASS: rdhdrhistogram.c:623: ut_nan 158s RDUT: PASS: rdhdrhistogram.c:638: ut_sigfigs 158s RDUT: PASS: rdhdrhistogram.c:654: ut_minmax_trackable 158s RDUT: PASS: rdhdrhistogram.c:664: ut_unitmagnitude_overflow 158s RDUT: PASS: rdhdrhistogram.c:697: ut_subbucketmask_overflow 158s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: rdhdrhistogram: PASS 158s RDUT: INFO: rdkafka_conf.c:4441: unittest_conf: Safified client.software.name="aba.-va" 158s RDUT: INFO: rdkafka_conf.c:4449: unittest_conf: Safified client.software.version="1.2.3.4.5----a" 158s RDUT: PASS: rdkafka_conf.c:4453: unittest_conf 158s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: conf: PASS 158s RDUT: PASS: rdkafka_broker.c:2247: rd_ut_reconnect_backoff 158s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: broker: PASS 158s RDUT: INFO: rdkafka_request.c:6600: unittest_idempotent_producer: Verifying idempotent producer error handling 158s %5|1739666037.212|CONFWARN|rdkafka#producer-1| [thrd:app]: No `bootstrap.servers` configured: client will not be able to connect to Kafka cluster 158s RDUT: INFO: rdkafka_request.c:6766: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6766: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6766: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6766: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 158s RDUT: INFO: rdkafka_request.c:6771: unittest_idempotent_producer: DR for message: Success: (persistence=2) 159s RDUT: PASS: rdkafka_request.c:6796: unittest_idempotent_producer 159s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: request: PASS 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1585: do_unittest_config_no_principal_should_fail 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1614: do_unittest_config_empty_should_fail 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1681: do_unittest_config_empty_value_should_fail 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1719: do_unittest_config_value_with_quote_should_fail 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1644: do_unittest_config_unrecognized_should_fail 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1468: do_unittest_config_defaults 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1511: do_unittest_config_explicit_scope_and_life 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1555: do_unittest_config_all_explicit_values 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1758: do_unittest_config_extensions 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1777: do_unittest_illegal_extension_keys_should_fail 159s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1810: do_unittest_odd_extension_size_should_fail 159s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: sasl_oauthbearer: PASS 159s RDUT: PASS: rdkafka_msgset_reader.c:1805: unittest_aborted_txns 159s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: aborted_txns: PASS 159s RDUT: PASS: rdkafka_cgrp.c:7049: unittest_consumer_group_metadata 159s RDUT: PASS: rdkafka_cgrp.c:7120: unittest_set_intersect 159s RDUT: PASS: rdkafka_cgrp.c:7169: unittest_set_subtract 159s RDUT: PASS: rdkafka_cgrp.c:7196: unittest_map_to_list 159s RDUT: PASS: rdkafka_cgrp.c:7226: unittest_list_to_map 159s RDUT: PASS: rdkafka_cgrp.c:7295: unittest_member_metadata_serdes 159s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: cgrp: PASS 159s RDUT: PASS: rdkafka_sasl_scram.c:810: unittest_scram_nonce 159s RDUT: PASS: rdkafka_sasl_scram.c:849: unittest_scram_safe 159s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: scram: PASS 159s RDUT: INFO: rdkafka_assignor.c:1654: ut_assignors: Test case Symmetrical subscription: range assignor 159s RDUT: INFO: rdkafka_assignor.c:1654: ut_assignors: Test case Symmetrical subscription: roundrobin assignor 159s RDUT: INFO: rdkafka_assignor.c:1654: ut_assignors: Test case 1*3 partitions (asymmetrical): range assignor 159s RDUT: INFO: rdkafka_assignor.c:1654: ut_assignors: Test case 1*3 partitions (asymmetrical): roundrobin assignor 159s RDUT: INFO: rdkafka_assignor.c:1654: ut_assignors: Test case #2121 (asymmetrical): range assignor 159s RDUT: INFO: rdkafka_assignor.c:1654: ut_assignors: Test case #2121 (asymmetrical): roundrobin assignor 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:645: ut_testOneConsumerNoTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #0 ran for 0.046ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:679: ut_testOneConsumerNonexistentTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #1 ran for 0.048ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #2 ran for 0.061ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #3 ran for 0.053ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #4 ran for 0.062ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #5 ran for 0.046ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #6 ran for 0.048ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #7 ran for 0.062ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #8 ran for 0.063ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1167: ut_testRackAwareAssignmentWithUniformSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #9 ran for 0.252ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1258: ut_testRackAwareAssignmentWithNonEqualSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #10 ran for 0.233ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1318: ut_testRackAwareAssignmentWithUniformPartitions 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #11 ran for 0.237ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1411: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #12 ran for 0.234ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1493: ut_testRackAwareAssignmentWithCoPartitioning0 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #13 ran for 0.106ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1595: ut_testRackAwareAssignmentWithCoPartitioning1 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #14 ran for 0.149ms ] 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 1 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 2 ] 159s RDUT: PASS: rdkafka_range_assignor.c:1652: ut_testCoPartitionedAssignmentWithSameSubscription 159s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #15 ran for 0.115ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #0 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_sticky_assignor.c:2256: ut_testOneConsumerNoTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2274: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2280: ut_testOneConsumerNoTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2274: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2280: ut_testOneConsumerNoTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #0 ran for 0.082ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #1 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 0 ] 159s RDUT: PASS: rdkafka_sticky_assignor.c:2294: ut_testOneConsumerNonexistentTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2311: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2317: ut_testOneConsumerNonexistentTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2311: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2317: ut_testOneConsumerNonexistentTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #1 ran for 0.072ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #2 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2350: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2356: ut_testOneConsumerOneTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2350: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2356: ut_testOneConsumerOneTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2350: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2356: ut_testOneConsumerOneTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #2 ran for 0.105ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #3 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2385: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2391: ut_testOnlyAssignsPartitionsFromSubscribedTopics 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2385: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2391: ut_testOnlyAssignsPartitionsFromSubscribedTopics 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2385: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2391: ut_testOnlyAssignsPartitionsFromSubscribedTopics 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #3 ran for 0.107ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #4 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2420: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2426: ut_testOneConsumerMultipleTopics 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2420: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2426: ut_testOneConsumerMultipleTopics 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2420: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2426: ut_testOneConsumerMultipleTopics 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #4 ran for 0.100ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #5 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2457: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2464: ut_testTwoConsumersOneTopicOnePartition 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2457: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2464: ut_testTwoConsumersOneTopicOnePartition 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2457: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2464: ut_testTwoConsumersOneTopicOnePartition 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #5 ran for 0.101ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #6 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2496: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2503: ut_testTwoConsumersOneTopicTwoPartitions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2496: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2503: ut_testTwoConsumersOneTopicTwoPartitions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2496: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2503: ut_testTwoConsumersOneTopicTwoPartitions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #6 ran for 0.103ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #7 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2540: verifying assignment for 3 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2548: ut_testMultipleConsumersMixedTopicSubscriptions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2540: verifying assignment for 3 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2548: ut_testMultipleConsumersMixedTopicSubscriptions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2540: verifying assignment for 3 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2548: ut_testMultipleConsumersMixedTopicSubscriptions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #7 ran for 0.134ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #8 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2582: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2589: ut_testTwoConsumersTwoTopicsSixPartitions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2582: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2589: ut_testTwoConsumersTwoTopicsSixPartitions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2582: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2589: ut_testTwoConsumersTwoTopicsSixPartitions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #8 ran for 0.138ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #9 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2618: verifying assignment for 1 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2634: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2647: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2655: ut_testAddRemoveConsumerOneTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2618: verifying assignment for 1 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2634: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2647: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2655: ut_testAddRemoveConsumerOneTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2618: verifying assignment for 1 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2634: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2647: verifying assignment for 1 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2655: ut_testAddRemoveConsumerOneTopic 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #9 ran for 0.234ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #10 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2719: verifying assignment for 4 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2728: ut_testPoorRoundRobinAssignmentScenario 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2719: verifying assignment for 4 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2728: ut_testPoorRoundRobinAssignmentScenario 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2719: verifying assignment for 4 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2728: ut_testPoorRoundRobinAssignmentScenario 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #10 ran for 0.186ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #11 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2761: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_sticky_assignor.c:2767: ut_testAddRemoveTopicTwoConsumers: Adding topic2 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2784: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_sticky_assignor.c:2792: ut_testAddRemoveTopicTwoConsumers: Removing topic1 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2807: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2815: ut_testAddRemoveTopicTwoConsumers 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2761: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_sticky_assignor.c:2767: ut_testAddRemoveTopicTwoConsumers: Adding topic2 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2784: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_sticky_assignor.c:2792: ut_testAddRemoveTopicTwoConsumers: Removing topic1 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2807: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2815: ut_testAddRemoveTopicTwoConsumers 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2761: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_sticky_assignor.c:2767: ut_testAddRemoveTopicTwoConsumers: Adding topic2 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2784: verifying assignment for 2 member(s): 159s RDUT: INFO: rdkafka_sticky_assignor.c:2792: ut_testAddRemoveTopicTwoConsumers: Removing topic1 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2807: verifying assignment for 2 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2815: ut_testAddRemoveTopicTwoConsumers 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #11 ran for 0.335ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #12 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2870: verifying assignment for 19 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2885: verifying assignment for 18 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2892: ut_testReassignmentAfterOneConsumerLeaves 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2870: verifying assignment for 19 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2885: verifying assignment for 18 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2892: ut_testReassignmentAfterOneConsumerLeaves 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2870: verifying assignment for 19 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2885: verifying assignment for 18 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2892: ut_testReassignmentAfterOneConsumerLeaves 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #12 ran for 11.402ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #13 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2932: verifying assignment for 8 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2944: verifying assignment for 9 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2951: ut_testReassignmentAfterOneConsumerAdded 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2932: verifying assignment for 8 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2944: verifying assignment for 9 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2951: ut_testReassignmentAfterOneConsumerAdded 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2932: verifying assignment for 8 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2944: verifying assignment for 9 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:2951: ut_testReassignmentAfterOneConsumerAdded 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #13 ran for 0.782ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #14 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testSameSubscriptions:3000: verifying assignment for 9 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testSameSubscriptions:3013: verifying assignment for 8 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:3021: ut_testSameSubscriptions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 1 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testSameSubscriptions:3000: verifying assignment for 9 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testSameSubscriptions:3013: verifying assignment for 8 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:3021: ut_testSameSubscriptions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 2 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testSameSubscriptions:3000: verifying assignment for 9 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testSameSubscriptions:3013: verifying assignment for 8 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:3021: ut_testSameSubscriptions 159s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #14 ran for 8.132ms ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #15 ] 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 0 ] 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3077: verifying assignment for 200 member(s): 159s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3093: verifying assignment for 150 member(s): 159s RDUT: PASS: rdkafka_sticky_assignor.c:3100: ut_testLargeAssignmentWithMultipleConsumersLeaving 159s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 1 ] 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3077: verifying assignment for 200 member(s): 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3093: verifying assignment for 150 member(s): 160s RDUT: PASS: rdkafka_sticky_assignor.c:3100: ut_testLargeAssignmentWithMultipleConsumersLeaving 160s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 2 ] 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3077: verifying assignment for 200 member(s): 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3093: verifying assignment for 150 member(s): 160s RDUT: PASS: rdkafka_sticky_assignor.c:3100: ut_testLargeAssignmentWithMultipleConsumersLeaving 160s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #15 ran for 1673.723ms ] 160s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #16 ] 160s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 0 ] 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNewSubscription:3145: verifying assignment for 3 member(s): 160s RDUT: INFO: rdkafka_sticky_assignor.c:3151: ut_testNewSubscription: Adding topic1 to consumer1 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNewSubscription:3160: verifying assignment for 3 member(s): 160s RDUT: PASS: rdkafka_sticky_assignor.c:3168: ut_testNewSubscription 160s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 1 ] 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNewSubscription:3145: verifying assignment for 3 member(s): 160s RDUT: INFO: rdkafka_sticky_assignor.c:3151: ut_testNewSubscription: Adding topic1 to consumer1 160s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNewSubscription:3160: verifying assignment for 3 member(s): 160s RDUT: PASS: rdkafka_sticky_assignor.c:3168: ut_testNewSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNewSubscription:3145: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_sticky_assignor.c:3151: ut_testNewSubscription: Adding topic1 to consumer1 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNewSubscription:3160: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3168: ut_testNewSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #16 ran for 0.476ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #17 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3206: verifying assignment for 4 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3227: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3263: ut_testMoveExistingAssignments 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3206: verifying assignment for 4 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3227: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3263: ut_testMoveExistingAssignments 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3206: verifying assignment for 4 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3227: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3263: ut_testMoveExistingAssignments 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #17 ran for 0.256ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #18 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3313: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3322: ut_testMoveExistingAssignments_j 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3313: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3322: ut_testMoveExistingAssignments_j 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3313: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3322: ut_testMoveExistingAssignments_j 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #18 ran for 0.168ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #19 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness:3377: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3383: ut_testStickiness 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness:3377: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3383: ut_testStickiness 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness:3377: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3383: ut_testStickiness 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #19 ran for 0.152ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #20 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness_j:3426: verifying assignment for 4 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness_j:3451: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3487: ut_testStickiness_j 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness_j:3426: verifying assignment for 4 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness_j:3451: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3487: ut_testStickiness_j 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness_j:3426: verifying assignment for 4 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness_j:3451: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3487: ut_testStickiness_j 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #20 ran for 0.199ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #21 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3524: verifying assignment for 1 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3534: verifying assignment for 2 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3548: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3548: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3560: verifying assignment for 2 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3572: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3581: ut_testStickiness2 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3524: verifying assignment for 1 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3534: verifying assignment for 2 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3548: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3548: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3560: verifying assignment for 2 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3572: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3581: ut_testStickiness2 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3524: verifying assignment for 1 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3534: verifying assignment for 2 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3548: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3548: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3560: verifying assignment for 2 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testStickiness2:3572: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3581: ut_testStickiness2 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #21 ran for 0.578ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #22 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3607: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3617: ut_testAssignmentUpdatedForDeletedTopic 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3607: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3617: ut_testAssignmentUpdatedForDeletedTopic 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3607: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3617: ut_testAssignmentUpdatedForDeletedTopic 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #22 ran for 1.303ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #23 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3644: verifying assignment for 1 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3658: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3664: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3644: verifying assignment for 1 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3658: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3664: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3644: verifying assignment for 1 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3658: verifying assignment for 1 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3664: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #23 ran for 0.159ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #24 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 0 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:3682: ut_testConflictingPreviousAssignments 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 1 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:3682: ut_testConflictingPreviousAssignments 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 2 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:3682: ut_testConflictingPreviousAssignments 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #24 ran for 0.046ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #25 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3770: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3779: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3770: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3779: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3770: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3779: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #25 ran for 0.132ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #26 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3817: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3828: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3817: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3828: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3817: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3828: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #26 ran for 0.147ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #27 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3865: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3876: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3865: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3876: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3865: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3876: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #27 ran for 0.145ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #28 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 0 ] 161s %3|1739666039.921|STICKY|rdkafka#consumer-4| [thrd:app]: Sticky assignor: Found multiple consumers consumer1 and consumer2 claiming the same topic partition topic1:0 in the same generation 1, this will be invalidated and removed from their previous assignment. 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3917: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3926: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 1 ] 161s %3|1739666039.921|STICKY|rdkafka#consumer-4| [thrd:app]: Sticky assignor: Found multiple consumers consumer1 and consumer2 claiming the same topic partition topic1:0 in the same generation 1, this will be invalidated and removed from their previous assignment. 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3917: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3926: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 2 ] 161s %3|1739666039.921|STICKY|rdkafka#consumer-4| [thrd:app]: Sticky assignor: Found multiple consumers consumer1 and consumer2 claiming the same topic partition topic1:0 in the same generation 1, this will be invalidated and removed from their previous assignment. 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3917: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3926: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #28 ran for 0.176ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #29 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 0 ] 161s %3|1739666039.921|STICKY|rdkafka#consumer-4| [thrd:app]: Sticky assignor: Found multiple consumers consumer1 and consumer2 claiming the same topic partition topic1:1 in the same generation 1, this will be invalidated and removed from their previous assignment. 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3974: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3983: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 1 ] 161s %3|1739666039.921|STICKY|rdkafka#consumer-4| [thrd:app]: Sticky assignor: Found multiple consumers consumer1 and consumer2 claiming the same topic partition topic1:1 in the same generation 1, this will be invalidated and removed from their previous assignment. 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3974: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3983: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 2 ] 161s %3|1739666039.921|STICKY|rdkafka#consumer-4| [thrd:app]: Sticky assignor: Found multiple consumers consumer1 and consumer2 claiming the same topic partition topic1:1 in the same generation 1, this will be invalidated and removed from their previous assignment. 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3974: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:3983: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #29 ran for 0.173ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #30 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4036: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4042: ut_testEnsurePartitionsAssignedToHighestGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4036: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4042: ut_testEnsurePartitionsAssignedToHighestGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4036: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4042: ut_testEnsurePartitionsAssignedToHighestGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #30 ran for 0.191ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #31 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4094: verifying assignment for 4 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4102: ut_testNoReassignmentOnCurrentMembers 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4094: verifying assignment for 4 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4102: ut_testNoReassignmentOnCurrentMembers 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4094: verifying assignment for 4 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4102: ut_testNoReassignmentOnCurrentMembers 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #31 ran for 0.240ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #32 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 0 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4141: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4151: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 1 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4141: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4151: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4141: verifying assignment for 2 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4151: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #32 ran for 0.148ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #33 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 0 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:4366: ut_testRackAwareAssignmentWithUniformSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 1 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:4366: ut_testRackAwareAssignmentWithUniformSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4523: ut_testRackAwareAssignmentWithUniformSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #33 ran for 1.446ms ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4744: rd_kafka_sticky_assignor_unittest: [ Test #34 ] 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 0 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:4545: ut_testRackAwareAssignmentWithNonEqualSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 1 ] 161s RDUT: PASS: rdkafka_sticky_assignor.c:4545: ut_testRackAwareAssignmentWithNonEqualSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4747: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 2 ] 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: INFO: rdkafka_assignor.c:1206: verifyValidityAndBalance0: setupRackAwareAssignment0:4222: verifying assignment for 3 member(s): 161s RDUT: PASS: rdkafka_sticky_assignor.c:4664: ut_testRackAwareAssignmentWithNonEqualSubscription 161s RDUT: INFO: rdkafka_sticky_assignor.c:4751: rd_kafka_sticky_assignor_unittest: [ Test #34 ran for 1.285ms ] 161s RDUT: PASS: rdkafka_assignor.c:1777: ut_assignors 161s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: assignors: PASS 161s RDUT: SKIP: rdhttp.c:465: unittest_http: RD_UT_HTTP_URL environment variable not set 161s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: http: PASS 161s RDUT: INFO: rdkafka_sasl_oauthbearer_oidc.c:425: ut_sasl_oauthbearer_oidc_should_succeed: BEGIN:  161s RDUT: PASS: rdkafka_sasl_oauthbearer_oidc.c:464: ut_sasl_oauthbearer_oidc_should_succeed 161s RDUT: INFO: rdkafka_sasl_oauthbearer_oidc.c:480: ut_sasl_oauthbearer_oidc_with_empty_key: BEGIN:  161s RDUT: PASS: rdkafka_sasl_oauthbearer_oidc.c:510: ut_sasl_oauthbearer_oidc_with_empty_key 161s RDUT: INFO: rdkafka_sasl_oauthbearer_oidc.c:527: ut_sasl_oauthbearer_oidc_post_fields: BEGIN:  161s RDUT: PASS: rdkafka_sasl_oauthbearer_oidc.c:542: ut_sasl_oauthbearer_oidc_post_fields 161s RDUT: INFO: rdkafka_sasl_oauthbearer_oidc.c:559: ut_sasl_oauthbearer_oidc_post_fields_with_empty_scope: BEGIN:  161s RDUT: PASS: rdkafka_sasl_oauthbearer_oidc.c:574: ut_sasl_oauthbearer_oidc_post_fields_with_empty_scope 161s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: sasl_oauthbearer_oidc: PASS 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 215 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 229 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 229 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 212 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 212 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 226 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 226 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 218 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 218 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 252 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 266 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 266 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 273 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 279 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 279 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 266 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 266 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 249 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 273 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 273 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 217 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 254 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 254 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdkafka_telemetry_decode.c:623: unit_test_telemetry: metrics_payload_size: 283 161s RDUT: PASS: rdkafka_telemetry_decode.c:730: unit_test_telemetry 161s RDUT: INFO: rdunittest.c:504: rd_unittest: unittest: telemetry: PASS 161s /tmp/autopkgtest.z7lEof/wrapper.sh: checking for leaked background processes... 161s /tmp/autopkgtest.z7lEof/wrapper.sh: waiting for tee/cat subprocesses... 161s /tmp/autopkgtest.z7lEof/wrapper.sh: cleaning up... 161s /tmp/autopkgtest.z7lEof/wrapper.sh: Exit status: 0 161s autopkgtest: DBG: testbed command exited with code 0 161s autopkgtest [00:34:00]: test unittest: -----------------------] 161s autopkgtest: DBG: testbed executing test finished with exit status 0 161s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/unittest-stdout /tmp/autopkgtest-work.sreybbfn/out/unittest-stdout 161s autopkgtest: DBG: got reply from testbed: ok 161s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/unittest-stderr /tmp/autopkgtest-work.sreybbfn/out/unittest-stderr 161s autopkgtest: DBG: got reply from testbed: ok 161s autopkgtest [00:34:00]: test unittest: - - - - - - - - - - results - - - - - - - - - - 161s autopkgtest: DBG: sending command to testbed: copyup /tmp/autopkgtest.z7lEof/unittest-artifacts/ /tmp/autopkgtest-work.sreybbfn/out/artifacts/ 161s unittest PASS 162s autopkgtest: DBG: got reply from testbed: ok 162s autopkgtest: DBG: testbed command ['rm', '-rf', '/tmp/autopkgtest.z7lEof/unittest-artifacts', '/tmp/autopkgtest.z7lEof/autopkgtest_tmp'], kind short, sout raw, serr pipe, env [] 162s autopkgtest: DBG: testbed command exited with code 0 162s autopkgtest: DBG: needs_reset, previously=False, requested by run_tests() line 230 162s autopkgtest [00:34:01]: @@@@@@@@@@@@@@@@@@@@ summary 162s examples PASS 162s unittest PASS 162s autopkgtest: DBG: testbed stop 162s autopkgtest: DBG: testbed close, scratch=/tmp/autopkgtest.z7lEof 162s autopkgtest: DBG: sending command to testbed: close 180s autopkgtest: DBG: got reply from testbed: ok 180s autopkgtest: DBG: sending command to testbed: quit 180s nova [W] Using flock in prodstack6-s390x 180s flock: timeout while waiting to get lock 180s Creating nova instance adt-plucky-s390x-librdkafka-20250216-003119-juju-7f2275-prod-proposed-migration-environment-15-4f42dd4f-7af8-4991-853f-3de641615ae6 from image adt/ubuntu-plucky-s390x-server-20250215.img (UUID 099dc609-2061-4009-a65b-d554466baca0)... 180s nova [W] Timed out waiting for 83e958df-7e73-44d8-ad8f-14e3a92a8aee to get deleted.