0s autopkgtest [15:34:47]: starting date and time: 2024-03-18 15:34:47+0000 0s autopkgtest [15:34:47]: git checkout: b506e79c ssh-setup/nova: fix ARCH having two lines of data 0s autopkgtest [15:34:47]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.0p7lvqoj/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:openssl --apt-upgrade librdkafka --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=openssl/3.0.13-0ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos01-arm64-19.secgroup --name adt-noble-arm64-librdkafka-20240318-153447-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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://us.ports.ubuntu.com/ubuntu-ports/ 121s autopkgtest [15:36:48]: testbed dpkg architecture: arm64 122s autopkgtest [15:36:49]: testbed apt version: 2.7.12 122s autopkgtest [15:36:49]: @@@@@@@@@@@@@@@@@@@@ test bed setup 123s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 124s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 124s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3728 kB] 124s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.0 kB] 124s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [485 kB] 124s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [654 kB] 124s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 124s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.6 kB] 124s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 124s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4102 kB] 125s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 125s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [55.5 kB] 125s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 130s Fetched 9246 kB in 3s (3543 kB/s) 130s Reading package lists... 134s Reading package lists... 135s Building dependency tree... 135s Reading state information... 136s Calculating upgrade... 137s The following packages will be REMOVED: 137s libssl3 137s The following NEW packages will be installed: 137s libssl3t64 137s The following packages will be upgraded: 137s openssl 137s 1 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 137s Need to get 2777 kB of archives. 137s After this operation, 139 kB of additional disk space will be used. 137s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu2 [985 kB] 137s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu2 [1793 kB] 138s Fetched 2777 kB in 1s (3236 kB/s) 138s (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.) 139s Preparing to unpack .../openssl_3.0.13-0ubuntu2_arm64.deb ... 139s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 139s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 139s wget depends on libssl3 (>= 3.0.0). 139s u-boot-tools depends on libssl3 (>= 3.0.0). 139s tnftp depends on libssl3 (>= 3.0.0). 139s tcpdump depends on libssl3 (>= 3.0.0). 139s systemd-resolved depends on libssl3 (>= 3.0.0). 139s systemd depends on libssl3 (>= 3.0.0). 139s sudo depends on libssl3 (>= 3.0.0). 139s sbsigntool depends on libssl3 (>= 3.0.0). 139s rsync depends on libssl3 (>= 3.0.0). 139s python3-cryptography depends on libssl3 (>= 3.0.0). 139s openssh-server depends on libssl3 (>= 3.0.10). 139s openssh-client depends on libssl3 (>= 3.0.10). 139s mtd-utils depends on libssl3 (>= 3.0.0). 139s mokutil depends on libssl3 (>= 3.0.0). 139s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 139s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 139s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 139s libsasl2-modules:arm64 depends on libssl3 (>= 3.0.0). 139s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 139s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 139s libpython3.11-minimal:arm64 depends on libssl3 (>= 3.0.0). 139s libnvme1 depends on libssl3 (>= 3.0.0). 139s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 139s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 139s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 139s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 139s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 139s kmod depends on libssl3 (>= 3.0.0). 139s dhcpcd-base depends on libssl3 (>= 3.0.0). 139s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 139s 139s (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.) 139s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 139s Selecting previously unselected package libssl3t64:arm64. 139s (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.) 139s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_arm64.deb ... 139s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu2) ... 139s Setting up libssl3t64:arm64 (3.0.13-0ubuntu2) ... 139s Setting up openssl (3.0.13-0ubuntu2) ... 139s Processing triggers for man-db (2.12.0-3) ... 140s Processing triggers for libc-bin (2.39-0ubuntu2) ... 140s Reading package lists... 141s Building dependency tree... 141s Reading state information... 142s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 142s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 142s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 142s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 146s Reading package lists... 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 147s Calculating upgrade... 148s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 148s Reading package lists... 149s Building dependency tree... 149s Reading state information... 150s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 153s autopkgtest [15:37:20]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 153s autopkgtest [15:37:20]: @@@@@@@@@@@@@@@@@@@@ apt-source librdkafka 158s Get:1 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (dsc) [2258 B] 158s Get:2 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (tar) [4381 kB] 158s Get:3 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (diff) [12.3 kB] 158s gpgv: Signature made Fri Oct 27 14:34:28 2023 UTC 158s gpgv: using RSA key A9592C521CB904077D6598009D0B5E5B1EEC8F0E 158s gpgv: Can't check signature: No public key 158s dpkg-source: warning: cannot verify inline signature for ./librdkafka_2.3.0-1.dsc: no acceptable signature found 158s autopkgtest [15:37:25]: testing package librdkafka version 2.3.0-1 159s autopkgtest [15:37:26]: build not needed 161s autopkgtest [15:37:28]: test examples: preparing testbed 164s Reading package lists... 164s Building dependency tree... 164s Reading state information... 165s Starting pkgProblemResolver with broken count: 0 165s Starting 2 pkgProblemResolver with broken count: 0 165s Done 167s The following additional packages will be installed: 167s build-essential cpp cpp-13 cpp-13-aarch64-linux-gnu cpp-aarch64-linux-gnu 167s g++ g++-13 g++-13-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-13 167s gcc-13-aarch64-linux-gnu gcc-aarch64-linux-gnu libasan8 libatomic1 167s libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libgcc-13-dev libgomp1 167s libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libnsl-dev libpkgconf3 167s librdkafka++1 librdkafka-dev librdkafka1 libstdc++-13-dev libtirpc-dev 167s libtsan2 libubsan1 linux-libc-dev pkg-config pkgconf pkgconf-bin 167s rpcsvc-proto 167s Suggested packages: 167s cpp-doc gcc-13-locales cpp-13-doc gcc-13-doc gcc-multilib manpages-dev 167s autoconf automake libtool flex bison gdb gcc-doc gdb-aarch64-linux-gnu 167s glibc-doc libstdc++-13-doc 167s Recommended packages: 167s manpages manpages-dev libc-devtools 167s The following NEW packages will be installed: 167s autopkgtest-satdep build-essential cpp cpp-13 cpp-13-aarch64-linux-gnu 167s cpp-aarch64-linux-gnu g++ g++-13 g++-13-aarch64-linux-gnu 167s g++-aarch64-linux-gnu gcc gcc-13 gcc-13-aarch64-linux-gnu 167s gcc-aarch64-linux-gnu libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 167s libcrypt-dev libgcc-13-dev libgomp1 libhwasan0 libisl23 libitm1 liblsan0 167s libmpc3 libnsl-dev libpkgconf3 librdkafka++1 librdkafka-dev librdkafka1 167s libstdc++-13-dev libtirpc-dev libtsan2 libubsan1 linux-libc-dev pkg-config 167s pkgconf pkgconf-bin rpcsvc-proto 167s 0 upgraded, 41 newly installed, 0 to remove and 0 not upgraded. 167s Need to get 63.5 MB/63.5 MB of archives. 167s After this operation, 229 MB of additional disk space will be used. 167s Get:1 /tmp/autopkgtest.5mlAJd/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [728 B] 167s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 libc-dev-bin arm64 2.39-0ubuntu2 [19.7 kB] 167s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 linux-libc-dev arm64 6.8.0-11.11 [1569 kB] 168s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libcrypt-dev arm64 1:4.4.36-4 [136 kB] 168s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libtirpc-dev arm64 1.3.4+ds-1build1 [232 kB] 168s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 libnsl-dev arm64 1.3.0-3 [71.9 kB] 168s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 rpcsvc-proto arm64 1.4.2-0ubuntu6 [65.4 kB] 168s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 libc6-dev arm64 2.39-0ubuntu2 [1596 kB] 168s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 libisl23 arm64 0.26-3 [713 kB] 168s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 libmpc3 arm64 1.3.1-1 [55.3 kB] 168s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [10.3 MB] 169s Get:12 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-13 arm64 13.2.0-17ubuntu2 [1028 B] 169s Get:13 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [5316 B] 169s Get:14 http://ftpmaster.internal/ubuntu noble/main arm64 cpp arm64 4:13.2.0-7ubuntu1 [22.4 kB] 169s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 libcc1-0 arm64 14-20240303-1ubuntu1 [44.7 kB] 169s Get:16 http://ftpmaster.internal/ubuntu noble/main arm64 libgomp1 arm64 14-20240303-1ubuntu1 [144 kB] 169s Get:17 http://ftpmaster.internal/ubuntu noble/main arm64 libitm1 arm64 14-20240303-1ubuntu1 [27.7 kB] 169s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 libatomic1 arm64 14-20240303-1ubuntu1 [11.4 kB] 169s Get:19 http://ftpmaster.internal/ubuntu noble/main arm64 libasan8 arm64 14-20240303-1ubuntu1 [2919 kB] 169s Get:20 http://ftpmaster.internal/ubuntu noble/main arm64 liblsan0 arm64 14-20240303-1ubuntu1 [1282 kB] 169s Get:21 http://ftpmaster.internal/ubuntu noble/main arm64 libtsan2 arm64 14-20240303-1ubuntu1 [2687 kB] 170s Get:22 http://ftpmaster.internal/ubuntu noble/main arm64 libubsan1 arm64 14-20240303-1ubuntu1 [1151 kB] 170s Get:23 http://ftpmaster.internal/ubuntu noble/main arm64 libhwasan0 arm64 14-20240303-1ubuntu1 [1597 kB] 170s Get:24 http://ftpmaster.internal/ubuntu noble/main arm64 libgcc-13-dev arm64 13.2.0-17ubuntu2 [2464 kB] 170s Get:25 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [20.1 MB] 171s Get:26 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-13 arm64 13.2.0-17ubuntu2 [467 kB] 171s Get:27 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [1198 B] 171s Get:28 http://ftpmaster.internal/ubuntu noble/main arm64 gcc arm64 4:13.2.0-7ubuntu1 [5018 B] 171s Get:29 http://ftpmaster.internal/ubuntu noble/main arm64 libstdc++-13-dev arm64 13.2.0-17ubuntu2 [2322 kB] 171s Get:30 http://ftpmaster.internal/ubuntu noble/main arm64 g++-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [11.7 MB] 172s Get:31 http://ftpmaster.internal/ubuntu noble/main arm64 g++-13 arm64 13.2.0-17ubuntu2 [14.4 kB] 172s Get:32 http://ftpmaster.internal/ubuntu noble/main arm64 g++-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [962 B] 172s Get:33 http://ftpmaster.internal/ubuntu noble/main arm64 g++ arm64 4:13.2.0-7ubuntu1 [1082 B] 172s Get:34 http://ftpmaster.internal/ubuntu noble/main arm64 build-essential arm64 12.10ubuntu1 [4932 B] 172s Get:35 http://ftpmaster.internal/ubuntu noble/main arm64 libpkgconf3 arm64 1.8.1-2 [31.2 kB] 172s Get:36 http://ftpmaster.internal/ubuntu noble/universe arm64 librdkafka1 arm64 2.3.0-1 [703 kB] 172s Get:37 http://ftpmaster.internal/ubuntu noble/universe arm64 librdkafka++1 arm64 2.3.0-1 [43.4 kB] 172s Get:38 http://ftpmaster.internal/ubuntu noble/universe arm64 librdkafka-dev arm64 2.3.0-1 [965 kB] 172s Get:39 http://ftpmaster.internal/ubuntu noble/main arm64 pkgconf-bin arm64 1.8.1-2 [20.4 kB] 172s Get:40 http://ftpmaster.internal/ubuntu noble/main arm64 pkgconf arm64 1.8.1-2 [16.7 kB] 172s Get:41 http://ftpmaster.internal/ubuntu noble/main arm64 pkg-config arm64 1.8.1-2 [7170 B] 172s Fetched 63.5 MB in 5s (12.7 MB/s) 172s Selecting previously unselected package libc-dev-bin. 173s (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 ... 75852 files and directories currently installed.) 173s Preparing to unpack .../00-libc-dev-bin_2.39-0ubuntu2_arm64.deb ... 173s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 173s Selecting previously unselected package linux-libc-dev:arm64. 173s Preparing to unpack .../01-linux-libc-dev_6.8.0-11.11_arm64.deb ... 173s Unpacking linux-libc-dev:arm64 (6.8.0-11.11) ... 173s Selecting previously unselected package libcrypt-dev:arm64. 173s Preparing to unpack .../02-libcrypt-dev_1%3a4.4.36-4_arm64.deb ... 173s Unpacking libcrypt-dev:arm64 (1:4.4.36-4) ... 173s Selecting previously unselected package libtirpc-dev:arm64. 173s Preparing to unpack .../03-libtirpc-dev_1.3.4+ds-1build1_arm64.deb ... 173s Unpacking libtirpc-dev:arm64 (1.3.4+ds-1build1) ... 173s Selecting previously unselected package libnsl-dev:arm64. 173s Preparing to unpack .../04-libnsl-dev_1.3.0-3_arm64.deb ... 173s Unpacking libnsl-dev:arm64 (1.3.0-3) ... 173s Selecting previously unselected package rpcsvc-proto. 173s Preparing to unpack .../05-rpcsvc-proto_1.4.2-0ubuntu6_arm64.deb ... 173s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 173s Selecting previously unselected package libc6-dev:arm64. 173s Preparing to unpack .../06-libc6-dev_2.39-0ubuntu2_arm64.deb ... 173s Unpacking libc6-dev:arm64 (2.39-0ubuntu2) ... 173s Selecting previously unselected package libisl23:arm64. 173s Preparing to unpack .../07-libisl23_0.26-3_arm64.deb ... 173s Unpacking libisl23:arm64 (0.26-3) ... 174s Selecting previously unselected package libmpc3:arm64. 174s Preparing to unpack .../08-libmpc3_1.3.1-1_arm64.deb ... 174s Unpacking libmpc3:arm64 (1.3.1-1) ... 174s Selecting previously unselected package cpp-13-aarch64-linux-gnu. 174s Preparing to unpack .../09-cpp-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 174s Unpacking cpp-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 174s Selecting previously unselected package cpp-13. 174s Preparing to unpack .../10-cpp-13_13.2.0-17ubuntu2_arm64.deb ... 174s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 174s Selecting previously unselected package cpp-aarch64-linux-gnu. 174s Preparing to unpack .../11-cpp-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 174s Unpacking cpp-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 174s Selecting previously unselected package cpp. 174s Preparing to unpack .../12-cpp_4%3a13.2.0-7ubuntu1_arm64.deb ... 174s Unpacking cpp (4:13.2.0-7ubuntu1) ... 174s Selecting previously unselected package libcc1-0:arm64. 174s Preparing to unpack .../13-libcc1-0_14-20240303-1ubuntu1_arm64.deb ... 174s Unpacking libcc1-0:arm64 (14-20240303-1ubuntu1) ... 174s Selecting previously unselected package libgomp1:arm64. 174s Preparing to unpack .../14-libgomp1_14-20240303-1ubuntu1_arm64.deb ... 174s Unpacking libgomp1:arm64 (14-20240303-1ubuntu1) ... 174s Selecting previously unselected package libitm1:arm64. 174s Preparing to unpack .../15-libitm1_14-20240303-1ubuntu1_arm64.deb ... 174s Unpacking libitm1:arm64 (14-20240303-1ubuntu1) ... 174s Selecting previously unselected package libatomic1:arm64. 174s Preparing to unpack .../16-libatomic1_14-20240303-1ubuntu1_arm64.deb ... 174s Unpacking libatomic1:arm64 (14-20240303-1ubuntu1) ... 174s Selecting previously unselected package libasan8:arm64. 174s Preparing to unpack .../17-libasan8_14-20240303-1ubuntu1_arm64.deb ... 174s Unpacking libasan8:arm64 (14-20240303-1ubuntu1) ... 174s Selecting previously unselected package liblsan0:arm64. 174s Preparing to unpack .../18-liblsan0_14-20240303-1ubuntu1_arm64.deb ... 174s Unpacking liblsan0:arm64 (14-20240303-1ubuntu1) ... 175s Selecting previously unselected package libtsan2:arm64. 175s Preparing to unpack .../19-libtsan2_14-20240303-1ubuntu1_arm64.deb ... 175s Unpacking libtsan2:arm64 (14-20240303-1ubuntu1) ... 175s Selecting previously unselected package libubsan1:arm64. 175s Preparing to unpack .../20-libubsan1_14-20240303-1ubuntu1_arm64.deb ... 175s Unpacking libubsan1:arm64 (14-20240303-1ubuntu1) ... 175s Selecting previously unselected package libhwasan0:arm64. 175s Preparing to unpack .../21-libhwasan0_14-20240303-1ubuntu1_arm64.deb ... 175s Unpacking libhwasan0:arm64 (14-20240303-1ubuntu1) ... 175s Selecting previously unselected package libgcc-13-dev:arm64. 175s Preparing to unpack .../22-libgcc-13-dev_13.2.0-17ubuntu2_arm64.deb ... 175s Unpacking libgcc-13-dev:arm64 (13.2.0-17ubuntu2) ... 175s Selecting previously unselected package gcc-13-aarch64-linux-gnu. 175s Preparing to unpack .../23-gcc-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 175s Unpacking gcc-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 176s Selecting previously unselected package gcc-13. 176s Preparing to unpack .../24-gcc-13_13.2.0-17ubuntu2_arm64.deb ... 176s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 176s Selecting previously unselected package gcc-aarch64-linux-gnu. 176s Preparing to unpack .../25-gcc-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 176s Unpacking gcc-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 176s Selecting previously unselected package gcc. 176s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_arm64.deb ... 176s Unpacking gcc (4:13.2.0-7ubuntu1) ... 176s Selecting previously unselected package libstdc++-13-dev:arm64. 176s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-17ubuntu2_arm64.deb ... 176s Unpacking libstdc++-13-dev:arm64 (13.2.0-17ubuntu2) ... 176s Selecting previously unselected package g++-13-aarch64-linux-gnu. 176s Preparing to unpack .../28-g++-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 176s Unpacking g++-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 177s Selecting previously unselected package g++-13. 177s Preparing to unpack .../29-g++-13_13.2.0-17ubuntu2_arm64.deb ... 177s Unpacking g++-13 (13.2.0-17ubuntu2) ... 177s Selecting previously unselected package g++-aarch64-linux-gnu. 177s Preparing to unpack .../30-g++-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 177s Unpacking g++-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 177s Selecting previously unselected package g++. 177s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_arm64.deb ... 177s Unpacking g++ (4:13.2.0-7ubuntu1) ... 177s Selecting previously unselected package build-essential. 177s Preparing to unpack .../32-build-essential_12.10ubuntu1_arm64.deb ... 177s Unpacking build-essential (12.10ubuntu1) ... 177s Selecting previously unselected package libpkgconf3:arm64. 177s Preparing to unpack .../33-libpkgconf3_1.8.1-2_arm64.deb ... 177s Unpacking libpkgconf3:arm64 (1.8.1-2) ... 177s Selecting previously unselected package librdkafka1:arm64. 177s Preparing to unpack .../34-librdkafka1_2.3.0-1_arm64.deb ... 177s Unpacking librdkafka1:arm64 (2.3.0-1) ... 177s Selecting previously unselected package librdkafka++1:arm64. 177s Preparing to unpack .../35-librdkafka++1_2.3.0-1_arm64.deb ... 177s Unpacking librdkafka++1:arm64 (2.3.0-1) ... 177s Selecting previously unselected package librdkafka-dev:arm64. 177s Preparing to unpack .../36-librdkafka-dev_2.3.0-1_arm64.deb ... 177s Unpacking librdkafka-dev:arm64 (2.3.0-1) ... 177s Selecting previously unselected package pkgconf-bin. 177s Preparing to unpack .../37-pkgconf-bin_1.8.1-2_arm64.deb ... 177s Unpacking pkgconf-bin (1.8.1-2) ... 177s Selecting previously unselected package pkgconf:arm64. 177s Preparing to unpack .../38-pkgconf_1.8.1-2_arm64.deb ... 177s Unpacking pkgconf:arm64 (1.8.1-2) ... 177s Selecting previously unselected package pkg-config:arm64. 177s Preparing to unpack .../39-pkg-config_1.8.1-2_arm64.deb ... 177s Unpacking pkg-config:arm64 (1.8.1-2) ... 178s Selecting previously unselected package autopkgtest-satdep. 178s Preparing to unpack .../40-1-autopkgtest-satdep.deb ... 178s Unpacking autopkgtest-satdep (0) ... 178s Setting up linux-libc-dev:arm64 (6.8.0-11.11) ... 178s Setting up libgomp1:arm64 (14-20240303-1ubuntu1) ... 178s Setting up libtirpc-dev:arm64 (1.3.4+ds-1build1) ... 178s Setting up libpkgconf3:arm64 (1.8.1-2) ... 178s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 178s Setting up libmpc3:arm64 (1.3.1-1) ... 178s Setting up libatomic1:arm64 (14-20240303-1ubuntu1) ... 178s Setting up pkgconf-bin (1.8.1-2) ... 178s Setting up librdkafka1:arm64 (2.3.0-1) ... 178s Setting up libubsan1:arm64 (14-20240303-1ubuntu1) ... 178s Setting up libnsl-dev:arm64 (1.3.0-3) ... 178s Setting up libhwasan0:arm64 (14-20240303-1ubuntu1) ... 178s Setting up libcrypt-dev:arm64 (1:4.4.36-4) ... 178s Setting up libasan8:arm64 (14-20240303-1ubuntu1) ... 178s Setting up libtsan2:arm64 (14-20240303-1ubuntu1) ... 178s Setting up libisl23:arm64 (0.26-3) ... 178s Setting up libc-dev-bin (2.39-0ubuntu2) ... 178s Setting up libcc1-0:arm64 (14-20240303-1ubuntu1) ... 178s Setting up liblsan0:arm64 (14-20240303-1ubuntu1) ... 178s Setting up libitm1:arm64 (14-20240303-1ubuntu1) ... 178s Setting up cpp-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 178s Setting up librdkafka++1:arm64 (2.3.0-1) ... 178s Setting up pkgconf:arm64 (1.8.1-2) ... 178s Setting up cpp-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 178s Setting up pkg-config:arm64 (1.8.1-2) ... 178s Setting up libgcc-13-dev:arm64 (13.2.0-17ubuntu2) ... 178s Setting up librdkafka-dev:arm64 (2.3.0-1) ... 178s Setting up libc6-dev:arm64 (2.39-0ubuntu2) ... 178s Setting up libstdc++-13-dev:arm64 (13.2.0-17ubuntu2) ... 178s Setting up cpp-13 (13.2.0-17ubuntu2) ... 178s Setting up gcc-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 178s Setting up gcc-13 (13.2.0-17ubuntu2) ... 178s Setting up cpp (4:13.2.0-7ubuntu1) ... 178s Setting up g++-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 178s Setting up gcc-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 178s Setting up g++-13 (13.2.0-17ubuntu2) ... 178s Setting up gcc (4:13.2.0-7ubuntu1) ... 178s Setting up g++-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 178s Setting up g++ (4:13.2.0-7ubuntu1) ... 178s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 178s Setting up build-essential (12.10ubuntu1) ... 178s Setting up autopkgtest-satdep (0) ... 178s Processing triggers for man-db (2.12.0-3) ... 178s Processing triggers for libc-bin (2.39-0ubuntu2) ... 184s (Reading database ... 78749 files and directories currently installed.) 184s Removing autopkgtest-satdep (0) ... 184s autopkgtest [15:37:51]: test examples: [----------------------- 185s Info pkg-config (rdkafka): -lrdkafka 185s Info pkg-config (rdkafka++): -lrdkafka++ 185s Build consumer.c 185s Build producer.c 185s Build rdkafka_example.c 185s Build rdkafka_complex_consumer_example.cpp 187s Build rdkafka_example.cpp 189s Run consumer.c.out 189s Run producer.c.out 189s Run rdkafka_complex_consumer_example.cpp.out 189s Run rdkafka_example.c.out 189s Run rdkafka_example.cpp.out 189s All OK 189s autopkgtest [15:37:56]: test examples: -----------------------] 190s autopkgtest [15:37:57]: test examples: - - - - - - - - - - results - - - - - - - - - - 190s examples PASS 190s autopkgtest [15:37:57]: test unittest: preparing testbed 193s Reading package lists... 193s Building dependency tree... 193s Reading state information... 194s Starting pkgProblemResolver with broken count: 0 194s Starting 2 pkgProblemResolver with broken count: 0 194s Done 196s The following NEW packages will be installed: 196s autopkgtest-satdep 196s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 196s Need to get 0 B/736 B of archives. 196s After this operation, 0 B of additional disk space will be used. 196s Get:1 /tmp/autopkgtest.5mlAJd/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [736 B] 197s Selecting previously unselected package autopkgtest-satdep. 197s (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 ... 78749 files and directories currently installed.) 197s Preparing to unpack .../2-autopkgtest-satdep.deb ... 197s Unpacking autopkgtest-satdep (0) ... 197s Setting up autopkgtest-satdep (0) ... 201s (Reading database ... 78749 files and directories currently installed.) 201s Removing autopkgtest-satdep (0) ... 202s autopkgtest [15:38:09]: test unittest: [----------------------- 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: empty tqh[0] 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 1,0 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 2,1,0 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,2 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1,2 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,0,2 202s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 2,0,1 202s RDUT: PASS: rdunittest.c:345: unittest_sysqueue 202s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sysqueue: PASS 202s RDUT: INFO: rdstring.c:409: ut_strcasestr: BEGIN:  202s RDUT: PASS: rdstring.c:425: ut_strcasestr 202s RDUT: INFO: rdstring.c:606: ut_string_split: BEGIN:  202s RDUT: PASS: rdstring.c:632: ut_string_split 202s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: string: PASS 203s RDUT: INFO: rdmap.c:457: unittest_untyped_map: 500000 map_get iterations took 875.550ms = 1us/get 203s RDUT: INFO: rdmap.c:474: unittest_untyped_map: Total time over 100000 entries took 1091.815ms 203s RDUT: PASS: rdmap.c:477: unittest_untyped_map 204s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 2 person Hedvig Lindahl 204s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 1 person Roy McPhearsome 204s RDUT: PASS: rdmap.c:323: unittest_typed_map 204s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: map: PASS 204s RDUT: PASS: rdbuf.c:1353: do_unittest_write_read 204s RDUT: PASS: rdbuf.c:1518: do_unittest_write_split_seek 204s RDUT: PASS: rdbuf.c:1608: do_unittest_write_read_payload_correctness 204s RDUT: PASS: rdbuf.c:1676: do_unittest_write_iov 204s RDUT: PASS: rdbuf.c:1866: do_unittest_erase 204s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdbuf: PASS 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 204s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdvarint: PASS 204s RDUT: INFO: crc32c.c:411: unittest_rd_crc32c: Calculate CRC32C using software 204s RDUT: INFO: crc32c.c:422: unittest_rd_crc32c: Calculate CRC32C using software 204s RDUT: PASS: crc32c.c:429: unittest_rd_crc32c 204s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: crc32c: PASS 204s RDUT: INFO: rdkafka_msg.c:1999: unittest_msgq_order: FIFO: testing in FIFO mode 204s RDUT: PASS: rdkafka_msg.c:2194: unittest_msg_seq_wrap 204s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: get baseline insert time 204s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 2 messages into destq with 2 messages 204s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 204s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 204s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: get baseline insert time 204s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 2 messages 204s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 1.0000us/msg 204s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 3 messages 204s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 204s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.5000us/msg over 2 messages in 1us 204s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 204s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: single-message ranges 204s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4 messages into destq with 5 messages 204s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 204s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 204s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: single-message ranges 204s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 5 messages 204s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 204s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 6 messages 204s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 204s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 7 messages 204s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 204s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 8 messages 204s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 204s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 4 messages in 0us 204s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 204s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: many messages 205s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4315956 messages into destq with 165288 messages 205s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 30975us, 0.0069us/msg 206s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 206s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: many messages 206s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 100001 messages into destq with 165288 messages 206s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 18us, 0.0002us/msg 206s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 50001 messages into destq with 265289 messages 206s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 7694us, 0.1539us/msg 206s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 20001 messages into destq with 315290 messages 206s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 11340us, 0.5670us/msg 206s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 335291 messages 206s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 13317us, 0.2252us/msg 206s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 394420 messages 206s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 28736us, 0.3310us/msg 207s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4000001 messages into destq with 481243 messages 207s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 34376us, 0.0086us/msg 208s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0221us/msg over 4315956 messages in 95481us 208s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 208s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2508 208s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 145952 messages into destq with 154875 messages 208s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 15391us, 0.0512us/msg 208s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 208s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2508 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 154875 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.0000us/msg 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 214004 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.0000us/msg 208s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 145952 messages in 4us 208s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 208s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2450 (v1.2.1 regression) 208s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 86 messages into destq with 199999 messages 208s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 1us, 0.0000us/msg 208s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 208s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2450 (v1.2.1 regression) 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 199999 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 5 messages into destq with 200001 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.4000us/msg 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4 messages into destq with 200006 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.2500us/msg 208s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200010 messages 208s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200012 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.3333us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 61 messages into destq with 200015 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.0164us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200076 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200078 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200080 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 209s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200082 messages 209s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.3333us/msg 209s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.1163us/msg over 86 messages in 10us 209s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: msg: PASS 209s RDUT: PASS: rdmurmur2.c:166: unittest_murmur2 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: murmurhash: PASS 209s RDUT: PASS: rdfnv1a.c:112: unittest_fnv1a 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: fnv1a: PASS 209s RDUT: PASS: rdhdrhistogram.c:468: ut_high_sigfig 209s RDUT: PASS: rdhdrhistogram.c:495: ut_quantile 209s RDUT: PASS: rdhdrhistogram.c:514: ut_mean 209s RDUT: PASS: rdhdrhistogram.c:536: ut_stddev 209s RDUT: PASS: rdhdrhistogram.c:555: ut_totalcount 209s RDUT: PASS: rdhdrhistogram.c:573: ut_max 209s RDUT: PASS: rdhdrhistogram.c:590: ut_min 209s RDUT: PASS: rdhdrhistogram.c:609: ut_reset 209s RDUT: PASS: rdhdrhistogram.c:623: ut_nan 209s RDUT: PASS: rdhdrhistogram.c:638: ut_sigfigs 209s RDUT: PASS: rdhdrhistogram.c:654: ut_minmax_trackable 209s RDUT: PASS: rdhdrhistogram.c:664: ut_unitmagnitude_overflow 209s RDUT: PASS: rdhdrhistogram.c:697: ut_subbucketmask_overflow 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdhdrhistogram: PASS 209s RDUT: INFO: rdkafka_conf.c:4415: unittest_conf: Safified client.software.name="aba.-va" 209s RDUT: INFO: rdkafka_conf.c:4423: unittest_conf: Safified client.software.version="1.2.3.4.5----a" 209s RDUT: PASS: rdkafka_conf.c:4427: unittest_conf 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: conf: PASS 209s RDUT: PASS: rdkafka_broker.c:2175: rd_ut_reconnect_backoff 209s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: broker: PASS 209s RDUT: INFO: rdkafka_request.c:5574: unittest_idempotent_producer: Verifying idempotent producer error handling 209s %5|1710776296.217|CONFWARN|rdkafka#producer-1| [thrd:app]: No `bootstrap.servers` configured: client will not be able to connect to Kafka cluster 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 209s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 210s RDUT: PASS: rdkafka_request.c:5769: unittest_idempotent_producer 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: request: PASS 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1585: do_unittest_config_no_principal_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1614: do_unittest_config_empty_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1681: do_unittest_config_empty_value_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1719: do_unittest_config_value_with_quote_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1644: do_unittest_config_unrecognized_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1468: do_unittest_config_defaults 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1511: do_unittest_config_explicit_scope_and_life 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1555: do_unittest_config_all_explicit_values 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1758: do_unittest_config_extensions 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1777: do_unittest_illegal_extension_keys_should_fail 210s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1810: do_unittest_odd_extension_size_should_fail 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sasl_oauthbearer: PASS 210s RDUT: PASS: rdkafka_msgset_reader.c:1805: unittest_aborted_txns 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: aborted_txns: PASS 210s RDUT: PASS: rdkafka_cgrp.c:5806: unittest_consumer_group_metadata 210s RDUT: PASS: rdkafka_cgrp.c:5877: unittest_set_intersect 210s RDUT: PASS: rdkafka_cgrp.c:5926: unittest_set_subtract 210s RDUT: PASS: rdkafka_cgrp.c:5953: unittest_map_to_list 210s RDUT: PASS: rdkafka_cgrp.c:5983: unittest_list_to_map 210s RDUT: PASS: rdkafka_cgrp.c:6052: unittest_member_metadata_serdes 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: cgrp: PASS 210s RDUT: PASS: rdkafka_sasl_scram.c:810: unittest_scram_nonce 210s RDUT: PASS: rdkafka_sasl_scram.c:849: unittest_scram_safe 210s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: scram: PASS 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: range assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: roundrobin assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): range assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): roundrobin assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): range assignor 210s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): roundrobin assignor 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:645: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #0 ran for 0.096ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:679: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #1 ran for 0.032ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #2 ran for 0.046ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #3 ran for 0.046ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #4 ran for 0.049ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #5 ran for 0.042ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #6 ran for 0.047ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #7 ran for 0.066ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #8 ran for 0.063ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1167: ut_testRackAwareAssignmentWithUniformSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #9 ran for 0.397ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1258: ut_testRackAwareAssignmentWithNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #10 ran for 0.360ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1318: ut_testRackAwareAssignmentWithUniformPartitions 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #11 ran for 0.359ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1411: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #12 ran for 0.352ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1493: ut_testRackAwareAssignmentWithCoPartitioning0 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #13 ran for 0.140ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1595: ut_testRackAwareAssignmentWithCoPartitioning1 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #14 ran for 0.236ms ] 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 1 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 2 ] 210s RDUT: PASS: rdkafka_range_assignor.c:1652: ut_testCoPartitionedAssignmentWithSameSubscription 210s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #15 ran for 0.156ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #0 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_sticky_assignor.c:2255: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #0 ran for 1.280ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #1 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 0 ] 210s RDUT: PASS: rdkafka_sticky_assignor.c:2293: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #1 ran for 0.133ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #2 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #2 ran for 0.097ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #3 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #3 ran for 0.094ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #4 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #4 ran for 0.097ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #5 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #5 ran for 0.087ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #6 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #6 ran for 0.097ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #7 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #7 ran for 0.155ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #8 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #8 ran for 2.662ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #9 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #9 ran for 0.862ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #10 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #10 ran for 0.238ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #11 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 210s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #11 ran for 0.373ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #12 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #12 ran for 19.005ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #13 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #13 ran for 2.205ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #14 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 0 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 1 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 2 ] 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 210s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 210s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 210s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #14 ran for 10.928ms ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #15 ] 210s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 0 ] 211s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 211s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 211s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 211s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 1 ] 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 212s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 212s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 212s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #15 ran for 3243.919ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #16 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #16 ran for 1.131ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #17 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #17 ran for 0.257ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #18 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #18 ran for 0.225ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #19 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #19 ran for 0.203ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #20 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #20 ran for 0.234ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #21 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #21 ran for 0.816ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #22 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #22 ran for 2.443ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #23 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #23 ran for 2.026ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #24 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 0 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 1 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 2 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #24 ran for 0.118ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #25 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #25 ran for 0.162ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #26 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #26 ran for 0.179ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #27 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #27 ran for 0.857ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #28 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 0 ] 213s %3|1710776300.526|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. 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 1 ] 213s %3|1710776300.527|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. 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 2 ] 213s %3|1710776300.527|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. 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #28 ran for 0.991ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #29 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 0 ] 213s %3|1710776300.527|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. 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 1 ] 213s %3|1710776300.527|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. 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 2 ] 213s %3|1710776300.527|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. 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #29 ran for 0.156ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #30 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #30 ran for 0.264ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #31 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #31 ran for 0.336ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #32 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 0 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 1 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #32 ran for 0.176ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #33 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 0 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 1 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4522: ut_testRackAwareAssignmentWithUniformSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #33 ran for 3.047ms ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #34 ] 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 0 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 1 ] 213s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 2 ] 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 213s RDUT: PASS: rdkafka_sticky_assignor.c:4663: ut_testRackAwareAssignmentWithNonEqualSubscription 213s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #34 ran for 2.040ms ] 213s RDUT: PASS: rdkafka_assignor.c:1776: ut_assignors 213s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: assignors: PASS 214s autopkgtest [15:38:21]: test unittest: -----------------------] 214s autopkgtest [15:38:21]: test unittest: - - - - - - - - - - results - - - - - - - - - - 214s unittest PASS 215s autopkgtest [15:38:22]: @@@@@@@@@@@@@@@@@@@@ summary 215s examples PASS 215s unittest PASS 231s Creating nova instance adt-noble-arm64-librdkafka-20240318-153447-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-arm64-server-20240318.img (UUID c38d6760-4740-4c63-8ba1-31527817f4d6)...