0s autopkgtest [16:26:19]: starting date and time: 2024-03-21 16:26:19+0000 0s autopkgtest [16:26:19]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [16:26:19]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.jrm2_x_8/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-arm64-10.secgroup --name adt-noble-arm64-librdkafka-20240321-162619-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-arm64-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/ 161s autopkgtest [16:29:00]: testbed dpkg architecture: arm64 161s autopkgtest [16:29:00]: testbed apt version: 2.7.12 161s autopkgtest [16:29:00]: @@@@@@@@@@@@@@@@@@@@ test bed setup 163s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 164s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 164s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 164s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 164s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3757 kB] 165s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [677 kB] 165s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 165s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.7 kB] 165s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 165s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4172 kB] 166s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 166s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [67.4 kB] 166s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 176s Fetched 9391 kB in 5s (1802 kB/s) 177s Reading package lists... 186s Reading package lists... 187s Building dependency tree... 187s Reading state information... 188s Calculating upgrade... 190s The following packages will be REMOVED: 190s libssl3 190s The following NEW packages will be installed: 190s libssl3t64 190s The following packages have been kept back: 190s libsasl2-2 libsasl2-modules-db 190s The following packages will be upgraded: 190s debianutils libsasl2-modules linux-firmware openssl 190s 4 upgraded, 1 newly installed, 1 to remove and 2 not upgraded. 190s Need to get 478 MB of archives. 190s After this operation, 5863 kB of additional disk space will be used. 190s Get:1 http://ftpmaster.internal/ubuntu noble/main arm64 debianutils arm64 5.17 [90.1 kB] 190s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu2 [985 kB] 191s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libsasl2-modules arm64 2.1.28+dfsg1-5ubuntu1 [69.3 kB] 191s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu2 [1793 kB] 191s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 linux-firmware arm64 20240318.git3b128b60-0ubuntu1 [475 MB] 235s Fetched 478 MB in 43s (11.0 MB/s) 235s (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 ... 75850 files and directories currently installed.) 235s Preparing to unpack .../debianutils_5.17_arm64.deb ... 235s Unpacking debianutils (5.17) over (5.16) ... 235s Setting up debianutils (5.17) ... 236s (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 ... 75850 files and directories currently installed.) 236s Preparing to unpack .../openssl_3.0.13-0ubuntu2_arm64.deb ... 236s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 236s Preparing to unpack .../libsasl2-modules_2.1.28+dfsg1-5ubuntu1_arm64.deb ... 236s Unpacking libsasl2-modules:arm64 (2.1.28+dfsg1-5ubuntu1) over (2.1.28+dfsg1-4) ... 236s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 236s wget depends on libssl3 (>= 3.0.0). 236s u-boot-tools depends on libssl3 (>= 3.0.0). 236s tnftp depends on libssl3 (>= 3.0.0). 236s tcpdump depends on libssl3 (>= 3.0.0). 236s systemd-resolved depends on libssl3 (>= 3.0.0). 236s systemd depends on libssl3 (>= 3.0.0). 236s sudo depends on libssl3 (>= 3.0.0). 236s sbsigntool depends on libssl3 (>= 3.0.0). 236s rsync depends on libssl3 (>= 3.0.0). 236s python3-cryptography depends on libssl3 (>= 3.0.0). 236s openssh-server depends on libssl3 (>= 3.0.10). 236s openssh-client depends on libssl3 (>= 3.0.10). 236s mtd-utils depends on libssl3 (>= 3.0.0). 236s mokutil depends on libssl3 (>= 3.0.0). 236s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 236s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 236s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 236s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 236s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 236s libpython3.11-minimal:arm64 depends on libssl3 (>= 3.0.0). 236s libnvme1 depends on libssl3 (>= 3.0.0). 236s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 236s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 236s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 236s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 236s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 236s kmod depends on libssl3 (>= 3.0.0). 236s dhcpcd-base depends on libssl3 (>= 3.0.0). 236s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 236s 236s (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 ... 75850 files and directories currently installed.) 237s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 237s Selecting previously unselected package libssl3t64:arm64. 237s (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 ... 75839 files and directories currently installed.) 237s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_arm64.deb ... 237s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu2) ... 237s Preparing to unpack .../linux-firmware_20240318.git3b128b60-0ubuntu1_arm64.deb ... 237s Unpacking linux-firmware (20240318.git3b128b60-0ubuntu1) over (20240202.git36777504-0ubuntu1) ... 251s Setting up linux-firmware (20240318.git3b128b60-0ubuntu1) ... 251s Setting up libssl3t64:arm64 (3.0.13-0ubuntu2) ... 251s Setting up libsasl2-modules:arm64 (2.1.28+dfsg1-5ubuntu1) ... 251s Setting up openssl (3.0.13-0ubuntu2) ... 251s Processing triggers for man-db (2.12.0-3) ... 254s Processing triggers for libc-bin (2.39-0ubuntu2) ... 254s Processing triggers for initramfs-tools (0.142ubuntu20) ... 254s update-initramfs: Generating /boot/initrd.img-6.8.0-11-generic 254s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 319s System running in EFI mode, skipping. 320s Reading package lists... 321s Building dependency tree... 321s Reading state information... 323s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 324s sh: Attempting to set up Debian/Ubuntu apt sources automatically 324s sh: Distribution appears to be Ubuntu 328s Reading package lists... 328s Building dependency tree... 329s Reading state information... 330s eatmydata is already the newest version (131-1). 330s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 330s Reading package lists... 330s Building dependency tree... 330s Reading state information... 332s dbus is already the newest version (1.14.10-4ubuntu1). 332s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 332s Reading package lists... 333s Building dependency tree... 333s Reading state information... 334s rng-tools-debian is already the newest version (2.4). 334s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 334s Reading package lists... 335s Building dependency tree... 335s Reading state information... 336s The following packages will be REMOVED: 336s cloud-init* python3-configobj* python3-debconf* 337s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 337s After this operation, 3252 kB disk space will be freed. 337s (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 ... 75914 files and directories currently installed.) 337s Removing cloud-init (24.1.1-0ubuntu1) ... 339s Removing python3-configobj (5.0.8-3) ... 340s Removing python3-debconf (1.5.86) ... 340s Processing triggers for man-db (2.12.0-3) ... 341s (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 ... 75525 files and directories currently installed.) 341s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 343s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 343s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 343s invoke-rc.d: policy-rc.d denied execution of try-restart. 344s Reading package lists... 344s Building dependency tree... 344s Reading state information... 346s linux-generic is already the newest version (6.8.0-11.11+1). 346s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 347s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 347s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 347s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 355s Reading package lists... 356s Reading package lists... 356s Building dependency tree... 356s Reading state information... 358s Calculating upgrade... 359s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 359s Reading package lists... 359s Building dependency tree... 359s Reading state information... 361s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 361s autopkgtest [16:32:20]: rebooting testbed after setup commands that affected boot 527s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 537s autopkgtest [16:35:16]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 542s autopkgtest [16:35:21]: @@@@@@@@@@@@@@@@@@@@ apt-source librdkafka 551s Get:1 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (dsc) [2258 B] 551s Get:2 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (tar) [4381 kB] 551s Get:3 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (diff) [12.3 kB] 552s gpgv: Signature made Fri Oct 27 14:34:28 2023 UTC 552s gpgv: using RSA key A9592C521CB904077D6598009D0B5E5B1EEC8F0E 552s gpgv: Can't check signature: No public key 552s dpkg-source: warning: cannot verify inline signature for ./librdkafka_2.3.0-1.dsc: no acceptable signature found 554s autopkgtest [16:35:33]: testing package librdkafka version 2.3.0-1 554s autopkgtest [16:35:33]: build not needed 563s autopkgtest [16:35:42]: test examples: preparing testbed 571s Reading package lists... 572s Building dependency tree... 572s Reading state information... 573s Starting pkgProblemResolver with broken count: 0 573s Starting 2 pkgProblemResolver with broken count: 0 573s Done 575s The following additional packages will be installed: 575s build-essential cpp cpp-13 cpp-13-aarch64-linux-gnu cpp-aarch64-linux-gnu 575s g++ g++-13 g++-13-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-13 575s gcc-13-aarch64-linux-gnu gcc-aarch64-linux-gnu libasan8 libatomic1 575s libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libgcc-13-dev libgomp1 575s libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libnsl-dev libpkgconf3 575s librdkafka++1 librdkafka-dev librdkafka1 libstdc++-13-dev libtirpc-dev 575s libtsan2 libubsan1 linux-libc-dev pkg-config pkgconf pkgconf-bin 575s rpcsvc-proto 575s Suggested packages: 575s cpp-doc gcc-13-locales cpp-13-doc gcc-13-doc gcc-multilib manpages-dev 575s autoconf automake libtool flex bison gdb gcc-doc gdb-aarch64-linux-gnu 575s glibc-doc libstdc++-13-doc 575s Recommended packages: 575s manpages manpages-dev libc-devtools 575s The following NEW packages will be installed: 575s autopkgtest-satdep build-essential cpp cpp-13 cpp-13-aarch64-linux-gnu 575s cpp-aarch64-linux-gnu g++ g++-13 g++-13-aarch64-linux-gnu 575s g++-aarch64-linux-gnu gcc gcc-13 gcc-13-aarch64-linux-gnu 575s gcc-aarch64-linux-gnu libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 575s libcrypt-dev libgcc-13-dev libgomp1 libhwasan0 libisl23 libitm1 liblsan0 575s libmpc3 libnsl-dev libpkgconf3 librdkafka++1 librdkafka-dev librdkafka1 575s libstdc++-13-dev libtirpc-dev libtsan2 libubsan1 linux-libc-dev pkg-config 575s pkgconf pkgconf-bin rpcsvc-proto 576s 0 upgraded, 41 newly installed, 0 to remove and 0 not upgraded. 576s Need to get 63.5 MB/63.5 MB of archives. 576s After this operation, 229 MB of additional disk space will be used. 576s Get:1 /tmp/autopkgtest.smGJoc/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [728 B] 576s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 libc-dev-bin arm64 2.39-0ubuntu2 [19.7 kB] 576s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 linux-libc-dev arm64 6.8.0-11.11 [1569 kB] 576s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libcrypt-dev arm64 1:4.4.36-4 [136 kB] 576s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libtirpc-dev arm64 1.3.4+ds-1build1 [232 kB] 577s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 libnsl-dev arm64 1.3.0-3 [71.9 kB] 577s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 rpcsvc-proto arm64 1.4.2-0ubuntu6 [65.4 kB] 577s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 libc6-dev arm64 2.39-0ubuntu2 [1596 kB] 577s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 libisl23 arm64 0.26-3 [713 kB] 577s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 libmpc3 arm64 1.3.1-1 [55.3 kB] 577s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [10.3 MB] 578s Get:12 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-13 arm64 13.2.0-17ubuntu2 [1028 B] 578s Get:13 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [5316 B] 578s Get:14 http://ftpmaster.internal/ubuntu noble/main arm64 cpp arm64 4:13.2.0-7ubuntu1 [22.4 kB] 578s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 libcc1-0 arm64 14-20240303-1ubuntu1 [44.7 kB] 578s Get:16 http://ftpmaster.internal/ubuntu noble/main arm64 libgomp1 arm64 14-20240303-1ubuntu1 [144 kB] 578s Get:17 http://ftpmaster.internal/ubuntu noble/main arm64 libitm1 arm64 14-20240303-1ubuntu1 [27.7 kB] 578s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 libatomic1 arm64 14-20240303-1ubuntu1 [11.4 kB] 578s Get:19 http://ftpmaster.internal/ubuntu noble/main arm64 libasan8 arm64 14-20240303-1ubuntu1 [2919 kB] 578s Get:20 http://ftpmaster.internal/ubuntu noble/main arm64 liblsan0 arm64 14-20240303-1ubuntu1 [1282 kB] 578s Get:21 http://ftpmaster.internal/ubuntu noble/main arm64 libtsan2 arm64 14-20240303-1ubuntu1 [2687 kB] 579s Get:22 http://ftpmaster.internal/ubuntu noble/main arm64 libubsan1 arm64 14-20240303-1ubuntu1 [1151 kB] 579s Get:23 http://ftpmaster.internal/ubuntu noble/main arm64 libhwasan0 arm64 14-20240303-1ubuntu1 [1597 kB] 579s Get:24 http://ftpmaster.internal/ubuntu noble/main arm64 libgcc-13-dev arm64 13.2.0-17ubuntu2 [2464 kB] 579s Get:25 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [20.1 MB] 581s Get:26 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-13 arm64 13.2.0-17ubuntu2 [467 kB] 581s Get:27 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [1198 B] 581s Get:28 http://ftpmaster.internal/ubuntu noble/main arm64 gcc arm64 4:13.2.0-7ubuntu1 [5018 B] 581s Get:29 http://ftpmaster.internal/ubuntu noble/main arm64 libstdc++-13-dev arm64 13.2.0-17ubuntu2 [2322 kB] 582s Get:30 http://ftpmaster.internal/ubuntu noble/main arm64 g++-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [11.7 MB] 583s Get:31 http://ftpmaster.internal/ubuntu noble/main arm64 g++-13 arm64 13.2.0-17ubuntu2 [14.4 kB] 583s Get:32 http://ftpmaster.internal/ubuntu noble/main arm64 g++-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [962 B] 583s Get:33 http://ftpmaster.internal/ubuntu noble/main arm64 g++ arm64 4:13.2.0-7ubuntu1 [1082 B] 583s Get:34 http://ftpmaster.internal/ubuntu noble/main arm64 build-essential arm64 12.10ubuntu1 [4932 B] 583s Get:35 http://ftpmaster.internal/ubuntu noble/main arm64 libpkgconf3 arm64 1.8.1-2 [31.2 kB] 583s Get:36 http://ftpmaster.internal/ubuntu noble/universe arm64 librdkafka1 arm64 2.3.0-1 [703 kB] 583s Get:37 http://ftpmaster.internal/ubuntu noble/universe arm64 librdkafka++1 arm64 2.3.0-1 [43.4 kB] 583s Get:38 http://ftpmaster.internal/ubuntu noble/universe arm64 librdkafka-dev arm64 2.3.0-1 [965 kB] 583s Get:39 http://ftpmaster.internal/ubuntu noble/main arm64 pkgconf-bin arm64 1.8.1-2 [20.4 kB] 583s Get:40 http://ftpmaster.internal/ubuntu noble/main arm64 pkgconf arm64 1.8.1-2 [16.7 kB] 583s Get:41 http://ftpmaster.internal/ubuntu noble/main arm64 pkg-config arm64 1.8.1-2 [7170 B] 584s Fetched 63.5 MB in 7s (8472 kB/s) 584s Selecting previously unselected package libc-dev-bin. 585s (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 ... 75470 files and directories currently installed.) 585s Preparing to unpack .../00-libc-dev-bin_2.39-0ubuntu2_arm64.deb ... 585s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 585s Selecting previously unselected package linux-libc-dev:arm64. 585s Preparing to unpack .../01-linux-libc-dev_6.8.0-11.11_arm64.deb ... 585s Unpacking linux-libc-dev:arm64 (6.8.0-11.11) ... 586s Selecting previously unselected package libcrypt-dev:arm64. 586s Preparing to unpack .../02-libcrypt-dev_1%3a4.4.36-4_arm64.deb ... 586s Unpacking libcrypt-dev:arm64 (1:4.4.36-4) ... 586s Selecting previously unselected package libtirpc-dev:arm64. 586s Preparing to unpack .../03-libtirpc-dev_1.3.4+ds-1build1_arm64.deb ... 586s Unpacking libtirpc-dev:arm64 (1.3.4+ds-1build1) ... 586s Selecting previously unselected package libnsl-dev:arm64. 586s Preparing to unpack .../04-libnsl-dev_1.3.0-3_arm64.deb ... 586s Unpacking libnsl-dev:arm64 (1.3.0-3) ... 586s Selecting previously unselected package rpcsvc-proto. 586s Preparing to unpack .../05-rpcsvc-proto_1.4.2-0ubuntu6_arm64.deb ... 586s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 586s Selecting previously unselected package libc6-dev:arm64. 586s Preparing to unpack .../06-libc6-dev_2.39-0ubuntu2_arm64.deb ... 586s Unpacking libc6-dev:arm64 (2.39-0ubuntu2) ... 587s Selecting previously unselected package libisl23:arm64. 587s Preparing to unpack .../07-libisl23_0.26-3_arm64.deb ... 587s Unpacking libisl23:arm64 (0.26-3) ... 587s Selecting previously unselected package libmpc3:arm64. 587s Preparing to unpack .../08-libmpc3_1.3.1-1_arm64.deb ... 587s Unpacking libmpc3:arm64 (1.3.1-1) ... 587s Selecting previously unselected package cpp-13-aarch64-linux-gnu. 587s Preparing to unpack .../09-cpp-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 587s Unpacking cpp-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 588s Selecting previously unselected package cpp-13. 588s Preparing to unpack .../10-cpp-13_13.2.0-17ubuntu2_arm64.deb ... 588s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 588s Selecting previously unselected package cpp-aarch64-linux-gnu. 588s Preparing to unpack .../11-cpp-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 588s Unpacking cpp-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 588s Selecting previously unselected package cpp. 588s Preparing to unpack .../12-cpp_4%3a13.2.0-7ubuntu1_arm64.deb ... 588s Unpacking cpp (4:13.2.0-7ubuntu1) ... 588s Selecting previously unselected package libcc1-0:arm64. 588s Preparing to unpack .../13-libcc1-0_14-20240303-1ubuntu1_arm64.deb ... 588s Unpacking libcc1-0:arm64 (14-20240303-1ubuntu1) ... 588s Selecting previously unselected package libgomp1:arm64. 588s Preparing to unpack .../14-libgomp1_14-20240303-1ubuntu1_arm64.deb ... 588s Unpacking libgomp1:arm64 (14-20240303-1ubuntu1) ... 588s Selecting previously unselected package libitm1:arm64. 588s Preparing to unpack .../15-libitm1_14-20240303-1ubuntu1_arm64.deb ... 588s Unpacking libitm1:arm64 (14-20240303-1ubuntu1) ... 589s Selecting previously unselected package libatomic1:arm64. 589s Preparing to unpack .../16-libatomic1_14-20240303-1ubuntu1_arm64.deb ... 589s Unpacking libatomic1:arm64 (14-20240303-1ubuntu1) ... 589s Selecting previously unselected package libasan8:arm64. 589s Preparing to unpack .../17-libasan8_14-20240303-1ubuntu1_arm64.deb ... 589s Unpacking libasan8:arm64 (14-20240303-1ubuntu1) ... 589s Selecting previously unselected package liblsan0:arm64. 589s Preparing to unpack .../18-liblsan0_14-20240303-1ubuntu1_arm64.deb ... 589s Unpacking liblsan0:arm64 (14-20240303-1ubuntu1) ... 589s Selecting previously unselected package libtsan2:arm64. 589s Preparing to unpack .../19-libtsan2_14-20240303-1ubuntu1_arm64.deb ... 589s Unpacking libtsan2:arm64 (14-20240303-1ubuntu1) ... 590s Selecting previously unselected package libubsan1:arm64. 590s Preparing to unpack .../20-libubsan1_14-20240303-1ubuntu1_arm64.deb ... 590s Unpacking libubsan1:arm64 (14-20240303-1ubuntu1) ... 590s Selecting previously unselected package libhwasan0:arm64. 590s Preparing to unpack .../21-libhwasan0_14-20240303-1ubuntu1_arm64.deb ... 590s Unpacking libhwasan0:arm64 (14-20240303-1ubuntu1) ... 590s Selecting previously unselected package libgcc-13-dev:arm64. 590s Preparing to unpack .../22-libgcc-13-dev_13.2.0-17ubuntu2_arm64.deb ... 590s Unpacking libgcc-13-dev:arm64 (13.2.0-17ubuntu2) ... 591s Selecting previously unselected package gcc-13-aarch64-linux-gnu. 591s Preparing to unpack .../23-gcc-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 591s Unpacking gcc-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 594s Selecting previously unselected package gcc-13. 594s Preparing to unpack .../24-gcc-13_13.2.0-17ubuntu2_arm64.deb ... 594s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 594s Selecting previously unselected package gcc-aarch64-linux-gnu. 594s Preparing to unpack .../25-gcc-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 594s Unpacking gcc-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 594s Selecting previously unselected package gcc. 594s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_arm64.deb ... 594s Unpacking gcc (4:13.2.0-7ubuntu1) ... 594s Selecting previously unselected package libstdc++-13-dev:arm64. 594s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-17ubuntu2_arm64.deb ... 594s Unpacking libstdc++-13-dev:arm64 (13.2.0-17ubuntu2) ... 595s Selecting previously unselected package g++-13-aarch64-linux-gnu. 595s Preparing to unpack .../28-g++-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 595s Unpacking g++-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 596s Selecting previously unselected package g++-13. 596s Preparing to unpack .../29-g++-13_13.2.0-17ubuntu2_arm64.deb ... 596s Unpacking g++-13 (13.2.0-17ubuntu2) ... 596s Selecting previously unselected package g++-aarch64-linux-gnu. 596s Preparing to unpack .../30-g++-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 596s Unpacking g++-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 596s Selecting previously unselected package g++. 596s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_arm64.deb ... 596s Unpacking g++ (4:13.2.0-7ubuntu1) ... 596s Selecting previously unselected package build-essential. 596s Preparing to unpack .../32-build-essential_12.10ubuntu1_arm64.deb ... 596s Unpacking build-essential (12.10ubuntu1) ... 597s Selecting previously unselected package libpkgconf3:arm64. 597s Preparing to unpack .../33-libpkgconf3_1.8.1-2_arm64.deb ... 597s Unpacking libpkgconf3:arm64 (1.8.1-2) ... 597s Selecting previously unselected package librdkafka1:arm64. 597s Preparing to unpack .../34-librdkafka1_2.3.0-1_arm64.deb ... 597s Unpacking librdkafka1:arm64 (2.3.0-1) ... 597s Selecting previously unselected package librdkafka++1:arm64. 597s Preparing to unpack .../35-librdkafka++1_2.3.0-1_arm64.deb ... 597s Unpacking librdkafka++1:arm64 (2.3.0-1) ... 597s Selecting previously unselected package librdkafka-dev:arm64. 597s Preparing to unpack .../36-librdkafka-dev_2.3.0-1_arm64.deb ... 597s Unpacking librdkafka-dev:arm64 (2.3.0-1) ... 597s Selecting previously unselected package pkgconf-bin. 597s Preparing to unpack .../37-pkgconf-bin_1.8.1-2_arm64.deb ... 597s Unpacking pkgconf-bin (1.8.1-2) ... 597s Selecting previously unselected package pkgconf:arm64. 597s Preparing to unpack .../38-pkgconf_1.8.1-2_arm64.deb ... 597s Unpacking pkgconf:arm64 (1.8.1-2) ... 597s Selecting previously unselected package pkg-config:arm64. 597s Preparing to unpack .../39-pkg-config_1.8.1-2_arm64.deb ... 597s Unpacking pkg-config:arm64 (1.8.1-2) ... 597s Selecting previously unselected package autopkgtest-satdep. 597s Preparing to unpack .../40-1-autopkgtest-satdep.deb ... 597s Unpacking autopkgtest-satdep (0) ... 598s Setting up linux-libc-dev:arm64 (6.8.0-11.11) ... 598s Setting up libgomp1:arm64 (14-20240303-1ubuntu1) ... 598s Setting up libtirpc-dev:arm64 (1.3.4+ds-1build1) ... 598s Setting up libpkgconf3:arm64 (1.8.1-2) ... 598s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 598s Setting up libmpc3:arm64 (1.3.1-1) ... 598s Setting up libatomic1:arm64 (14-20240303-1ubuntu1) ... 598s Setting up pkgconf-bin (1.8.1-2) ... 598s Setting up librdkafka1:arm64 (2.3.0-1) ... 598s Setting up libubsan1:arm64 (14-20240303-1ubuntu1) ... 598s Setting up libnsl-dev:arm64 (1.3.0-3) ... 598s Setting up libhwasan0:arm64 (14-20240303-1ubuntu1) ... 598s Setting up libcrypt-dev:arm64 (1:4.4.36-4) ... 598s Setting up libasan8:arm64 (14-20240303-1ubuntu1) ... 598s Setting up libtsan2:arm64 (14-20240303-1ubuntu1) ... 598s Setting up libisl23:arm64 (0.26-3) ... 598s Setting up libc-dev-bin (2.39-0ubuntu2) ... 598s Setting up libcc1-0:arm64 (14-20240303-1ubuntu1) ... 598s Setting up liblsan0:arm64 (14-20240303-1ubuntu1) ... 598s Setting up libitm1:arm64 (14-20240303-1ubuntu1) ... 598s Setting up cpp-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 598s Setting up librdkafka++1:arm64 (2.3.0-1) ... 598s Setting up pkgconf:arm64 (1.8.1-2) ... 598s Setting up cpp-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 598s Setting up pkg-config:arm64 (1.8.1-2) ... 598s Setting up libgcc-13-dev:arm64 (13.2.0-17ubuntu2) ... 598s Setting up librdkafka-dev:arm64 (2.3.0-1) ... 598s Setting up libc6-dev:arm64 (2.39-0ubuntu2) ... 598s Setting up libstdc++-13-dev:arm64 (13.2.0-17ubuntu2) ... 598s Setting up cpp-13 (13.2.0-17ubuntu2) ... 598s Setting up gcc-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 598s Setting up gcc-13 (13.2.0-17ubuntu2) ... 598s Setting up cpp (4:13.2.0-7ubuntu1) ... 598s Setting up g++-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 598s Setting up gcc-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 598s Setting up g++-13 (13.2.0-17ubuntu2) ... 598s Setting up gcc (4:13.2.0-7ubuntu1) ... 598s Setting up g++-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 598s Setting up g++ (4:13.2.0-7ubuntu1) ... 598s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 598s Setting up build-essential (12.10ubuntu1) ... 598s Setting up autopkgtest-satdep (0) ... 598s Processing triggers for man-db (2.12.0-3) ... 600s Processing triggers for libc-bin (2.39-0ubuntu2) ... 611s (Reading database ... 78367 files and directories currently installed.) 611s Removing autopkgtest-satdep (0) ... 612s autopkgtest [16:36:31]: test examples: [----------------------- 613s Info pkg-config (rdkafka): -lrdkafka 613s Info pkg-config (rdkafka++): -lrdkafka++ 613s Build consumer.c 613s Build producer.c 613s Build rdkafka_example.c 614s Build rdkafka_complex_consumer_example.cpp 617s Build rdkafka_example.cpp 620s Run consumer.c.out 620s Run producer.c.out 620s Run rdkafka_complex_consumer_example.cpp.out 620s Run rdkafka_example.c.out 620s Run rdkafka_example.cpp.out 620s All OK 620s autopkgtest [16:36:39]: test examples: -----------------------] 621s autopkgtest [16:36:40]: test examples: - - - - - - - - - - results - - - - - - - - - - 621s examples PASS 622s autopkgtest [16:36:41]: test unittest: preparing testbed 629s Reading package lists... 630s Building dependency tree... 630s Reading state information... 630s Starting pkgProblemResolver with broken count: 0 631s Starting 2 pkgProblemResolver with broken count: 0 631s Done 633s The following NEW packages will be installed: 633s autopkgtest-satdep 633s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 633s Need to get 0 B/732 B of archives. 633s After this operation, 0 B of additional disk space will be used. 633s Get:1 /tmp/autopkgtest.smGJoc/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [732 B] 634s Selecting previously unselected package autopkgtest-satdep. 634s (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 ... 78367 files and directories currently installed.) 634s Preparing to unpack .../2-autopkgtest-satdep.deb ... 634s Unpacking autopkgtest-satdep (0) ... 634s Setting up autopkgtest-satdep (0) ... 642s (Reading database ... 78367 files and directories currently installed.) 642s Removing autopkgtest-satdep (0) ... 643s autopkgtest [16:37:02]: test unittest: [----------------------- 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: empty tqh[0] 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 1,0 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 2,1,0 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,2 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1,2 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,0,2 644s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 2,0,1 644s RDUT: PASS: rdunittest.c:345: unittest_sysqueue 644s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sysqueue: PASS 644s RDUT: INFO: rdstring.c:409: ut_strcasestr: BEGIN:  644s RDUT: PASS: rdstring.c:425: ut_strcasestr 644s RDUT: INFO: rdstring.c:606: ut_string_split: BEGIN:  644s RDUT: PASS: rdstring.c:632: ut_string_split 644s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: string: PASS 645s RDUT: INFO: rdmap.c:457: unittest_untyped_map: 500000 map_get iterations took 893.540ms = 1us/get 645s RDUT: INFO: rdmap.c:474: unittest_untyped_map: Total time over 100000 entries took 1159.766ms 645s RDUT: PASS: rdmap.c:477: unittest_untyped_map 645s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 2 person Hedvig Lindahl 645s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 1 person Roy McPhearsome 645s RDUT: PASS: rdmap.c:323: unittest_typed_map 645s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: map: PASS 645s RDUT: PASS: rdbuf.c:1353: do_unittest_write_read 645s RDUT: PASS: rdbuf.c:1518: do_unittest_write_split_seek 645s RDUT: PASS: rdbuf.c:1608: do_unittest_write_read_payload_correctness 645s RDUT: PASS: rdbuf.c:1676: do_unittest_write_iov 645s RDUT: PASS: rdbuf.c:1866: do_unittest_erase 645s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdbuf: PASS 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 645s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdvarint: PASS 645s RDUT: INFO: crc32c.c:411: unittest_rd_crc32c: Calculate CRC32C using software 645s RDUT: INFO: crc32c.c:422: unittest_rd_crc32c: Calculate CRC32C using software 645s RDUT: PASS: crc32c.c:429: unittest_rd_crc32c 645s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: crc32c: PASS 645s RDUT: INFO: rdkafka_msg.c:1999: unittest_msgq_order: FIFO: testing in FIFO mode 645s RDUT: PASS: rdkafka_msg.c:2194: unittest_msg_seq_wrap 645s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: get baseline insert time 645s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 2 messages into destq with 2 messages 645s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 645s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 645s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: get baseline insert time 645s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 2 messages 645s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 645s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 3 messages 645s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 645s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 2 messages in 0us 645s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 645s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: single-message ranges 645s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4 messages into destq with 5 messages 645s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 645s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 645s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: single-message ranges 645s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 5 messages 645s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 645s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 6 messages 645s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 645s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 7 messages 645s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 1.0000us/msg 645s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 8 messages 645s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 645s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.2500us/msg over 4 messages in 1us 645s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 645s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: many messages 655s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4315956 messages into destq with 165288 messages 655s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 44895us, 0.0100us/msg 657s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 657s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: many messages 657s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 100001 messages into destq with 165288 messages 657s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 26us, 0.0003us/msg 658s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 50001 messages into destq with 265289 messages 658s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 12905us, 0.2581us/msg 658s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 20001 messages into destq with 315290 messages 658s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 19299us, 0.9649us/msg 658s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 335291 messages 658s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 21554us, 0.3645us/msg 658s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 394420 messages 658s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 45510us, 0.5242us/msg 660s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4000001 messages into destq with 481243 messages 660s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 58771us, 0.0147us/msg 662s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0366us/msg over 4315956 messages in 158065us 662s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 662s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2508 662s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 145952 messages into destq with 154875 messages 662s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 24830us, 0.0825us/msg 662s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 662s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2508 662s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 154875 messages 662s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 4us, 0.0001us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 214004 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.0000us/msg 663s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 145952 messages in 6us 663s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 663s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2450 (v1.2.1 regression) 663s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 86 messages into destq with 199999 messages 663s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 1us, 0.0000us/msg 663s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 663s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2450 (v1.2.1 regression) 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 199999 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 5 messages into destq with 200001 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 3us, 0.6000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4 messages into destq with 200006 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.5000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200010 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 1.0000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200012 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.6667us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 61 messages into destq with 200015 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.0164us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200076 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 1.0000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200078 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 3us, 1.5000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200080 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 1.0000us/msg 663s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200082 messages 663s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.6667us/msg 663s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.2326us/msg over 86 messages in 20us 663s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 663s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: msg: PASS 663s RDUT: PASS: rdmurmur2.c:166: unittest_murmur2 663s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: murmurhash: PASS 663s RDUT: PASS: rdfnv1a.c:112: unittest_fnv1a 663s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: fnv1a: PASS 663s RDUT: PASS: rdhdrhistogram.c:468: ut_high_sigfig 663s RDUT: PASS: rdhdrhistogram.c:495: ut_quantile 663s RDUT: PASS: rdhdrhistogram.c:514: ut_mean 663s RDUT: PASS: rdhdrhistogram.c:536: ut_stddev 663s RDUT: PASS: rdhdrhistogram.c:555: ut_totalcount 663s RDUT: PASS: rdhdrhistogram.c:573: ut_max 663s RDUT: PASS: rdhdrhistogram.c:590: ut_min 663s RDUT: PASS: rdhdrhistogram.c:609: ut_reset 663s RDUT: PASS: rdhdrhistogram.c:623: ut_nan 663s RDUT: PASS: rdhdrhistogram.c:638: ut_sigfigs 663s RDUT: PASS: rdhdrhistogram.c:654: ut_minmax_trackable 663s RDUT: PASS: rdhdrhistogram.c:664: ut_unitmagnitude_overflow 663s RDUT: PASS: rdhdrhistogram.c:697: ut_subbucketmask_overflow 663s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdhdrhistogram: PASS 663s RDUT: INFO: rdkafka_conf.c:4415: unittest_conf: Safified client.software.name="aba.-va" 663s RDUT: INFO: rdkafka_conf.c:4423: unittest_conf: Safified client.software.version="1.2.3.4.5----a" 663s RDUT: PASS: rdkafka_conf.c:4427: unittest_conf 663s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: conf: PASS 663s RDUT: PASS: rdkafka_broker.c:2175: rd_ut_reconnect_backoff 663s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: broker: PASS 663s RDUT: INFO: rdkafka_request.c:5574: unittest_idempotent_producer: Verifying idempotent producer error handling 663s %5|1711039042.848|CONFWARN|rdkafka#producer-1| [thrd:app]: No `bootstrap.servers` configured: client will not be able to connect to Kafka cluster 663s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 663s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 664s RDUT: PASS: rdkafka_request.c:5769: unittest_idempotent_producer 664s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: request: PASS 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1585: do_unittest_config_no_principal_should_fail 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1614: do_unittest_config_empty_should_fail 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1681: do_unittest_config_empty_value_should_fail 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1719: do_unittest_config_value_with_quote_should_fail 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1644: do_unittest_config_unrecognized_should_fail 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1468: do_unittest_config_defaults 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1511: do_unittest_config_explicit_scope_and_life 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1555: do_unittest_config_all_explicit_values 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1758: do_unittest_config_extensions 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1777: do_unittest_illegal_extension_keys_should_fail 664s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1810: do_unittest_odd_extension_size_should_fail 664s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sasl_oauthbearer: PASS 664s RDUT: PASS: rdkafka_msgset_reader.c:1805: unittest_aborted_txns 664s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: aborted_txns: PASS 664s RDUT: PASS: rdkafka_cgrp.c:5806: unittest_consumer_group_metadata 664s RDUT: PASS: rdkafka_cgrp.c:5877: unittest_set_intersect 664s RDUT: PASS: rdkafka_cgrp.c:5926: unittest_set_subtract 664s RDUT: PASS: rdkafka_cgrp.c:5953: unittest_map_to_list 664s RDUT: PASS: rdkafka_cgrp.c:5983: unittest_list_to_map 664s RDUT: PASS: rdkafka_cgrp.c:6052: unittest_member_metadata_serdes 664s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: cgrp: PASS 664s RDUT: PASS: rdkafka_sasl_scram.c:810: unittest_scram_nonce 664s RDUT: PASS: rdkafka_sasl_scram.c:849: unittest_scram_safe 664s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: scram: PASS 664s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: range assignor 664s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: roundrobin assignor 664s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): range assignor 664s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): roundrobin assignor 664s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): range assignor 664s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): roundrobin assignor 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:645: ut_testOneConsumerNoTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #0 ran for 1.066ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:679: ut_testOneConsumerNonexistentTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #1 ran for 0.069ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #2 ran for 0.096ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #3 ran for 0.091ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #4 ran for 0.096ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #5 ran for 0.117ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #6 ran for 0.101ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #7 ran for 0.126ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #8 ran for 0.126ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1167: ut_testRackAwareAssignmentWithUniformSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #9 ran for 0.640ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1258: ut_testRackAwareAssignmentWithNonEqualSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #10 ran for 0.592ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1318: ut_testRackAwareAssignmentWithUniformPartitions 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #11 ran for 0.617ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1411: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #12 ran for 1.866ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1493: ut_testRackAwareAssignmentWithCoPartitioning0 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #13 ran for 2.396ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1595: ut_testRackAwareAssignmentWithCoPartitioning1 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #14 ran for 0.695ms ] 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 1 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 2 ] 664s RDUT: PASS: rdkafka_range_assignor.c:1652: ut_testCoPartitionedAssignmentWithSameSubscription 664s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #15 ran for 0.274ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #0 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_sticky_assignor.c:2255: ut_testOneConsumerNoTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #0 ran for 1.173ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #1 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 0 ] 664s RDUT: PASS: rdkafka_sticky_assignor.c:2293: ut_testOneConsumerNonexistentTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #1 ran for 0.116ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #2 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #2 ran for 0.194ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #3 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #3 ran for 0.208ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #4 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #4 ran for 0.207ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #5 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #5 ran for 0.194ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #6 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #6 ran for 0.187ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #7 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #7 ran for 0.310ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #8 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 1 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 2 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 664s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 664s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #8 ran for 4.456ms ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #9 ] 664s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 0 ] 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 664s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 1 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 2 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 665s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #9 ran for 2.935ms ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #10 ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 0 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 1 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 2 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 665s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #10 ran for 0.439ms ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #11 ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 0 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 1 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 2 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 665s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 665s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #11 ran for 0.781ms ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #12 ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 0 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 1 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 2 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 665s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #12 ran for 36.859ms ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #13 ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 0 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 1 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 2 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 665s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #13 ran for 3.325ms ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #14 ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 0 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 1 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 2 ] 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 665s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 665s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 665s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #14 ran for 22.746ms ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #15 ] 665s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 0 ] 666s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 666s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 666s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 666s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 1 ] 668s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 669s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 669s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 669s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 2 ] 670s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 670s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 670s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 670s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #15 ran for 5993.616ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #16 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #16 ran for 2.953ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #17 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #17 ran for 0.423ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #18 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #18 ran for 1.042ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #19 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #19 ran for 0.323ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #20 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #20 ran for 0.399ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #21 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #21 ran for 1.340ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #22 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #22 ran for 5.041ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #23 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #23 ran for 2.024ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #24 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 0 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 1 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 2 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #24 ran for 0.054ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #25 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #25 ran for 0.327ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #26 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #26 ran for 0.403ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #27 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #27 ran for 0.370ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #28 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 0 ] 671s %3|1711039049.956|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. 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 1 ] 671s %3|1711039049.957|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. 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 2 ] 671s %3|1711039049.957|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. 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #28 ran for 0.406ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #29 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 0 ] 671s %3|1711039049.958|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. 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 1 ] 671s %3|1711039049.958|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. 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 2 ] 671s %3|1711039049.958|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. 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #29 ran for 0.691ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #30 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #30 ran for 0.454ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #31 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #31 ran for 0.597ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #32 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 0 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 1 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #32 ran for 0.331ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #33 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 0 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 1 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4522: ut_testRackAwareAssignmentWithUniformSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #33 ran for 7.322ms ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #34 ] 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 0 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 1 ] 671s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 2 ] 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 671s RDUT: PASS: rdkafka_sticky_assignor.c:4663: ut_testRackAwareAssignmentWithNonEqualSubscription 671s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #34 ran for 3.902ms ] 671s RDUT: PASS: rdkafka_assignor.c:1776: ut_assignors 671s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: assignors: PASS 671s autopkgtest [16:37:30]: test unittest: -----------------------] 672s unittest PASS 672s autopkgtest [16:37:31]: test unittest: - - - - - - - - - - results - - - - - - - - - - 673s autopkgtest [16:37:32]: @@@@@@@@@@@@@@@@@@@@ summary 673s examples PASS 673s unittest PASS 685s Creating nova instance adt-noble-arm64-librdkafka-20240321-162619-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240320.img (UUID 6a08b5d2-0d74-409b-820d-5d035099b87d)...