0s autopkgtest [00:27:00]: starting date and time: 2024-03-22 00:27:00+0000 0s autopkgtest [00:27:00]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [00:27:00]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gs5yp7gi/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:cyrus-sasl2,src:db5.3,src:heimdal,src:openssl --apt-upgrade librdkafka --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=cyrus-sasl2/2.1.28+dfsg1-5ubuntu1 db5.3/5.3.28+dfsg2-6 heimdal/7.8.git20221117.28daf24+dfsg-5ubuntu2 openssl/3.0.13-0ubuntu2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-s390x-10.secgroup --name adt-noble-s390x-librdkafka-20240322-002700-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 121s autopkgtest [00:29:01]: testbed dpkg architecture: s390x 121s autopkgtest [00:29:01]: testbed apt version: 2.7.12 121s autopkgtest [00:29:01]: @@@@@@@@@@@@@@@@@@@@ test bed setup 122s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 122s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 122s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 122s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB] 122s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3800 kB] 123s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [670 kB] 123s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 123s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 123s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 123s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3989 kB] 123s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 123s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 123s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 125s Fetched 9188 kB in 2s (4076 kB/s) 125s Reading package lists... 127s Reading package lists... 128s Building dependency tree... 128s Reading state information... 128s Calculating upgrade... 128s The following packages will be REMOVED: 128s libssl3 128s The following NEW packages will be installed: 128s libssl3t64 128s The following packages have been kept back: 128s libsasl2-2 libsasl2-modules-db 128s The following packages will be upgraded: 128s cloud-init debianutils libsasl2-modules openssl python3-markupsafe 128s 5 upgraded, 1 newly installed, 1 to remove and 2 not upgraded. 128s Need to get 3461 kB of archives. 128s After this operation, 244 kB of additional disk space will be used. 128s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 128s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB] 129s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libsasl2-modules s390x 2.1.28+dfsg1-5ubuntu1 [76.6 kB] 129s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB] 129s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x python3-markupsafe s390x 2.1.5-1build1 [12.8 kB] 129s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x cloud-init all 24.1.2-0ubuntu1 [597 kB] 129s Preconfiguring packages ... 129s Fetched 3461 kB in 1s (3343 kB/s) 129s (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 ... 52171 files and directories currently installed.) 129s Preparing to unpack .../debianutils_5.17_s390x.deb ... 129s Unpacking debianutils (5.17) over (5.16) ... 129s Setting up debianutils (5.17) ... 130s (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 ... 52171 files and directories currently installed.) 130s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ... 130s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 130s Preparing to unpack .../libsasl2-modules_2.1.28+dfsg1-5ubuntu1_s390x.deb ... 130s Unpacking libsasl2-modules:s390x (2.1.28+dfsg1-5ubuntu1) over (2.1.28+dfsg1-4) ... 130s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested: 130s wget depends on libssl3 (>= 3.0.0). 130s tnftp depends on libssl3 (>= 3.0.0). 130s tcpdump depends on libssl3 (>= 3.0.0). 130s systemd-resolved depends on libssl3 (>= 3.0.0). 130s systemd depends on libssl3 (>= 3.0.0). 130s sudo depends on libssl3 (>= 3.0.0). 130s s390-tools depends on libssl3 (>= 3.0.0). 130s rsync depends on libssl3 (>= 3.0.0). 130s python3-cryptography depends on libssl3 (>= 3.0.0). 130s openssh-server depends on libssl3 (>= 3.0.10). 130s openssh-client depends on libssl3 (>= 3.0.10). 130s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 130s libsystemd-shared:s390x depends on libssl3 (>= 3.0.0). 130s libssh-4:s390x depends on libssl3 (>= 3.0.0). 130s libsasl2-2:s390x depends on libssl3 (>= 3.0.0). 130s libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0). 130s libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0). 130s libnvme1 depends on libssl3 (>= 3.0.0). 130s libkrb5-3:s390x depends on libssl3 (>= 3.0.0). 130s libkmod2:s390x depends on libssl3 (>= 3.0.0). 130s libfido2-1:s390x depends on libssl3 (>= 3.0.0). 130s libcurl4:s390x depends on libssl3 (>= 3.0.0). 130s libcryptsetup12:s390x depends on libssl3 (>= 3.0.0). 130s kmod depends on libssl3 (>= 3.0.0). 130s dhcpcd-base depends on libssl3 (>= 3.0.0). 130s bind9-libs:s390x depends on libssl3 (>= 3.0.0). 130s 130s (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 ... 52171 files and directories currently installed.) 130s Removing libssl3:s390x (3.0.10-1ubuntu4) ... 130s Selecting previously unselected package libssl3t64:s390x. 130s (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 ... 52160 files and directories currently installed.) 130s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ... 130s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ... 130s Preparing to unpack .../python3-markupsafe_2.1.5-1build1_s390x.deb ... 130s Unpacking python3-markupsafe (2.1.5-1build1) over (2.1.5-1) ... 130s Preparing to unpack .../cloud-init_24.1.2-0ubuntu1_all.deb ... 130s Unpacking cloud-init (24.1.2-0ubuntu1) over (24.1.1-0ubuntu1) ... 130s Setting up cloud-init (24.1.2-0ubuntu1) ... 131s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ... 131s Setting up libsasl2-modules:s390x (2.1.28+dfsg1-5ubuntu1) ... 131s Setting up python3-markupsafe (2.1.5-1build1) ... 131s Setting up openssl (3.0.13-0ubuntu2) ... 131s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 132s Processing triggers for man-db (2.12.0-3) ... 132s Processing triggers for libc-bin (2.39-0ubuntu2) ... 133s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 133s Unknown architecture, assuming PC-style ttyS0 133s sh: Attempting to set up Debian/Ubuntu apt sources automatically 133s sh: Distribution appears to be Ubuntu 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 135s eatmydata is already the newest version (131-1). 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s dbus is already the newest version (1.14.10-4ubuntu1). 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s rng-tools-debian is already the newest version (2.4). 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 136s The following packages will be REMOVED: 136s cloud-init* python3-configobj* python3-debconf* 136s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 136s After this operation, 3256 kB disk space will be freed. 136s (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 ... 52172 files and directories currently installed.) 136s Removing cloud-init (24.1.2-0ubuntu1) ... 136s Removing python3-configobj (5.0.8-3) ... 136s Removing python3-debconf (1.5.86) ... 136s Processing triggers for man-db (2.12.0-3) ... 137s (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 ... 51783 files and directories currently installed.) 137s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 137s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 137s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 137s invoke-rc.d: policy-rc.d denied execution of try-restart. 137s Reading package lists... 138s Building dependency tree... 138s Reading state information... 138s linux-generic is already the newest version (6.8.0-11.11+1). 138s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 138s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 138s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 138s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 140s Reading package lists... 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s Calculating upgrade... 140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s Reading package lists... 141s Building dependency tree... 141s Reading state information... 141s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s autopkgtest [00:29:21]: rebooting testbed after setup commands that affected boot 156s autopkgtest [00:29:36]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 158s autopkgtest [00:29:38]: @@@@@@@@@@@@@@@@@@@@ apt-source librdkafka 162s Get:1 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (dsc) [2258 B] 162s Get:2 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (tar) [4381 kB] 162s Get:3 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (diff) [12.3 kB] 162s gpgv: Signature made Fri Oct 27 14:34:28 2023 UTC 162s gpgv: using RSA key A9592C521CB904077D6598009D0B5E5B1EEC8F0E 162s gpgv: Can't check signature: No public key 162s dpkg-source: warning: cannot verify inline signature for ./librdkafka_2.3.0-1.dsc: no acceptable signature found 162s autopkgtest [00:29:42]: testing package librdkafka version 2.3.0-1 162s autopkgtest [00:29:42]: build not needed 164s autopkgtest [00:29:44]: test examples: preparing testbed 176s Reading package lists... 177s Building dependency tree... 177s Reading state information... 177s Starting pkgProblemResolver with broken count: 0 177s Starting 2 pkgProblemResolver with broken count: 0 177s Done 177s The following additional packages will be installed: 177s build-essential cpp cpp-13 cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu g++ 177s g++-13 g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 177s gcc-13-s390x-linux-gnu gcc-s390x-linux-gnu libasan8 libatomic1 libc-dev-bin 177s libc6-dev libcc1-0 libcrypt-dev libgcc-13-dev libgomp1 libisl23 libitm1 177s libmpc3 libnsl-dev libpkgconf3 librdkafka++1 librdkafka-dev librdkafka1 177s libstdc++-13-dev libtirpc-dev libubsan1 linux-libc-dev pkg-config pkgconf 177s pkgconf-bin rpcsvc-proto 177s Suggested packages: 177s cpp-doc gcc-13-locales cpp-13-doc g++-multilib g++-13-multilib gcc-13-doc 177s gcc-multilib manpages-dev autoconf automake libtool flex bison gdb gcc-doc 177s gcc-13-multilib gdb-s390x-linux-gnu glibc-doc libstdc++-13-doc 177s Recommended packages: 177s manpages manpages-dev libc-devtools 177s The following NEW packages will be installed: 177s autopkgtest-satdep build-essential cpp cpp-13 cpp-13-s390x-linux-gnu 177s cpp-s390x-linux-gnu g++ g++-13 g++-13-s390x-linux-gnu g++-s390x-linux-gnu 177s gcc gcc-13 gcc-13-s390x-linux-gnu gcc-s390x-linux-gnu libasan8 libatomic1 177s libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libgcc-13-dev libgomp1 libisl23 177s libitm1 libmpc3 libnsl-dev libpkgconf3 librdkafka++1 librdkafka-dev 177s librdkafka1 libstdc++-13-dev libtirpc-dev libubsan1 linux-libc-dev 177s pkg-config pkgconf pkgconf-bin rpcsvc-proto 177s 0 upgraded, 38 newly installed, 0 to remove and 0 not upgraded. 177s Need to get 55.1 MB/55.1 MB of archives. 177s After this operation, 187 MB of additional disk space will be used. 177s Get:1 /tmp/autopkgtest.Lv8DgU/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [732 B] 177s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libc-dev-bin s390x 2.39-0ubuntu2 [20.2 kB] 177s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x linux-libc-dev s390x 6.8.0-11.11 [1590 kB] 178s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libcrypt-dev s390x 1:4.4.36-4 [135 kB] 178s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libtirpc-dev s390x 1.3.4+ds-1build1 [225 kB] 178s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libnsl-dev s390x 1.3.0-3 [73.5 kB] 178s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x rpcsvc-proto s390x 1.4.2-0ubuntu6 [64.7 kB] 178s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x libc6-dev s390x 2.39-0ubuntu2 [1629 kB] 178s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x libisl23 s390x 0.26-3 [722 kB] 178s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x libmpc3 s390x 1.3.1-1 [54.9 kB] 178s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [9929 kB] 179s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13 s390x 13.2.0-17ubuntu2 [1026 B] 179s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x cpp-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [5308 B] 179s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x cpp s390x 4:13.2.0-7ubuntu1 [22.4 kB] 179s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x libcc1-0 s390x 14-20240303-1ubuntu1 [49.9 kB] 179s Get:16 http://ftpmaster.internal/ubuntu noble/main s390x libgomp1 s390x 14-20240303-1ubuntu1 [151 kB] 179s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x libitm1 s390x 14-20240303-1ubuntu1 [31.1 kB] 179s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x libatomic1 s390x 14-20240303-1ubuntu1 [9392 B] 179s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x libasan8 s390x 14-20240303-1ubuntu1 [2998 kB] 179s Get:20 http://ftpmaster.internal/ubuntu noble/main s390x libubsan1 s390x 14-20240303-1ubuntu1 [1186 kB] 179s Get:21 http://ftpmaster.internal/ubuntu noble/main s390x libgcc-13-dev s390x 13.2.0-17ubuntu2 [1003 kB] 179s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [19.1 MB] 180s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13 s390x 13.2.0-17ubuntu2 [467 kB] 180s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x gcc-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [1208 B] 180s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x gcc s390x 4:13.2.0-7ubuntu1 [5014 B] 180s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x libstdc++-13-dev s390x 13.2.0-17ubuntu2 [2430 kB] 180s Get:27 http://ftpmaster.internal/ubuntu noble/main s390x g++-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [11.3 MB] 180s Get:28 http://ftpmaster.internal/ubuntu noble/main s390x g++-13 s390x 13.2.0-17ubuntu2 [14.4 kB] 180s Get:29 http://ftpmaster.internal/ubuntu noble/main s390x g++-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [956 B] 180s Get:30 http://ftpmaster.internal/ubuntu noble/main s390x g++ s390x 4:13.2.0-7ubuntu1 [1096 B] 180s Get:31 http://ftpmaster.internal/ubuntu noble/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 180s Get:32 http://ftpmaster.internal/ubuntu noble/main s390x libpkgconf3 s390x 1.8.1-2 [30.4 kB] 180s Get:33 http://ftpmaster.internal/ubuntu noble/universe s390x librdkafka1 s390x 2.3.0-1 [810 kB] 180s Get:34 http://ftpmaster.internal/ubuntu noble/universe s390x librdkafka++1 s390x 2.3.0-1 [45.2 kB] 180s Get:35 http://ftpmaster.internal/ubuntu noble/universe s390x librdkafka-dev s390x 2.3.0-1 [1004 kB] 180s Get:36 http://ftpmaster.internal/ubuntu noble/main s390x pkgconf-bin s390x 1.8.1-2 [20.8 kB] 180s Get:37 http://ftpmaster.internal/ubuntu noble/main s390x pkgconf s390x 1.8.1-2 [16.7 kB] 181s Get:38 http://ftpmaster.internal/ubuntu noble/main s390x pkg-config s390x 1.8.1-2 [7170 B] 181s Fetched 55.1 MB in 4s (15.3 MB/s) 181s Selecting previously unselected package libc-dev-bin. 181s (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 ... 51728 files and directories currently installed.) 181s Preparing to unpack .../00-libc-dev-bin_2.39-0ubuntu2_s390x.deb ... 181s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 181s Selecting previously unselected package linux-libc-dev:s390x. 181s Preparing to unpack .../01-linux-libc-dev_6.8.0-11.11_s390x.deb ... 181s Unpacking linux-libc-dev:s390x (6.8.0-11.11) ... 181s Selecting previously unselected package libcrypt-dev:s390x. 181s Preparing to unpack .../02-libcrypt-dev_1%3a4.4.36-4_s390x.deb ... 181s Unpacking libcrypt-dev:s390x (1:4.4.36-4) ... 181s Selecting previously unselected package libtirpc-dev:s390x. 181s Preparing to unpack .../03-libtirpc-dev_1.3.4+ds-1build1_s390x.deb ... 181s Unpacking libtirpc-dev:s390x (1.3.4+ds-1build1) ... 181s Selecting previously unselected package libnsl-dev:s390x. 181s Preparing to unpack .../04-libnsl-dev_1.3.0-3_s390x.deb ... 181s Unpacking libnsl-dev:s390x (1.3.0-3) ... 181s Selecting previously unselected package rpcsvc-proto. 181s Preparing to unpack .../05-rpcsvc-proto_1.4.2-0ubuntu6_s390x.deb ... 181s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 181s Selecting previously unselected package libc6-dev:s390x. 181s Preparing to unpack .../06-libc6-dev_2.39-0ubuntu2_s390x.deb ... 181s Unpacking libc6-dev:s390x (2.39-0ubuntu2) ... 181s Selecting previously unselected package libisl23:s390x. 181s Preparing to unpack .../07-libisl23_0.26-3_s390x.deb ... 181s Unpacking libisl23:s390x (0.26-3) ... 181s Selecting previously unselected package libmpc3:s390x. 181s Preparing to unpack .../08-libmpc3_1.3.1-1_s390x.deb ... 181s Unpacking libmpc3:s390x (1.3.1-1) ... 181s Selecting previously unselected package cpp-13-s390x-linux-gnu. 181s Preparing to unpack .../09-cpp-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 181s Unpacking cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 182s Selecting previously unselected package cpp-13. 182s Preparing to unpack .../10-cpp-13_13.2.0-17ubuntu2_s390x.deb ... 182s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 182s Selecting previously unselected package cpp-s390x-linux-gnu. 182s Preparing to unpack .../11-cpp-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 182s Unpacking cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 182s Selecting previously unselected package cpp. 182s Preparing to unpack .../12-cpp_4%3a13.2.0-7ubuntu1_s390x.deb ... 182s Unpacking cpp (4:13.2.0-7ubuntu1) ... 182s Selecting previously unselected package libcc1-0:s390x. 182s Preparing to unpack .../13-libcc1-0_14-20240303-1ubuntu1_s390x.deb ... 182s Unpacking libcc1-0:s390x (14-20240303-1ubuntu1) ... 182s Selecting previously unselected package libgomp1:s390x. 182s Preparing to unpack .../14-libgomp1_14-20240303-1ubuntu1_s390x.deb ... 182s Unpacking libgomp1:s390x (14-20240303-1ubuntu1) ... 182s Selecting previously unselected package libitm1:s390x. 182s Preparing to unpack .../15-libitm1_14-20240303-1ubuntu1_s390x.deb ... 182s Unpacking libitm1:s390x (14-20240303-1ubuntu1) ... 182s Selecting previously unselected package libatomic1:s390x. 182s Preparing to unpack .../16-libatomic1_14-20240303-1ubuntu1_s390x.deb ... 182s Unpacking libatomic1:s390x (14-20240303-1ubuntu1) ... 182s Selecting previously unselected package libasan8:s390x. 182s Preparing to unpack .../17-libasan8_14-20240303-1ubuntu1_s390x.deb ... 182s Unpacking libasan8:s390x (14-20240303-1ubuntu1) ... 182s Selecting previously unselected package libubsan1:s390x. 182s Preparing to unpack .../18-libubsan1_14-20240303-1ubuntu1_s390x.deb ... 182s Unpacking libubsan1:s390x (14-20240303-1ubuntu1) ... 182s Selecting previously unselected package libgcc-13-dev:s390x. 182s Preparing to unpack .../19-libgcc-13-dev_13.2.0-17ubuntu2_s390x.deb ... 182s Unpacking libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 182s Selecting previously unselected package gcc-13-s390x-linux-gnu. 182s Preparing to unpack .../20-gcc-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 182s Unpacking gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 182s Selecting previously unselected package gcc-13. 182s Preparing to unpack .../21-gcc-13_13.2.0-17ubuntu2_s390x.deb ... 182s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 182s Selecting previously unselected package gcc-s390x-linux-gnu. 182s Preparing to unpack .../22-gcc-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 182s Unpacking gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 182s Selecting previously unselected package gcc. 182s Preparing to unpack .../23-gcc_4%3a13.2.0-7ubuntu1_s390x.deb ... 182s Unpacking gcc (4:13.2.0-7ubuntu1) ... 182s Selecting previously unselected package libstdc++-13-dev:s390x. 182s Preparing to unpack .../24-libstdc++-13-dev_13.2.0-17ubuntu2_s390x.deb ... 182s Unpacking libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 183s Selecting previously unselected package g++-13-s390x-linux-gnu. 183s Preparing to unpack .../25-g++-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 183s Unpacking g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 183s Selecting previously unselected package g++-13. 183s Preparing to unpack .../26-g++-13_13.2.0-17ubuntu2_s390x.deb ... 183s Unpacking g++-13 (13.2.0-17ubuntu2) ... 183s Selecting previously unselected package g++-s390x-linux-gnu. 183s Preparing to unpack .../27-g++-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 183s Unpacking g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 183s Selecting previously unselected package g++. 183s Preparing to unpack .../28-g++_4%3a13.2.0-7ubuntu1_s390x.deb ... 183s Unpacking g++ (4:13.2.0-7ubuntu1) ... 183s Selecting previously unselected package build-essential. 183s Preparing to unpack .../29-build-essential_12.10ubuntu1_s390x.deb ... 183s Unpacking build-essential (12.10ubuntu1) ... 183s Selecting previously unselected package libpkgconf3:s390x. 183s Preparing to unpack .../30-libpkgconf3_1.8.1-2_s390x.deb ... 183s Unpacking libpkgconf3:s390x (1.8.1-2) ... 183s Selecting previously unselected package librdkafka1:s390x. 183s Preparing to unpack .../31-librdkafka1_2.3.0-1_s390x.deb ... 183s Unpacking librdkafka1:s390x (2.3.0-1) ... 183s Selecting previously unselected package librdkafka++1:s390x. 183s Preparing to unpack .../32-librdkafka++1_2.3.0-1_s390x.deb ... 183s Unpacking librdkafka++1:s390x (2.3.0-1) ... 183s Selecting previously unselected package librdkafka-dev:s390x. 183s Preparing to unpack .../33-librdkafka-dev_2.3.0-1_s390x.deb ... 183s Unpacking librdkafka-dev:s390x (2.3.0-1) ... 183s Selecting previously unselected package pkgconf-bin. 183s Preparing to unpack .../34-pkgconf-bin_1.8.1-2_s390x.deb ... 183s Unpacking pkgconf-bin (1.8.1-2) ... 183s Selecting previously unselected package pkgconf:s390x. 183s Preparing to unpack .../35-pkgconf_1.8.1-2_s390x.deb ... 183s Unpacking pkgconf:s390x (1.8.1-2) ... 183s Selecting previously unselected package pkg-config:s390x. 183s Preparing to unpack .../36-pkg-config_1.8.1-2_s390x.deb ... 183s Unpacking pkg-config:s390x (1.8.1-2) ... 183s Selecting previously unselected package autopkgtest-satdep. 183s Preparing to unpack .../37-1-autopkgtest-satdep.deb ... 183s Unpacking autopkgtest-satdep (0) ... 183s Setting up linux-libc-dev:s390x (6.8.0-11.11) ... 183s Setting up libgomp1:s390x (14-20240303-1ubuntu1) ... 183s Setting up libtirpc-dev:s390x (1.3.4+ds-1build1) ... 183s Setting up libpkgconf3:s390x (1.8.1-2) ... 183s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 183s Setting up libmpc3:s390x (1.3.1-1) ... 183s Setting up libatomic1:s390x (14-20240303-1ubuntu1) ... 183s Setting up pkgconf-bin (1.8.1-2) ... 183s Setting up librdkafka1:s390x (2.3.0-1) ... 183s Setting up libubsan1:s390x (14-20240303-1ubuntu1) ... 183s Setting up libnsl-dev:s390x (1.3.0-3) ... 183s Setting up libcrypt-dev:s390x (1:4.4.36-4) ... 183s Setting up libasan8:s390x (14-20240303-1ubuntu1) ... 183s Setting up libisl23:s390x (0.26-3) ... 183s Setting up libc-dev-bin (2.39-0ubuntu2) ... 183s Setting up libcc1-0:s390x (14-20240303-1ubuntu1) ... 183s Setting up libitm1:s390x (14-20240303-1ubuntu1) ... 183s Setting up librdkafka++1:s390x (2.3.0-1) ... 183s Setting up pkgconf:s390x (1.8.1-2) ... 183s Setting up cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 183s Setting up pkg-config:s390x (1.8.1-2) ... 183s Setting up libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 183s Setting up librdkafka-dev:s390x (2.3.0-1) ... 183s Setting up libc6-dev:s390x (2.39-0ubuntu2) ... 183s Setting up libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 183s Setting up cpp-13 (13.2.0-17ubuntu2) ... 183s Setting up cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 183s Setting up gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 183s Setting up gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 183s Setting up g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 183s Setting up gcc-13 (13.2.0-17ubuntu2) ... 183s Setting up cpp (4:13.2.0-7ubuntu1) ... 183s Setting up g++-13 (13.2.0-17ubuntu2) ... 183s Setting up g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 183s Setting up gcc (4:13.2.0-7ubuntu1) ... 183s Setting up g++ (4:13.2.0-7ubuntu1) ... 183s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 183s Setting up build-essential (12.10ubuntu1) ... 183s Setting up autopkgtest-satdep (0) ... 183s Processing triggers for man-db (2.12.0-3) ... 184s Processing triggers for libc-bin (2.39-0ubuntu2) ... 186s (Reading database ... 54621 files and directories currently installed.) 186s Removing autopkgtest-satdep (0) ... 186s autopkgtest [00:30:06]: test examples: [----------------------- 187s Info pkg-config (rdkafka): -lrdkafka 187s Info pkg-config (rdkafka++): -lrdkafka++ 187s Build consumer.c 187s Build producer.c 187s Build rdkafka_example.c 187s Build rdkafka_complex_consumer_example.cpp 187s Build rdkafka_example.cpp 188s Run consumer.c.out 188s Run producer.c.out 188s Run rdkafka_complex_consumer_example.cpp.out 188s Run rdkafka_example.c.out 188s Run rdkafka_example.cpp.out 188s All OK 188s autopkgtest [00:30:08]: test examples: -----------------------] 189s autopkgtest [00:30:09]: test examples: - - - - - - - - - - results - - - - - - - - - - 189s examples PASS 189s autopkgtest [00:30:09]: test unittest: preparing testbed 202s Reading package lists... 202s Building dependency tree... 202s Reading state information... 202s Starting pkgProblemResolver with broken count: 0 202s Starting 2 pkgProblemResolver with broken count: 0 202s Done 202s The following NEW packages will be installed: 202s autopkgtest-satdep 202s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 202s Need to get 0 B/736 B of archives. 202s After this operation, 0 B of additional disk space will be used. 202s Get:1 /tmp/autopkgtest.Lv8DgU/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [736 B] 203s Selecting previously unselected package autopkgtest-satdep. 203s (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 ... 54621 files and directories currently installed.) 203s Preparing to unpack .../2-autopkgtest-satdep.deb ... 203s Unpacking autopkgtest-satdep (0) ... 203s Setting up autopkgtest-satdep (0) ... 204s (Reading database ... 54621 files and directories currently installed.) 204s Removing autopkgtest-satdep (0) ... 205s autopkgtest [00:30:25]: test unittest: [----------------------- 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: empty tqh[0] 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 1,0 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 2,1,0 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,2 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1,2 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,0,2 205s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 2,0,1 205s RDUT: PASS: rdunittest.c:345: unittest_sysqueue 205s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sysqueue: PASS 205s RDUT: INFO: rdstring.c:409: ut_strcasestr: BEGIN:  205s RDUT: PASS: rdstring.c:425: ut_strcasestr 205s RDUT: INFO: rdstring.c:606: ut_string_split: BEGIN:  205s RDUT: PASS: rdstring.c:632: ut_string_split 205s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: string: PASS 205s RDUT: INFO: rdmap.c:457: unittest_untyped_map: 500000 map_get iterations took 174.447ms = 0us/get 205s RDUT: INFO: rdmap.c:474: unittest_untyped_map: Total time over 100000 entries took 232.289ms 205s RDUT: PASS: rdmap.c:477: unittest_untyped_map 205s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 2 person Hedvig Lindahl 205s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 1 person Roy McPhearsome 205s RDUT: PASS: rdmap.c:323: unittest_typed_map 205s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: map: PASS 205s RDUT: PASS: rdbuf.c:1353: do_unittest_write_read 205s RDUT: PASS: rdbuf.c:1518: do_unittest_write_split_seek 205s RDUT: PASS: rdbuf.c:1608: do_unittest_write_read_payload_correctness 205s RDUT: PASS: rdbuf.c:1676: do_unittest_write_iov 205s RDUT: PASS: rdbuf.c:1866: do_unittest_erase 205s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdbuf: PASS 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 205s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdvarint: PASS 205s RDUT: INFO: crc32c.c:411: unittest_rd_crc32c: Calculate CRC32C using software 205s RDUT: INFO: crc32c.c:422: unittest_rd_crc32c: Calculate CRC32C using software 205s RDUT: PASS: crc32c.c:429: unittest_rd_crc32c 205s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: crc32c: PASS 205s RDUT: INFO: rdkafka_msg.c:1999: unittest_msgq_order: FIFO: testing in FIFO mode 205s RDUT: PASS: rdkafka_msg.c:2194: unittest_msg_seq_wrap 205s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: get baseline insert time 205s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 2 messages into destq with 2 messages 205s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 1us, 0.2500us/msg 205s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 205s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: get baseline insert time 205s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 2 messages 205s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 205s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 3 messages 205s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 205s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 2 messages in 0us 205s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 205s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: single-message ranges 205s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4 messages into destq with 5 messages 205s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 205s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 205s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: single-message ranges 205s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 5 messages 205s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 205s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 6 messages 205s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 205s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 7 messages 205s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 205s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 8 messages 205s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 205s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 4 messages in 0us 205s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 205s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: many messages 206s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4315956 messages into destq with 165288 messages 206s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 14801us, 0.0033us/msg 207s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 207s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: many messages 207s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 100001 messages into destq with 165288 messages 207s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 10us, 0.0001us/msg 207s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 50001 messages into destq with 265289 messages 207s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1770us, 0.0354us/msg 207s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 20001 messages into destq with 315290 messages 207s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 3389us, 0.1694us/msg 207s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 335291 messages 207s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 3809us, 0.0644us/msg 207s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 394420 messages 207s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 8266us, 0.0952us/msg 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4000001 messages into destq with 481243 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 28789us, 0.0072us/msg 208s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0107us/msg over 4315956 messages in 46033us 208s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 208s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2508 208s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 145952 messages into destq with 154875 messages 208s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 1966us, 0.0065us/msg 208s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 208s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2508 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 154875 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 214004 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 145952 messages in 1us 209s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 209s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2450 (v1.2.1 regression) 209s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 86 messages into destq with 199999 messages 209s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 209s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 209s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2450 (v1.2.1 regression) 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 199999 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 5 messages into destq with 200001 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4 messages into destq with 200006 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.2500us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200010 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200012 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 61 messages into destq with 200015 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200076 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200078 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200080 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200082 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0116us/msg over 86 messages in 1us 209s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: msg: PASS 209s RDUT: PASS: rdmurmur2.c:166: unittest_murmur2 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: murmurhash: PASS 209s RDUT: PASS: rdfnv1a.c:112: unittest_fnv1a 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: fnv1a: PASS 209s RDUT: PASS: rdhdrhistogram.c:468: ut_high_sigfig 209s RDUT: PASS: rdhdrhistogram.c:495: ut_quantile 209s RDUT: PASS: rdhdrhistogram.c:514: ut_mean 209s RDUT: PASS: rdhdrhistogram.c:536: ut_stddev 209s RDUT: PASS: rdhdrhistogram.c:555: ut_totalcount 209s RDUT: PASS: rdhdrhistogram.c:573: ut_max 209s RDUT: PASS: rdhdrhistogram.c:590: ut_min 209s RDUT: PASS: rdhdrhistogram.c:609: ut_reset 209s RDUT: PASS: rdhdrhistogram.c:623: ut_nan 209s RDUT: PASS: rdhdrhistogram.c:638: ut_sigfigs 209s RDUT: PASS: rdhdrhistogram.c:654: ut_minmax_trackable 209s RDUT: PASS: rdhdrhistogram.c:664: ut_unitmagnitude_overflow 209s RDUT: PASS: rdhdrhistogram.c:697: ut_subbucketmask_overflow 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdhdrhistogram: PASS 209s RDUT: INFO: rdkafka_conf.c:4415: unittest_conf: Safified client.software.name="aba.-va" 209s RDUT: INFO: rdkafka_conf.c:4423: unittest_conf: Safified client.software.version="1.2.3.4.5----a" 209s RDUT: PASS: rdkafka_conf.c:4427: unittest_conf 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: conf: PASS 209s RDUT: PASS: rdkafka_broker.c:2175: rd_ut_reconnect_backoff 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: broker: PASS 209s RDUT: INFO: rdkafka_request.c:5574: unittest_idempotent_producer: Verifying idempotent producer error handling 209s %5|1711067429.101|CONFWARN|rdkafka#producer-1| [thrd:app]: No `bootstrap.servers` configured: client will not be able to connect to Kafka cluster 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 210s RDUT: PASS: rdkafka_request.c:5769: unittest_idempotent_producer 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: request: PASS 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1585: do_unittest_config_no_principal_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1614: do_unittest_config_empty_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1681: do_unittest_config_empty_value_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1719: do_unittest_config_value_with_quote_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1644: do_unittest_config_unrecognized_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1468: do_unittest_config_defaults 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1511: do_unittest_config_explicit_scope_and_life 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1555: do_unittest_config_all_explicit_values 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1758: do_unittest_config_extensions 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1777: do_unittest_illegal_extension_keys_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1810: do_unittest_odd_extension_size_should_fail 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sasl_oauthbearer: PASS 210s RDUT: PASS: rdkafka_msgset_reader.c:1805: unittest_aborted_txns 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: aborted_txns: PASS 210s RDUT: PASS: rdkafka_cgrp.c:5806: unittest_consumer_group_metadata 210s RDUT: PASS: rdkafka_cgrp.c:5877: unittest_set_intersect 210s RDUT: PASS: rdkafka_cgrp.c:5926: unittest_set_subtract 210s RDUT: PASS: rdkafka_cgrp.c:5953: unittest_map_to_list 210s RDUT: PASS: rdkafka_cgrp.c:5983: unittest_list_to_map 210s RDUT: PASS: rdkafka_cgrp.c:6052: unittest_member_metadata_serdes 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: cgrp: PASS 210s RDUT: PASS: rdkafka_sasl_scram.c:810: unittest_scram_nonce 210s RDUT: PASS: rdkafka_sasl_scram.c:849: unittest_scram_safe 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: scram: PASS 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: range assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: roundrobin assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): range assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): roundrobin assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): range assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): roundrobin assignor 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:645: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #0 ran for 0.240ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:679: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #1 ran for 0.031ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #2 ran for 0.020ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #3 ran for 0.021ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #4 ran for 0.022ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #5 ran for 0.018ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #6 ran for 0.018ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #7 ran for 0.032ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #8 ran for 0.029ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1167: ut_testRackAwareAssignmentWithUniformSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #9 ran for 0.249ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1258: ut_testRackAwareAssignmentWithNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #10 ran for 0.237ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1318: ut_testRackAwareAssignmentWithUniformPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #11 ran for 0.237ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1411: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #12 ran for 0.238ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1493: ut_testRackAwareAssignmentWithCoPartitioning0 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #13 ran for 0.088ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1595: ut_testRackAwareAssignmentWithCoPartitioning1 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #14 ran for 0.133ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1652: ut_testCoPartitionedAssignmentWithSameSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #15 ran for 0.093ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #0 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_sticky_assignor.c:2255: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #0 ran for 0.413ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #1 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_sticky_assignor.c:2293: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #1 ran for 0.218ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #2 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #2 ran for 0.044ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #3 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #3 ran for 0.043ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #4 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #4 ran for 0.043ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #5 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #5 ran for 0.039ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #6 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #6 ran for 0.044ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #7 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #7 ran for 0.074ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #8 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #8 ran for 0.078ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #9 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #9 ran for 0.127ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #10 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #10 ran for 0.209ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #11 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #11 ran for 0.193ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #12 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #12 ran for 16.199ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #13 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #13 ran for 0.845ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #14 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #14 ran for 10.033ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #15 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 1 ] 211s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 211s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 211s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 211s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 2 ] 211s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #15 ran for 2008.207ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #16 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #16 ran for 1.147ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #17 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #17 ran for 0.697ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #18 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #18 ran for 0.538ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #19 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #19 ran for 0.517ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #20 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #20 ran for 0.686ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #21 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #21 ran for 1.611ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #22 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #22 ran for 1.748ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #23 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #23 ran for 0.074ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #24 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 0 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 1 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 2 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #24 ran for 0.009ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #25 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #25 ran for 0.078ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #26 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #26 ran for 0.088ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #27 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #27 ran for 0.086ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #28 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 0 ] 212s %3|1711067432.154|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. 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 1 ] 212s %3|1711067432.154|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. 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 2 ] 212s %3|1711067432.155|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. 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #28 ran for 0.751ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #29 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 0 ] 212s %3|1711067432.155|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. 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 1 ] 212s %3|1711067432.155|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. 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 2 ] 212s %3|1711067432.155|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. 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #29 ran for 0.748ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #30 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #30 ran for 0.561ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #31 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #31 ran for 0.611ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #32 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 0 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #32 ran for 0.513ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #33 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 0 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 1 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4522: ut_testRackAwareAssignmentWithUniformSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #33 ran for 1.379ms ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #34 ] 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 0 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 1 ] 212s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 2 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:4663: ut_testRackAwareAssignmentWithNonEqualSubscription 212s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #34 ran for 1.202ms ] 212s RDUT: PASS: rdkafka_assignor.c:1776: ut_assignors 212s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: assignors: PASS 212s autopkgtest [00:30:32]: test unittest: -----------------------] 213s autopkgtest [00:30:33]: test unittest: - - - - - - - - - - results - - - - - - - - - - 213s unittest PASS 213s autopkgtest [00:30:33]: @@@@@@@@@@@@@@@@@@@@ summary 213s examples PASS 213s unittest PASS 224s Creating nova instance adt-noble-s390x-librdkafka-20240322-002700-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...