0s autopkgtest [15:32:07]: starting date and time: 2024-03-21 15:32:07+0000 0s autopkgtest [15:32:07]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [15:32:07]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.liwmoqm_/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:cyrus-sasl2,src:db5.3,src:heimdal,src:openssl --apt-upgrade librdkafka --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=cyrus-sasl2/2.1.28+dfsg1-5ubuntu1 db5.3/5.3.28+dfsg2-6 heimdal/7.8.git20221117.28daf24+dfsg-5ubuntu2 openssl/3.0.13-0ubuntu2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos01-ppc64el-18.secgroup --name adt-noble-ppc64el-librdkafka-20240321-153207-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 269s autopkgtest [15:36:36]: testbed dpkg architecture: ppc64el 269s autopkgtest [15:36:36]: testbed apt version: 2.7.12 269s autopkgtest [15:36:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup 270s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 271s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3775 kB] 272s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 272s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 272s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 272s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [670 kB] 273s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 273s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 273s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 273s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4084 kB] 273s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 273s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [58.3 kB] 273s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 276s Fetched 9272 kB in 4s (2535 kB/s) 277s Reading package lists... 280s Reading package lists... 280s Building dependency tree... 280s Reading state information... 280s Calculating upgrade... 280s The following packages will be REMOVED: 280s libssl3 280s The following NEW packages will be installed: 280s libssl3t64 280s The following packages have been kept back: 280s libsasl2-2 libsasl2-modules-db 280s The following packages will be upgraded: 280s libsasl2-modules openssl 280s 2 upgraded, 1 newly installed, 1 to remove and 2 not upgraded. 280s Need to get 3235 kB of archives. 280s After this operation, 73.7 kB of additional disk space will be used. 280s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el openssl ppc64el 3.0.13-0ubuntu2 [1026 kB] 281s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libsasl2-modules ppc64el 2.1.28+dfsg1-5ubuntu1 [83.1 kB] 281s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libssl3t64 ppc64el 3.0.13-0ubuntu2 [2125 kB] 282s Fetched 3235 kB in 1s (2541 kB/s) 282s (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 ... 70157 files and directories currently installed.) 282s Preparing to unpack .../openssl_3.0.13-0ubuntu2_ppc64el.deb ... 282s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 282s Preparing to unpack .../libsasl2-modules_2.1.28+dfsg1-5ubuntu1_ppc64el.deb ... 283s Unpacking libsasl2-modules:ppc64el (2.1.28+dfsg1-5ubuntu1) over (2.1.28+dfsg1-4) ... 283s dpkg: libssl3:ppc64el: dependency problems, but removing anyway as you requested: 283s wget depends on libssl3 (>= 3.0.0). 283s tnftp depends on libssl3 (>= 3.0.0). 283s tcpdump depends on libssl3 (>= 3.0.0). 283s systemd-resolved depends on libssl3 (>= 3.0.0). 283s systemd depends on libssl3 (>= 3.0.0). 283s sudo depends on libssl3 (>= 3.0.0). 283s rsync depends on libssl3 (>= 3.0.0). 283s python3-cryptography depends on libssl3 (>= 3.0.0). 283s openssh-server depends on libssl3 (>= 3.0.10). 283s openssh-client depends on libssl3 (>= 3.0.10). 283s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 283s libsystemd-shared:ppc64el depends on libssl3 (>= 3.0.0). 283s libssh-4:ppc64el depends on libssl3 (>= 3.0.0). 283s libsasl2-2:ppc64el depends on libssl3 (>= 3.0.0). 283s libpython3.12-minimal:ppc64el depends on libssl3 (>= 3.0.0). 283s libpython3.11-minimal:ppc64el depends on libssl3 (>= 3.0.0). 283s libnvme1 depends on libssl3 (>= 3.0.0). 283s libkrb5-3:ppc64el depends on libssl3 (>= 3.0.0). 283s libkmod2:ppc64el depends on libssl3 (>= 3.0.0). 283s libfido2-1:ppc64el depends on libssl3 (>= 3.0.0). 283s libcurl4:ppc64el depends on libssl3 (>= 3.0.0). 283s libcryptsetup12:ppc64el depends on libssl3 (>= 3.0.0). 283s kmod depends on libssl3 (>= 3.0.0). 283s dhcpcd-base depends on libssl3 (>= 3.0.0). 283s bind9-libs:ppc64el depends on libssl3 (>= 3.0.0). 283s 283s (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 ... 70157 files and directories currently installed.) 283s Removing libssl3:ppc64el (3.0.10-1ubuntu4) ... 283s Selecting previously unselected package libssl3t64:ppc64el. 283s (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 ... 70146 files and directories currently installed.) 283s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_ppc64el.deb ... 283s Unpacking libssl3t64:ppc64el (3.0.13-0ubuntu2) ... 283s Setting up libssl3t64:ppc64el (3.0.13-0ubuntu2) ... 283s Setting up libsasl2-modules:ppc64el (2.1.28+dfsg1-5ubuntu1) ... 283s Setting up openssl (3.0.13-0ubuntu2) ... 283s Processing triggers for man-db (2.12.0-3) ... 284s Processing triggers for libc-bin (2.39-0ubuntu2) ... 285s Reading package lists... 285s Building dependency tree... 285s Reading state information... 286s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 287s sh: Attempting to set up Debian/Ubuntu apt sources automatically 287s sh: Distribution appears to be Ubuntu 288s Reading package lists... 288s Building dependency tree... 288s Reading state information... 288s eatmydata is already the newest version (131-1). 288s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 288s Reading package lists... 289s Building dependency tree... 289s Reading state information... 289s dbus is already the newest version (1.14.10-4ubuntu1). 289s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 289s Reading package lists... 289s Building dependency tree... 289s Reading state information... 289s rng-tools-debian is already the newest version (2.4). 289s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 289s Reading package lists... 290s Building dependency tree... 290s Reading state information... 290s The following packages will be REMOVED: 290s cloud-init* python3-configobj* python3-debconf* 290s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 290s After this operation, 3252 kB disk space will be freed. 290s (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 ... 70159 files and directories currently installed.) 290s Removing cloud-init (24.1.1-0ubuntu1) ... 291s Removing python3-configobj (5.0.8-3) ... 291s Removing python3-debconf (1.5.86) ... 291s Processing triggers for man-db (2.12.0-3) ... 292s (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 ... 69770 files and directories currently installed.) 292s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 292s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 292s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 292s invoke-rc.d: policy-rc.d denied execution of try-restart. 292s Reading package lists... 293s Building dependency tree... 293s Reading state information... 293s linux-generic is already the newest version (6.8.0-11.11+1). 293s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 293s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 293s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 293s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 297s Reading package lists... 297s Reading package lists... 297s Building dependency tree... 297s Reading state information... 298s Calculating upgrade... 298s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 298s Reading package lists... 298s Building dependency tree... 298s Reading state information... 298s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 298s autopkgtest [15:37:05]: rebooting testbed after setup commands that affected boot 474s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 482s autopkgtest [15:40:09]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 485s autopkgtest [15:40:12]: @@@@@@@@@@@@@@@@@@@@ apt-source librdkafka 490s Get:1 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (dsc) [2258 B] 490s Get:2 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (tar) [4381 kB] 490s Get:3 http://ftpmaster.internal/ubuntu noble/universe librdkafka 2.3.0-1 (diff) [12.3 kB] 491s gpgv: Signature made Fri Oct 27 14:34:28 2023 UTC 491s gpgv: using RSA key A9592C521CB904077D6598009D0B5E5B1EEC8F0E 491s gpgv: Can't check signature: No public key 491s dpkg-source: warning: cannot verify inline signature for ./librdkafka_2.3.0-1.dsc: no acceptable signature found 491s autopkgtest [15:40:18]: testing package librdkafka version 2.3.0-1 491s autopkgtest [15:40:18]: build not needed 492s autopkgtest [15:40:19]: test examples: preparing testbed 497s Reading package lists... 497s Building dependency tree... 497s Reading state information... 497s Starting pkgProblemResolver with broken count: 0 498s Starting 2 pkgProblemResolver with broken count: 0 498s Done 498s The following additional packages will be installed: 498s build-essential cpp cpp-13 cpp-13-powerpc64le-linux-gnu 498s cpp-powerpc64le-linux-gnu g++ g++-13 g++-13-powerpc64le-linux-gnu 498s g++-powerpc64le-linux-gnu gcc gcc-13 gcc-13-powerpc64le-linux-gnu 498s gcc-powerpc64le-linux-gnu libasan8 libatomic1 libc-dev-bin libc6-dev 498s libcc1-0 libcrypt-dev libgcc-13-dev libgomp1 libisl23 libitm1 liblsan0 498s libmpc3 libnsl-dev libpkgconf3 libquadmath0 librdkafka++1 librdkafka-dev 498s librdkafka1 libstdc++-13-dev libtirpc-dev libtsan2 libubsan1 linux-libc-dev 498s pkg-config pkgconf pkgconf-bin rpcsvc-proto 498s Suggested packages: 498s cpp-doc gcc-13-locales cpp-13-doc gcc-13-doc gcc-multilib manpages-dev 498s autoconf automake libtool flex bison gdb gcc-doc gdb-powerpc64le-linux-gnu 498s glibc-doc libstdc++-13-doc 498s Recommended packages: 498s manpages manpages-dev libc-devtools 498s The following NEW packages will be installed: 498s autopkgtest-satdep build-essential cpp cpp-13 cpp-13-powerpc64le-linux-gnu 498s cpp-powerpc64le-linux-gnu g++ g++-13 g++-13-powerpc64le-linux-gnu 498s g++-powerpc64le-linux-gnu gcc gcc-13 gcc-13-powerpc64le-linux-gnu 498s gcc-powerpc64le-linux-gnu libasan8 libatomic1 libc-dev-bin libc6-dev 498s libcc1-0 libcrypt-dev libgcc-13-dev libgomp1 libisl23 libitm1 liblsan0 498s libmpc3 libnsl-dev libpkgconf3 libquadmath0 librdkafka++1 librdkafka-dev 498s librdkafka1 libstdc++-13-dev libtirpc-dev libtsan2 libubsan1 linux-libc-dev 498s pkg-config pkgconf pkgconf-bin rpcsvc-proto 498s 0 upgraded, 41 newly installed, 0 to remove and 0 not upgraded. 498s Need to get 64.1 MB/64.1 MB of archives. 498s After this operation, 250 MB of additional disk space will be used. 498s Get:1 /tmp/autopkgtest.qCB38c/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [728 B] 498s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el libc-dev-bin ppc64el 2.39-0ubuntu2 [21.3 kB] 498s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el linux-libc-dev ppc64el 6.8.0-11.11 [1585 kB] 499s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el libcrypt-dev ppc64el 1:4.4.36-4 [167 kB] 499s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el libtirpc-dev ppc64el 1.3.4+ds-1build1 [262 kB] 499s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el libnsl-dev ppc64el 1.3.0-3 [79.2 kB] 499s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el rpcsvc-proto ppc64el 1.4.2-0ubuntu6 [82.3 kB] 499s Get:8 http://ftpmaster.internal/ubuntu noble/main ppc64el libc6-dev ppc64el 2.39-0ubuntu2 [2102 kB] 499s Get:9 http://ftpmaster.internal/ubuntu noble/main ppc64el libisl23 ppc64el 0.26-3 [864 kB] 499s Get:10 http://ftpmaster.internal/ubuntu noble/main ppc64el libmpc3 ppc64el 1.3.1-1 [61.2 kB] 499s Get:11 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-13-powerpc64le-linux-gnu ppc64el 13.2.0-17ubuntu2 [10.7 MB] 500s Get:12 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-13 ppc64el 13.2.0-17ubuntu2 [1038 B] 500s Get:13 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [5330 B] 500s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp ppc64el 4:13.2.0-7ubuntu1 [22.5 kB] 500s Get:15 http://ftpmaster.internal/ubuntu noble/main ppc64el libcc1-0 ppc64el 14-20240303-1ubuntu1 [48.1 kB] 500s Get:16 http://ftpmaster.internal/ubuntu noble/main ppc64el libgomp1 ppc64el 14-20240303-1ubuntu1 [161 kB] 500s Get:17 http://ftpmaster.internal/ubuntu noble/main ppc64el libitm1 ppc64el 14-20240303-1ubuntu1 [32.4 kB] 500s Get:18 http://ftpmaster.internal/ubuntu noble/main ppc64el libatomic1 ppc64el 14-20240303-1ubuntu1 [10.7 kB] 500s Get:19 http://ftpmaster.internal/ubuntu noble/main ppc64el libasan8 ppc64el 14-20240303-1ubuntu1 [2973 kB] 501s Get:20 http://ftpmaster.internal/ubuntu noble/main ppc64el liblsan0 ppc64el 14-20240303-1ubuntu1 [1325 kB] 501s Get:21 http://ftpmaster.internal/ubuntu noble/main ppc64el libtsan2 ppc64el 14-20240303-1ubuntu1 [2734 kB] 501s Get:22 http://ftpmaster.internal/ubuntu noble/main ppc64el libubsan1 ppc64el 14-20240303-1ubuntu1 [1194 kB] 501s Get:23 http://ftpmaster.internal/ubuntu noble/main ppc64el libquadmath0 ppc64el 14-20240303-1ubuntu1 [158 kB] 501s Get:24 http://ftpmaster.internal/ubuntu noble/main ppc64el libgcc-13-dev ppc64el 13.2.0-17ubuntu2 [1581 kB] 501s Get:25 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13-powerpc64le-linux-gnu ppc64el 13.2.0-17ubuntu2 [20.6 MB] 502s Get:26 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13 ppc64el 13.2.0-17ubuntu2 [477 kB] 502s Get:27 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [1224 B] 502s Get:28 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc ppc64el 4:13.2.0-7ubuntu1 [5022 B] 502s Get:29 http://ftpmaster.internal/ubuntu noble/main ppc64el libstdc++-13-dev ppc64el 13.2.0-17ubuntu2 [2445 kB] 502s Get:30 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-13-powerpc64le-linux-gnu ppc64el 13.2.0-17ubuntu2 [12.2 MB] 502s Get:31 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-13 ppc64el 13.2.0-17ubuntu2 [14.5 kB] 502s Get:32 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [968 B] 502s Get:33 http://ftpmaster.internal/ubuntu noble/main ppc64el g++ ppc64el 4:13.2.0-7ubuntu1 [1086 B] 502s Get:34 http://ftpmaster.internal/ubuntu noble/main ppc64el build-essential ppc64el 12.10ubuntu1 [4936 B] 502s Get:35 http://ftpmaster.internal/ubuntu noble/main ppc64el libpkgconf3 ppc64el 1.8.1-2 [36.9 kB] 502s Get:36 http://ftpmaster.internal/ubuntu noble/universe ppc64el librdkafka1 ppc64el 2.3.0-1 [882 kB] 503s Get:37 http://ftpmaster.internal/ubuntu noble/universe ppc64el librdkafka++1 ppc64el 2.3.0-1 [48.1 kB] 503s Get:38 http://ftpmaster.internal/ubuntu noble/universe ppc64el librdkafka-dev ppc64el 2.3.0-1 [1134 kB] 503s Get:39 http://ftpmaster.internal/ubuntu noble/main ppc64el pkgconf-bin ppc64el 1.8.1-2 [22.0 kB] 503s Get:40 http://ftpmaster.internal/ubuntu noble/main ppc64el pkgconf ppc64el 1.8.1-2 [16.7 kB] 503s Get:41 http://ftpmaster.internal/ubuntu noble/main ppc64el pkg-config ppc64el 1.8.1-2 [7170 B] 503s Fetched 64.1 MB in 5s (13.9 MB/s) 503s Selecting previously unselected package libc-dev-bin. 503s (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 ... 69715 files and directories currently installed.) 503s Preparing to unpack .../00-libc-dev-bin_2.39-0ubuntu2_ppc64el.deb ... 503s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 503s Selecting previously unselected package linux-libc-dev:ppc64el. 503s Preparing to unpack .../01-linux-libc-dev_6.8.0-11.11_ppc64el.deb ... 503s Unpacking linux-libc-dev:ppc64el (6.8.0-11.11) ... 504s Selecting previously unselected package libcrypt-dev:ppc64el. 504s Preparing to unpack .../02-libcrypt-dev_1%3a4.4.36-4_ppc64el.deb ... 504s Unpacking libcrypt-dev:ppc64el (1:4.4.36-4) ... 504s Selecting previously unselected package libtirpc-dev:ppc64el. 504s Preparing to unpack .../03-libtirpc-dev_1.3.4+ds-1build1_ppc64el.deb ... 504s Unpacking libtirpc-dev:ppc64el (1.3.4+ds-1build1) ... 504s Selecting previously unselected package libnsl-dev:ppc64el. 504s Preparing to unpack .../04-libnsl-dev_1.3.0-3_ppc64el.deb ... 504s Unpacking libnsl-dev:ppc64el (1.3.0-3) ... 504s Selecting previously unselected package rpcsvc-proto. 504s Preparing to unpack .../05-rpcsvc-proto_1.4.2-0ubuntu6_ppc64el.deb ... 504s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 504s Selecting previously unselected package libc6-dev:ppc64el. 504s Preparing to unpack .../06-libc6-dev_2.39-0ubuntu2_ppc64el.deb ... 504s Unpacking libc6-dev:ppc64el (2.39-0ubuntu2) ... 505s Selecting previously unselected package libisl23:ppc64el. 505s Preparing to unpack .../07-libisl23_0.26-3_ppc64el.deb ... 505s Unpacking libisl23:ppc64el (0.26-3) ... 505s Selecting previously unselected package libmpc3:ppc64el. 505s Preparing to unpack .../08-libmpc3_1.3.1-1_ppc64el.deb ... 505s Unpacking libmpc3:ppc64el (1.3.1-1) ... 505s Selecting previously unselected package cpp-13-powerpc64le-linux-gnu. 505s Preparing to unpack .../09-cpp-13-powerpc64le-linux-gnu_13.2.0-17ubuntu2_ppc64el.deb ... 505s Unpacking cpp-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 505s Selecting previously unselected package cpp-13. 505s Preparing to unpack .../10-cpp-13_13.2.0-17ubuntu2_ppc64el.deb ... 505s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 505s Selecting previously unselected package cpp-powerpc64le-linux-gnu. 505s Preparing to unpack .../11-cpp-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 505s Unpacking cpp-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 505s Selecting previously unselected package cpp. 505s Preparing to unpack .../12-cpp_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 505s Unpacking cpp (4:13.2.0-7ubuntu1) ... 505s Selecting previously unselected package libcc1-0:ppc64el. 505s Preparing to unpack .../13-libcc1-0_14-20240303-1ubuntu1_ppc64el.deb ... 505s Unpacking libcc1-0:ppc64el (14-20240303-1ubuntu1) ... 505s Selecting previously unselected package libgomp1:ppc64el. 505s Preparing to unpack .../14-libgomp1_14-20240303-1ubuntu1_ppc64el.deb ... 505s Unpacking libgomp1:ppc64el (14-20240303-1ubuntu1) ... 505s Selecting previously unselected package libitm1:ppc64el. 505s Preparing to unpack .../15-libitm1_14-20240303-1ubuntu1_ppc64el.deb ... 505s Unpacking libitm1:ppc64el (14-20240303-1ubuntu1) ... 505s Selecting previously unselected package libatomic1:ppc64el. 505s Preparing to unpack .../16-libatomic1_14-20240303-1ubuntu1_ppc64el.deb ... 505s Unpacking libatomic1:ppc64el (14-20240303-1ubuntu1) ... 505s Selecting previously unselected package libasan8:ppc64el. 505s Preparing to unpack .../17-libasan8_14-20240303-1ubuntu1_ppc64el.deb ... 505s Unpacking libasan8:ppc64el (14-20240303-1ubuntu1) ... 506s Selecting previously unselected package liblsan0:ppc64el. 506s Preparing to unpack .../18-liblsan0_14-20240303-1ubuntu1_ppc64el.deb ... 506s Unpacking liblsan0:ppc64el (14-20240303-1ubuntu1) ... 506s Selecting previously unselected package libtsan2:ppc64el. 506s Preparing to unpack .../19-libtsan2_14-20240303-1ubuntu1_ppc64el.deb ... 506s Unpacking libtsan2:ppc64el (14-20240303-1ubuntu1) ... 506s Selecting previously unselected package libubsan1:ppc64el. 506s Preparing to unpack .../20-libubsan1_14-20240303-1ubuntu1_ppc64el.deb ... 506s Unpacking libubsan1:ppc64el (14-20240303-1ubuntu1) ... 506s Selecting previously unselected package libquadmath0:ppc64el. 506s Preparing to unpack .../21-libquadmath0_14-20240303-1ubuntu1_ppc64el.deb ... 506s Unpacking libquadmath0:ppc64el (14-20240303-1ubuntu1) ... 506s Selecting previously unselected package libgcc-13-dev:ppc64el. 506s Preparing to unpack .../22-libgcc-13-dev_13.2.0-17ubuntu2_ppc64el.deb ... 506s Unpacking libgcc-13-dev:ppc64el (13.2.0-17ubuntu2) ... 506s Selecting previously unselected package gcc-13-powerpc64le-linux-gnu. 506s Preparing to unpack .../23-gcc-13-powerpc64le-linux-gnu_13.2.0-17ubuntu2_ppc64el.deb ... 506s Unpacking gcc-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 507s Selecting previously unselected package gcc-13. 507s Preparing to unpack .../24-gcc-13_13.2.0-17ubuntu2_ppc64el.deb ... 507s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 507s Selecting previously unselected package gcc-powerpc64le-linux-gnu. 507s Preparing to unpack .../25-gcc-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 507s Unpacking gcc-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 507s Selecting previously unselected package gcc. 507s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 507s Unpacking gcc (4:13.2.0-7ubuntu1) ... 507s Selecting previously unselected package libstdc++-13-dev:ppc64el. 507s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-17ubuntu2_ppc64el.deb ... 507s Unpacking libstdc++-13-dev:ppc64el (13.2.0-17ubuntu2) ... 507s Selecting previously unselected package g++-13-powerpc64le-linux-gnu. 507s Preparing to unpack .../28-g++-13-powerpc64le-linux-gnu_13.2.0-17ubuntu2_ppc64el.deb ... 507s Unpacking g++-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 507s Selecting previously unselected package g++-13. 507s Preparing to unpack .../29-g++-13_13.2.0-17ubuntu2_ppc64el.deb ... 507s Unpacking g++-13 (13.2.0-17ubuntu2) ... 507s Selecting previously unselected package g++-powerpc64le-linux-gnu. 507s Preparing to unpack .../30-g++-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 507s Unpacking g++-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 508s Selecting previously unselected package g++. 508s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 508s Unpacking g++ (4:13.2.0-7ubuntu1) ... 508s Selecting previously unselected package build-essential. 508s Preparing to unpack .../32-build-essential_12.10ubuntu1_ppc64el.deb ... 508s Unpacking build-essential (12.10ubuntu1) ... 508s Selecting previously unselected package libpkgconf3:ppc64el. 508s Preparing to unpack .../33-libpkgconf3_1.8.1-2_ppc64el.deb ... 508s Unpacking libpkgconf3:ppc64el (1.8.1-2) ... 508s Selecting previously unselected package librdkafka1:ppc64el. 508s Preparing to unpack .../34-librdkafka1_2.3.0-1_ppc64el.deb ... 508s Unpacking librdkafka1:ppc64el (2.3.0-1) ... 508s Selecting previously unselected package librdkafka++1:ppc64el. 508s Preparing to unpack .../35-librdkafka++1_2.3.0-1_ppc64el.deb ... 508s Unpacking librdkafka++1:ppc64el (2.3.0-1) ... 508s Selecting previously unselected package librdkafka-dev:ppc64el. 508s Preparing to unpack .../36-librdkafka-dev_2.3.0-1_ppc64el.deb ... 508s Unpacking librdkafka-dev:ppc64el (2.3.0-1) ... 508s Selecting previously unselected package pkgconf-bin. 508s Preparing to unpack .../37-pkgconf-bin_1.8.1-2_ppc64el.deb ... 508s Unpacking pkgconf-bin (1.8.1-2) ... 508s Selecting previously unselected package pkgconf:ppc64el. 508s Preparing to unpack .../38-pkgconf_1.8.1-2_ppc64el.deb ... 508s Unpacking pkgconf:ppc64el (1.8.1-2) ... 508s Selecting previously unselected package pkg-config:ppc64el. 508s Preparing to unpack .../39-pkg-config_1.8.1-2_ppc64el.deb ... 508s Unpacking pkg-config:ppc64el (1.8.1-2) ... 508s Selecting previously unselected package autopkgtest-satdep. 508s Preparing to unpack .../40-1-autopkgtest-satdep.deb ... 508s Unpacking autopkgtest-satdep (0) ... 508s Setting up linux-libc-dev:ppc64el (6.8.0-11.11) ... 508s Setting up libgomp1:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up libtirpc-dev:ppc64el (1.3.4+ds-1build1) ... 508s Setting up libpkgconf3:ppc64el (1.8.1-2) ... 508s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 508s Setting up libquadmath0:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up libmpc3:ppc64el (1.3.1-1) ... 508s Setting up libatomic1:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up pkgconf-bin (1.8.1-2) ... 508s Setting up librdkafka1:ppc64el (2.3.0-1) ... 508s Setting up libubsan1:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up libnsl-dev:ppc64el (1.3.0-3) ... 508s Setting up libcrypt-dev:ppc64el (1:4.4.36-4) ... 508s Setting up libasan8:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up libtsan2:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up libisl23:ppc64el (0.26-3) ... 508s Setting up libc-dev-bin (2.39-0ubuntu2) ... 508s Setting up cpp-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 508s Setting up libcc1-0:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up liblsan0:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up libitm1:ppc64el (14-20240303-1ubuntu1) ... 508s Setting up cpp-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 508s Setting up cpp-13 (13.2.0-17ubuntu2) ... 508s Setting up librdkafka++1:ppc64el (2.3.0-1) ... 508s Setting up pkgconf:ppc64el (1.8.1-2) ... 508s Setting up pkg-config:ppc64el (1.8.1-2) ... 508s Setting up libgcc-13-dev:ppc64el (13.2.0-17ubuntu2) ... 508s Setting up cpp (4:13.2.0-7ubuntu1) ... 508s Setting up librdkafka-dev:ppc64el (2.3.0-1) ... 508s Setting up libc6-dev:ppc64el (2.39-0ubuntu2) ... 508s Setting up libstdc++-13-dev:ppc64el (13.2.0-17ubuntu2) ... 508s Setting up gcc-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 508s Setting up gcc-13 (13.2.0-17ubuntu2) ... 508s Setting up g++-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 508s Setting up gcc-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 508s Setting up g++-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 508s Setting up g++-13 (13.2.0-17ubuntu2) ... 508s Setting up gcc (4:13.2.0-7ubuntu1) ... 508s Setting up g++ (4:13.2.0-7ubuntu1) ... 508s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 508s Setting up build-essential (12.10ubuntu1) ... 508s Setting up autopkgtest-satdep (0) ... 508s Processing triggers for man-db (2.12.0-3) ... 510s Processing triggers for libc-bin (2.39-0ubuntu2) ... 513s (Reading database ... 72647 files and directories currently installed.) 513s Removing autopkgtest-satdep (0) ... 514s autopkgtest [15:40:41]: test examples: [----------------------- 514s Info pkg-config (rdkafka): -lrdkafka 514s Info pkg-config (rdkafka++): -lrdkafka++ 514s Build consumer.c 514s Build producer.c 514s Build rdkafka_example.c 515s Build rdkafka_complex_consumer_example.cpp 516s Build rdkafka_example.cpp 518s Run consumer.c.out 518s autopkgtest [15:40:44]: test examples: -----------------------] 518s Run producer.c.out 518s Run rdkafka_complex_consumer_example.cpp.out 518s Run rdkafka_example.c.out 518s Run rdkafka_example.cpp.out 518s All OK 519s autopkgtest [15:40:46]: test examples: - - - - - - - - - - results - - - - - - - - - - 519s examples PASS 519s autopkgtest [15:40:46]: test unittest: preparing testbed 527s Reading package lists... 527s Building dependency tree... 527s Reading state information... 527s Starting pkgProblemResolver with broken count: 0 527s Starting 2 pkgProblemResolver with broken count: 0 527s Done 527s The following NEW packages will be installed: 527s autopkgtest-satdep 527s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 527s Need to get 0 B/732 B of archives. 527s After this operation, 0 B of additional disk space will be used. 527s Get:1 /tmp/autopkgtest.qCB38c/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [732 B] 528s Selecting previously unselected package autopkgtest-satdep. 528s (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 ... 72647 files and directories currently installed.) 528s Preparing to unpack .../2-autopkgtest-satdep.deb ... 528s Unpacking autopkgtest-satdep (0) ... 528s Setting up autopkgtest-satdep (0) ... 530s (Reading database ... 72647 files and directories currently installed.) 530s Removing autopkgtest-satdep (0) ... 531s autopkgtest [15:40:58]: test unittest: [----------------------- 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: empty tqh[0] 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 1,0 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: prepend 2,1,0 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,2 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: append 1,2 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 1,0,2 531s RDUT: INFO: rdunittest.c:158: ut_tq_test: Testing TAILQ: insert 2,0,1 531s RDUT: PASS: rdunittest.c:345: unittest_sysqueue 531s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sysqueue: PASS 531s RDUT: INFO: rdstring.c:409: ut_strcasestr: BEGIN:  531s RDUT: PASS: rdstring.c:425: ut_strcasestr 531s RDUT: INFO: rdstring.c:606: ut_string_split: BEGIN:  531s RDUT: PASS: rdstring.c:632: ut_string_split 531s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: string: PASS 531s RDUT: INFO: rdmap.c:457: unittest_untyped_map: 500000 map_get iterations took 447.832ms = 0us/get 531s RDUT: INFO: rdmap.c:474: unittest_untyped_map: Total time over 100000 entries took 543.022ms 531s RDUT: PASS: rdmap.c:477: unittest_untyped_map 532s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 2 person Hedvig Lindahl 532s RDUT: INFO: rdmap.c:305: unittest_typed_map: enumerated key 1 person Roy McPhearsome 532s RDUT: PASS: rdmap.c:323: unittest_typed_map 532s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: map: PASS 532s RDUT: PASS: rdbuf.c:1353: do_unittest_write_read 532s RDUT: PASS: rdbuf.c:1518: do_unittest_write_split_seek 532s RDUT: PASS: rdbuf.c:1608: do_unittest_write_read_payload_correctness 532s RDUT: PASS: rdbuf.c:1676: do_unittest_write_iov 532s RDUT: PASS: rdbuf.c:1866: do_unittest_erase 532s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdbuf: PASS 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: PASS: rdvarint.c:107: do_test_rd_uvarint_enc_i64 532s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdvarint: PASS 532s RDUT: INFO: crc32c.c:411: unittest_rd_crc32c: Calculate CRC32C using software 532s RDUT: INFO: crc32c.c:422: unittest_rd_crc32c: Calculate CRC32C using software 532s RDUT: PASS: crc32c.c:429: unittest_rd_crc32c 532s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: crc32c: PASS 532s RDUT: INFO: rdkafka_msg.c:1999: unittest_msgq_order: FIFO: testing in FIFO mode 532s RDUT: PASS: rdkafka_msg.c:2194: unittest_msg_seq_wrap 532s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: get baseline insert time 532s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 2 messages into destq with 2 messages 532s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 1us, 0.2500us/msg 532s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 532s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: get baseline insert time 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 2 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 3 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 1.0000us/msg 532s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.5000us/msg over 2 messages in 1us 532s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 532s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: single-message ranges 532s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4 messages into destq with 5 messages 532s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 0us, 0.0000us/msg 532s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 532s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: single-message ranges 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 5 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 6 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 7 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 1 messages into destq with 8 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 532s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 4 messages in 0us 532s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 532s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: many messages 532s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 4315956 messages into destq with 165288 messages 532s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 16469us, 0.0037us/msg 532s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 532s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: many messages 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 100001 messages into destq with 165288 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 48us, 0.0005us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 50001 messages into destq with 265289 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 4436us, 0.0887us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 20001 messages into destq with 315290 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 6219us, 0.3109us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 335291 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 7269us, 0.1229us/msg 532s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 394420 messages 532s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 15799us, 0.1820us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4000001 messages into destq with 481243 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 72794us, 0.0182us/msg 533s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0247us/msg over 4315956 messages in 106565us 533s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 533s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2508 533s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 145952 messages into destq with 154875 messages 533s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 9333us, 0.0310us/msg 533s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 533s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2508 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 59129 messages into destq with 154875 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 2us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 86823 messages into destq with 214004 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0000us/msg over 145952 messages in 2us 533s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 533s RDUT: INFO: rdkafka_msg.c:2239: unittest_msgq_insert_all_sort: Testing msgq insert (all) efficiency: issue #2450 (v1.2.1 regression) 533s RDUT: INFO: rdkafka_msg.c:2272: unittest_msgq_insert_all_sort: Begin insert of 86 messages into destq with 199999 messages 533s RDUT: INFO: rdkafka_msg.c:2280: unittest_msgq_insert_all_sort: Done: took 1us, 0.0000us/msg 533s RDUT: PASS: rdkafka_msg.c:2311: unittest_msgq_insert_all_sort 533s RDUT: INFO: rdkafka_msg.c:2335: unittest_msgq_insert_each_sort: Testing msgq insert (each) efficiency: issue #2450 (v1.2.1 regression) 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 199999 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 5 messages into destq with 200001 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.2000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 4 messages into destq with 200006 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200010 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200012 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 61 messages into destq with 200015 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.0164us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200076 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200078 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 2 messages into destq with 200080 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 1us, 0.5000us/msg 533s RDUT: INFO: rdkafka_msg.c:2372: unittest_msgq_insert_each_sort: Begin insert of 3 messages into destq with 200082 messages 533s RDUT: INFO: rdkafka_msg.c:2383: unittest_msgq_insert_each_sort: Done: took 0us, 0.0000us/msg 534s RDUT: INFO: rdkafka_msg.c:2408: unittest_msgq_insert_each_sort: Total: 0.0465us/msg over 86 messages in 4us 534s RDUT: PASS: rdkafka_msg.c:2424: unittest_msgq_insert_each_sort 534s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: msg: PASS 534s RDUT: PASS: rdmurmur2.c:166: unittest_murmur2 534s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: murmurhash: PASS 534s RDUT: PASS: rdfnv1a.c:112: unittest_fnv1a 534s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: fnv1a: PASS 534s RDUT: PASS: rdhdrhistogram.c:468: ut_high_sigfig 534s RDUT: PASS: rdhdrhistogram.c:495: ut_quantile 534s RDUT: PASS: rdhdrhistogram.c:514: ut_mean 534s RDUT: PASS: rdhdrhistogram.c:536: ut_stddev 534s RDUT: PASS: rdhdrhistogram.c:555: ut_totalcount 534s RDUT: PASS: rdhdrhistogram.c:573: ut_max 534s RDUT: PASS: rdhdrhistogram.c:590: ut_min 534s RDUT: PASS: rdhdrhistogram.c:609: ut_reset 534s RDUT: PASS: rdhdrhistogram.c:623: ut_nan 534s RDUT: PASS: rdhdrhistogram.c:638: ut_sigfigs 534s RDUT: PASS: rdhdrhistogram.c:654: ut_minmax_trackable 534s RDUT: PASS: rdhdrhistogram.c:664: ut_unitmagnitude_overflow 534s RDUT: PASS: rdhdrhistogram.c:697: ut_subbucketmask_overflow 534s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: rdhdrhistogram: PASS 534s RDUT: INFO: rdkafka_conf.c:4415: unittest_conf: Safified client.software.name="aba.-va" 534s RDUT: INFO: rdkafka_conf.c:4423: unittest_conf: Safified client.software.version="1.2.3.4.5----a" 534s RDUT: PASS: rdkafka_conf.c:4427: unittest_conf 534s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: conf: PASS 534s RDUT: PASS: rdkafka_broker.c:2175: rd_ut_reconnect_backoff 534s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: broker: PASS 534s RDUT: INFO: rdkafka_request.c:5574: unittest_idempotent_producer: Verifying idempotent producer error handling 534s %5|1711035661.090|CONFWARN|rdkafka#producer-1| [thrd:app]: No `bootstrap.servers` configured: client will not be able to connect to Kafka cluster 534s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5741: unittest_idempotent_producer: Got DeliveryReport event with 3 message(s) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 534s RDUT: INFO: rdkafka_request.c:5746: unittest_idempotent_producer: DR for message: Success: (persistence=2) 535s RDUT: PASS: rdkafka_request.c:5769: unittest_idempotent_producer 535s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: request: PASS 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1585: do_unittest_config_no_principal_should_fail 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1614: do_unittest_config_empty_should_fail 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1681: do_unittest_config_empty_value_should_fail 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1719: do_unittest_config_value_with_quote_should_fail 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1644: do_unittest_config_unrecognized_should_fail 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1468: do_unittest_config_defaults 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1511: do_unittest_config_explicit_scope_and_life 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1555: do_unittest_config_all_explicit_values 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1758: do_unittest_config_extensions 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1777: do_unittest_illegal_extension_keys_should_fail 535s RDUT: PASS: rdkafka_sasl_oauthbearer.c:1810: do_unittest_odd_extension_size_should_fail 535s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: sasl_oauthbearer: PASS 535s RDUT: PASS: rdkafka_msgset_reader.c:1805: unittest_aborted_txns 535s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: aborted_txns: PASS 535s RDUT: PASS: rdkafka_cgrp.c:5806: unittest_consumer_group_metadata 535s RDUT: PASS: rdkafka_cgrp.c:5877: unittest_set_intersect 535s RDUT: PASS: rdkafka_cgrp.c:5926: unittest_set_subtract 535s RDUT: PASS: rdkafka_cgrp.c:5953: unittest_map_to_list 535s RDUT: PASS: rdkafka_cgrp.c:5983: unittest_list_to_map 535s RDUT: PASS: rdkafka_cgrp.c:6052: unittest_member_metadata_serdes 535s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: cgrp: PASS 535s RDUT: PASS: rdkafka_sasl_scram.c:810: unittest_scram_nonce 535s RDUT: PASS: rdkafka_sasl_scram.c:849: unittest_scram_safe 535s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: scram: PASS 535s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: range assignor 535s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case Symmetrical subscription: roundrobin assignor 535s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): range assignor 535s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case 1*3 partitions (asymmetrical): roundrobin assignor 535s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): range assignor 535s RDUT: INFO: rdkafka_assignor.c:1653: ut_assignors: Test case #2121 (asymmetrical): roundrobin assignor 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:645: ut_testOneConsumerNoTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #0, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:665: ut_testOneConsumerNoTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #0 ran for 0.181ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:679: ut_testOneConsumerNonexistentTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #1, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:699: ut_testOneConsumerNonexistentTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #1 ran for 0.023ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #2, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:732: ut_testOneConsumerOneTopic 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #2 ran for 0.034ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #3, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:762: ut_testOnlyAssignsPartitionsFromSubscribedTopics 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #3 ran for 0.034ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #4, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:791: ut_testOneConsumerMultipleTopics 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #4 ran for 0.036ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #5, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:824: ut_testTwoConsumersOneTopicOnePartition 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #5 ran for 0.030ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #6, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:857: ut_testTwoConsumersOneTopicTwoPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #6 ran for 0.031ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #7, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:894: ut_testMultipleConsumersMixedTopicSubscriptions 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #7 ran for 0.048ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #8, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:927: ut_testTwoConsumersTwoTopicsSixPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #8 ran for 0.046ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1093: ut_testRackAwareAssignmentWithUniformSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #9, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1167: ut_testRackAwareAssignmentWithUniformSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #9 ran for 0.294ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1185: ut_testRackAwareAssignmentWithNonEqualSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #10, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1258: ut_testRackAwareAssignmentWithNonEqualSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #10 ran for 0.291ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1276: ut_testRackAwareAssignmentWithUniformPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #11, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1318: ut_testRackAwareAssignmentWithUniformPartitions 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #11 ran for 0.283ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1336: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #12, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1411: ut_testRackAwareAssignmentWithUniformPartitionsNonEqualSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #12 ran for 0.424ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1432: ut_testRackAwareAssignmentWithCoPartitioning0 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #13, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1493: ut_testRackAwareAssignmentWithCoPartitioning0 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #13 ran for 0.111ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1511: ut_testRackAwareAssignmentWithCoPartitioning1 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #14, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1595: ut_testRackAwareAssignmentWithCoPartitioning1 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #14 ran for 0.166ms ] 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 1 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1612: ut_testCoPartitionedAssignmentWithSameSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1715: rd_kafka_range_assignor_unittest: [ Test #15, RackConfig = 2 ] 535s RDUT: PASS: rdkafka_range_assignor.c:1652: ut_testCoPartitionedAssignmentWithSameSubscription 535s RDUT: INFO: rdkafka_range_assignor.c:1719: rd_kafka_range_assignor_unittest: [ Test #15 ran for 0.115ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #0 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_sticky_assignor.c:2255: ut_testOneConsumerNoTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #0, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNoTopic:2273: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2279: ut_testOneConsumerNoTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #0 ran for 0.509ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #1 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 0 ] 535s RDUT: PASS: rdkafka_sticky_assignor.c:2293: ut_testOneConsumerNonexistentTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #1, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerNonexistentTopic:2310: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2316: ut_testOneConsumerNonexistentTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #1 ran for 0.042ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #2 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #2, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerOneTopic:2349: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2355: ut_testOneConsumerOneTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #2 ran for 0.077ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #3 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #3, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOnlyAssignsPartitionsFromSubscribedTopics:2384: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2390: ut_testOnlyAssignsPartitionsFromSubscribedTopics 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #3 ran for 0.072ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #4 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #4, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOneConsumerMultipleTopics:2419: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2425: ut_testOneConsumerMultipleTopics 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #4 ran for 0.072ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #5 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #5, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicOnePartition:2456: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2463: ut_testTwoConsumersOneTopicOnePartition 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #5 ran for 0.069ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #6 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #6, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersOneTopicTwoPartitions:2495: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2502: ut_testTwoConsumersOneTopicTwoPartitions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #6 ran for 0.072ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #7 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #7, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMultipleConsumersMixedTopicSubscriptions:2539: verifying assignment for 3 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2547: ut_testMultipleConsumersMixedTopicSubscriptions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #7 ran for 0.117ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #8 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #8, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testTwoConsumersTwoTopicsSixPartitions:2581: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2588: ut_testTwoConsumersTwoTopicsSixPartitions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #8 ran for 0.115ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #9 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #9, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2617: verifying assignment for 1 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2633: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveConsumerOneTopic:2646: verifying assignment for 1 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2654: ut_testAddRemoveConsumerOneTopic 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #9 ran for 0.203ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #10 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #10, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPoorRoundRobinAssignmentScenario:2718: verifying assignment for 4 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2727: ut_testPoorRoundRobinAssignmentScenario 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #10 ran for 0.286ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #11 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #11, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2760: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_sticky_assignor.c:2766: ut_testAddRemoveTopicTwoConsumers: Adding topic2 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2783: verifying assignment for 2 member(s): 535s RDUT: INFO: rdkafka_sticky_assignor.c:2791: ut_testAddRemoveTopicTwoConsumers: Removing topic1 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAddRemoveTopicTwoConsumers:2806: verifying assignment for 2 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2814: ut_testAddRemoveTopicTwoConsumers 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #11 ran for 0.284ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #12 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #12, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2869: verifying assignment for 19 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerLeaves:2884: verifying assignment for 18 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2891: ut_testReassignmentAfterOneConsumerLeaves 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #12 ran for 22.228ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #13 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #13, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2931: verifying assignment for 8 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testReassignmentAfterOneConsumerAdded:2943: verifying assignment for 9 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:2950: ut_testReassignmentAfterOneConsumerAdded 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #13 ran for 2.004ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #14 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 1 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #14, RackConfig = 2 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:2999: verifying assignment for 9 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testSameSubscriptions:3012: verifying assignment for 8 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:3020: ut_testSameSubscriptions 535s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #14 ran for 6.537ms ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #15 ] 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 0 ] 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 535s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 535s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 535s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 1 ] 536s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 536s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 536s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 536s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #15, RackConfig = 2 ] 536s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3076: verifying assignment for 200 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testLargeAssignmentWithMultipleConsumersLeaving:3092: verifying assignment for 150 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3099: ut_testLargeAssignmentWithMultipleConsumersLeaving 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #15 ran for 2079.620ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #16 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #16, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3144: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_sticky_assignor.c:3150: ut_testNewSubscription: Adding topic1 to consumer1 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNewSubscription:3159: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3167: ut_testNewSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #16 ran for 0.521ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #17 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #17, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3205: verifying assignment for 4 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments:3226: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3262: ut_testMoveExistingAssignments 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #17 ran for 0.173ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #18 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #18, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testMoveExistingAssignments_j:3312: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3321: ut_testMoveExistingAssignments_j 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #18 ran for 0.147ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #19 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #19, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness:3376: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3382: ut_testStickiness 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #19 ran for 0.121ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #20 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #20, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3425: verifying assignment for 4 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness_j:3450: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3486: ut_testStickiness_j 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #20 ran for 0.162ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #21 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #21, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3523: verifying assignment for 1 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3533: verifying assignment for 2 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3547: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3559: verifying assignment for 2 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testStickiness2:3571: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3580: ut_testStickiness2 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #21 ran for 6.799ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #22 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #22, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAssignmentUpdatedForDeletedTopic:3606: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3616: ut_testAssignmentUpdatedForDeletedTopic 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #22 ran for 2.015ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #23 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #23, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3643: verifying assignment for 1 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted:3657: verifying assignment for 1 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3663: ut_testNoExceptionThrownWhenOnlySubscribedTopicDeleted 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #23 ran for 1.194ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #24 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 0 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 1 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #24, RackConfig = 2 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:3681: ut_testConflictingPreviousAssignments 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #24 ran for 0.522ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #25 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #25, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled:3769: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3778: ut_testAllConsumersReachExpectedQuotaAndAreConsideredFilled 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #25 ran for 0.787ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #26 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #26, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration:3816: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3827: ut_testOwnedPartitionsAreInvalidatedForConsumerWithStaleGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #26 ran for 0.133ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #27 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #27, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration:3864: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3875: ut_testOwnedPartitionsAreInvalidatedForConsumerWithNoGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #27 ran for 0.128ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #28 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 0 ] 537s %3|1711035664.226|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. 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 1 ] 537s %3|1711035664.226|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. 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #28, RackConfig = 2 ] 537s %3|1711035664.226|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. 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration:3916: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3925: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #28 ran for 0.126ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #29 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 0 ] 537s %3|1711035664.226|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. 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 1 ] 537s %3|1711035664.226|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. 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #29, RackConfig = 2 ] 537s %3|1711035664.226|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. 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2:3973: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:3982: ut_testPartitionsTransferringOwnershipIncludeThePartitionClaimedByMultipleConsumersInSameGeneration2 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #29 ran for 0.119ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #30 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #30, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testEnsurePartitionsAssignedToHighestGeneration:4035: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4041: ut_testEnsurePartitionsAssignedToHighestGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #30 ran for 0.307ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #31 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #31, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testNoReassignmentOnCurrentMembers:4093: verifying assignment for 4 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4101: ut_testNoReassignmentOnCurrentMembers 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #31 ran for 0.258ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #32 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 0 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 1 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #32, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration:4140: verifying assignment for 2 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4150: ut_testOwnedPartitionsAreInvalidatedForConsumerWithMultipleGeneration 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #32 ran for 0.133ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #33 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 0 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 1 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:4365: ut_testRackAwareAssignmentWithUniformSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #33, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4522: ut_testRackAwareAssignmentWithUniformSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #33 ran for 2.587ms ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4743: rd_kafka_sticky_assignor_unittest: [ Test #34 ] 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 0 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 1 ] 537s RDUT: PASS: rdkafka_sticky_assignor.c:4544: ut_testRackAwareAssignmentWithNonEqualSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4746: rd_kafka_sticky_assignor_unittest: [ Test #34, RackConfig = 2 ] 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: INFO: rdkafka_assignor.c:1205: verifyValidityAndBalance0: setupRackAwareAssignment0:4221: verifying assignment for 3 member(s): 537s RDUT: PASS: rdkafka_sticky_assignor.c:4663: ut_testRackAwareAssignmentWithNonEqualSubscription 537s RDUT: INFO: rdkafka_sticky_assignor.c:4750: rd_kafka_sticky_assignor_unittest: [ Test #34 ran for 7.560ms ] 537s RDUT: PASS: rdkafka_assignor.c:1776: ut_assignors 537s RDUT: INFO: rdunittest.c:501: rd_unittest: unittest: assignors: PASS 537s autopkgtest [15:41:04]: test unittest: -----------------------] 538s autopkgtest [15:41:05]: test unittest: - - - - - - - - - - results - - - - - - - - - - 538s unittest PASS 538s autopkgtest [15:41:05]: @@@@@@@@@@@@@@@@@@@@ summary 538s examples PASS 538s unittest PASS 571s Creating nova instance adt-noble-ppc64el-librdkafka-20240321-153207-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-ppc64el-server-20240321.img (UUID 024d6af5-fcba-4a84-85f9-01f41e1878d6)...