Started by user ha:////4LhIf0vdZMLFequGTVnN1cMcIypvsThRPBC5Va3yiaavAAAAnh+LCAAAAAAAAP9b85aBtbiIQTGjNKU4P08vOT+vOD8nVc83PyU1x6OyILUoJzMv2y+/JJUBAhiZGBgqihhk0NSjKDWzXb3RdlLBUSYGJk8GtpzUvPSSDB8G5tKinBIGIZ+sxLJE/ZzEvHT94JKizLx0a6BxUmjGOUNodHsLgAy+EgZh/dLi1CL9nNLkxNxMoEmpxZn5ANPJiavIAAAALuca Milanesio Running as SYSTEM [EnvInject] - Loading node environment variables. Building remotely on ha:////4HCJzRRUY+FcX9QbRoFl/NRe/vu6DuMFHhnKu1sJOWAoAAAAqh+LCAAAAAAAAP9b85aBtbiIQTGjNKU4P08vOT+vOD8nVc83PyU1x6OyILUoJzMv2y+/JJUBAhiZGBgqihhk0NSjKDWzXb3RdlLBUSYGJk8GtpzUvPSSDB8G5tKinBIGIZ+sxLJE/ZzEvHT94JKizLx0a6BxUmjGOUNodHsLgAyFEgY5/eT83ILSktQi/ZT85OzUIl0Dg8TcIoOU3OLEvKwKAHlyPkDTAAAAdocker-00amr0dmsanjx on gcloud66 (bazel-debian bazel-chrome-69 bazel-debian-chrome-69) in workspace /home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3 Selected Git installation does not exist. Using Default Wiping out workspace first. Cloning the remote Git repository Avoid fetching tags Cloning repository https://gerrit.googlesource.com/a/gerrit > /usr/bin/git init /home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3 # timeout=10 Fetching upstream changes from https://gerrit.googlesource.com/a/gerrit > /usr/bin/git --version # timeout=10 using GIT_ASKPASS to set credentials .netrc credentials for gerrit.googlesource.com > /usr/bin/git fetch --no-tags --force --progress -- https://gerrit.googlesource.com/a/gerrit +refs/heads/*:refs/remotes/gerrit/* # timeout=10 > /usr/bin/git config remote.gerrit.url https://gerrit.googlesource.com/a/gerrit # timeout=10 > /usr/bin/git config --add remote.gerrit.fetch +refs/heads/*:refs/remotes/gerrit/* # timeout=10 > /usr/bin/git config remote.gerrit.url https://gerrit.googlesource.com/a/gerrit # timeout=10 Fetching upstream changes from https://gerrit.googlesource.com/a/gerrit using GIT_ASKPASS to set credentials .netrc credentials for gerrit.googlesource.com > /usr/bin/git fetch --no-tags --force --progress -- https://gerrit.googlesource.com/a/gerrit +refs/heads/*:refs/remotes/gerrit/* # timeout=10 > /usr/bin/git config remote.origin.url https://gerrit.googlesource.com/a/plugins/kafka-events # timeout=10 Fetching upstream changes from https://gerrit.googlesource.com/a/plugins/kafka-events using GIT_ASKPASS to set credentials .netrc credentials for gerrit.googlesource.com > /usr/bin/git fetch --no-tags --force --progress -- https://gerrit.googlesource.com/a/plugins/kafka-events +refs/heads/*:refs/remotes/origin/* # timeout=10 > /usr/bin/git rev-parse origin/stable-3.3^{commit} # timeout=10 > /usr/bin/git rev-parse refs/remotes/gerrit/origin/stable-3.3^{commit} # timeout=10 > /usr/bin/git rev-parse refs/remotes/origin/stable-3.3^{commit} # timeout=10 > /usr/bin/git rev-parse refs/remotes/origin/origin/stable-3.3^{commit} # timeout=10 Checking out Revision 40e40ffa35ec98c618051d2745d62aedbc9011ec (origin/stable-3.3) > /usr/bin/git config core.sparsecheckout # timeout=10 > /usr/bin/git checkout -f 40e40ffa35ec98c618051d2745d62aedbc9011ec # timeout=10 Commit message: "Merge branch 'stable-3.2' into stable-3.3" > /usr/bin/git rev-list --no-walk 40e40ffa35ec98c618051d2745d62aedbc9011ec # timeout=10 [plugin-kafka-events-bazel-stable-3.3] $ /bin/bash -e /tmp/jenkins2374542199037750774.sh [plugin-kafka-events-bazel-stable-3.3] $ /bin/bash -e /tmp/jenkins5000296612719630313.sh Java set to: /usr/lib/jvm/java-11-openjdk-amd64/bin/java Previous HEAD position was 40e40ffa35 Merge branch 'stable-3.2' into stable-3.3 Switched to a new branch 'stable-3.3' Branch 'stable-3.3' set up to track remote branch 'stable-3.3' from 'gerrit'. Submodule 'modules/jgit' (https://gerrit.googlesource.com/a/jgit) registered for path 'modules/jgit' Submodule 'plugins/codemirror-editor' (https://gerrit.googlesource.com/a/plugins/codemirror-editor) registered for path 'plugins/codemirror-editor' Submodule 'plugins/commit-message-length-validator' (https://gerrit.googlesource.com/a/plugins/commit-message-length-validator) registered for path 'plugins/commit-message-length-validator' Submodule 'plugins/delete-project' (https://gerrit.googlesource.com/a/plugins/delete-project) registered for path 'plugins/delete-project' Submodule 'plugins/download-commands' (https://gerrit.googlesource.com/a/plugins/download-commands) registered for path 'plugins/download-commands' Submodule 'plugins/gitiles' (https://gerrit.googlesource.com/a/plugins/gitiles) registered for path 'plugins/gitiles' Submodule 'plugins/hooks' (https://gerrit.googlesource.com/a/plugins/hooks) registered for path 'plugins/hooks' Submodule 'plugins/plugin-manager' (https://gerrit.googlesource.com/a/plugins/plugin-manager) registered for path 'plugins/plugin-manager' Submodule 'plugins/replication' (https://gerrit.googlesource.com/a/plugins/replication) registered for path 'plugins/replication' Submodule 'plugins/reviewnotes' (https://gerrit.googlesource.com/a/plugins/reviewnotes) registered for path 'plugins/reviewnotes' Submodule 'plugins/singleusergroup' (https://gerrit.googlesource.com/a/plugins/singleusergroup) registered for path 'plugins/singleusergroup' Submodule 'plugins/webhooks' (https://gerrit.googlesource.com/a/plugins/webhooks) registered for path 'plugins/webhooks' Submodule 'polymer-bridges' (https://gerrit.googlesource.com/a/polymer-bridges) registered for path 'polymer-bridges' Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/modules/jgit'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/codemirror-editor'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/commit-message-length-validator'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/delete-project'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/download-commands'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/gitiles'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/hooks'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/plugin-manager'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/replication'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/reviewnotes'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/singleusergroup'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/plugins/webhooks'... Cloning into '/home/jenkins/workspace/plugin-kafka-events-bazel-stable-3.3/polymer-bridges'... Submodule path 'modules/jgit': checked out '84707715108a65a366ef35f2ae04aabecd0b35f6' Submodule path 'plugins/codemirror-editor': checked out 'c6217963e42322accc3f0bacb6540f8791f67ab0' Submodule path 'plugins/commit-message-length-validator': checked out '556e427fd737744ce8a6a37b89fd427ae59bc8ea' Submodule path 'plugins/delete-project': checked out '7d060dab5f311ec498f9a3b0aa58ce797b8c4d28' Submodule path 'plugins/download-commands': checked out '87e3930cea7c06aea454998abdddf6515a9f103b' Submodule path 'plugins/gitiles': checked out 'd83dc8b0cd4b56a769b4c6ce909ba9c3a79aa2a1' Submodule path 'plugins/hooks': checked out 'ad4f877749928b69ef94b62176c5797f6648887d' Submodule path 'plugins/plugin-manager': checked out '00e57948f4f112c226028bc5c8d8fe60f770038f' Submodule path 'plugins/replication': checked out 'fb4854b57300f1060690b59722eae8c3a18cdab5' Submodule path 'plugins/reviewnotes': checked out 'fb0390a8b49f0d601e11f8a1ac0658c429727f21' Submodule path 'plugins/singleusergroup': checked out '30ca9c1fa624b7389703dd8f8d35cff778e60d83' Submodule path 'plugins/webhooks': checked out '83dda664bf0ad96249dfd99a03b11d2eaf32703d' Submodule path 'polymer-bridges': checked out '855f4781b702de120953a64da5c277ea4908deaa' From https://gerrit.googlesource.com/a/plugins/kafka-events * branch HEAD -> FETCH_HEAD * [new tag] v2.15 -> v2.15 * [new tag] v3.3.0 -> v3.3.0 openjdk full version "11.0.12+7-post-Debian-2deb10u1" 2022/06/10 12:46:32 Downloading https://releases.bazel.build/4.2.2/release/bazel-4.2.2-linux-x86_64... Bazelisk version: v1.7.4 Extracting Bazel installation... Starting local Bazel server and connecting to it... Build label: 4.2.2 Build target: bazel-out/k8-opt/bin/src/main/java/com/google/devtools/build/lib/bazel/BazelServer_deploy.jar Build time: Thu Dec 2 18:15:58 2021 (1638468958) Build timestamp: 1638468958 Build timestamp as int: 1638468958 INFO: Invocation ID: 6ffa5fb3-f713-41a8-bebe-d589a1ec9fd6 Loading: Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Loading: 0 packages loaded Analyzing: 2 targets (1 packages loaded, 0 targets configured) Analyzing: 2 targets (151 packages loaded, 2260 targets configured) INFO: Analyzed 2 targets (282 packages loaded, 5817 targets configured). INFO: Found 2 targets... [0 / 284] [Prepa] BazelWorkspaceStatusAction stable-status.txt [89 / 285] Compiling src/google/protobuf/util/message_differencer.cc; 5s remote-cache, processwrapper-sandbox ... (31 actions, 30 running) [137 / 285] Compiling src/google/protobuf/descriptor.cc; 17s remote-cache, processwrapper-sandbox ... (31 actions, 30 running) [273 / 539] Compiling src/google/protobuf/compiler/cpp/cpp_message.cc; 10s remote-cache, processwrapper-sandbox ... (31 actions, 30 running) [371 / 539] Compiling src/google/protobuf/descriptor.cc [for host]; 9s remote-cache, processwrapper-sandbox ... (31 actions, 30 running) [555 / 725] Building java/com/google/gerrit/acceptance/libfunction.jar (2 source files); 5s remote-cache ... (31 actions, 21 running) [591 / 725] [Prepa] Compiling Java headers java/com/google/gerrit/jgit/libjgit-hjar.jar (1 source file) ... (3 actions, 0 running) [679 / 725] Building java/com/google/gerrit/server/libserver-class.jar (1056 source files) and running annotation processors (AutoAnnotationProcessor, AutoValueProcessor, AutoOneOfProcessor); 15s remote-cache, multiplex-worker ... (6 actions, 4 running) [724 / 725] [Prepa] Building plugins/kafka-events/kafka_events_tests.jar (5 source files) and running annotation processors (AutoAnnotationProcessor, AutoValueProcessor, AutoOneOfProcessor) INFO: Elapsed time: 246.803s, Critical Path: 93.59s INFO: 725 processes: 40 remote cache hit, 36 internal, 565 processwrapper-sandbox, 84 worker. INFO: Build completed successfully, 725 total actions INFO: Build completed successfully, 725 total actions INFO: Invocation ID: fe4f43f8-ee5d-4803-8536-ab2b01d5c0a5 Loading: Loading: 0 packages loaded INFO: Build option --test_env has changed, discarding analysis cache. Analyzing: 2 targets (1 packages loaded, 0 targets configured) INFO: Analyzed 2 targets (1 packages loaded, 5816 targets configured). INFO: Found 2 test targets... [0 / 4] [Prepa] BazelWorkspaceStatusAction stable-status.txt [589 / 723] 1 / 2 tests; [Prepa] BazelWorkspaceStatusAction stable-status.txt [723 / 724] 1 / 2 tests; [Prepa] Testing //plugins/kafka-events:kafka_events_tests [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 1s remote-cache, processwrapper-sandbox [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 11s remote-cache, processwrapper-sandbox [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 31s remote-cache, processwrapper-sandbox [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 41s remote-cache, processwrapper-sandbox [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 65s remote-cache, processwrapper-sandbox [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 87s remote-cache, processwrapper-sandbox [723 / 724] 1 / 2 tests; Testing //plugins/kafka-events:kafka_events_tests; 101s remote-cache, processwrapper-sandbox FAIL: //plugins/kafka-events:kafka_events_tests (see /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/execroot/gerrit/bazel-out/k8-fastbuild/testlogs/plugins/kafka-events/kafka_events_tests/test.log) [723 / 724] 2 / 2 tests, 1 failed; Testing //plugins/kafka-events:kafka_events_tests; 125s remote-cache, processwrapper-sandbox INFO: From Testing //plugins/kafka-events:kafka_events_tests: ==================== Test output for //plugins/kafka-events:kafka_events_tests: JUnit4 Test Runner .log4j:WARN No appenders could be found for logger (org.eclipse.jgit.internal.storage.file.FileSnapshot). log4j:WARN Please initialize the log4j system properly. log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info. WARNING: An illegal reflective access operation has occurred WARNING: Illegal reflective access by com.google.inject.assistedinject.FactoryProvider2 (file:/home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/external/guice-assistedinject/jar/guice-assistedinject-4.2.3.jar) to constructor java.lang.invoke.MethodHandles$Lookup(java.lang.Class,int) WARNING: Please consider reporting this to the maintainers of com.google.inject.assistedinject.FactoryProvider2 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations WARNING: All illegal access operations will be denied in a future release Auto-configured "receive.autogc = false" to disable auto-gc after git-receive-pack. Auto-configured "protocol.version = 2" to activate git wire protocol version 2. Initialized /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781 Jun 10, 2022 12:51:04 PM com.google.gerrit.server.git.SystemReaderInstaller start INFO: Set JGit's SystemReader to read system config from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781/etc/jgit.config Jun 10, 2022 12:51:04 PM com.google.gerrit.server.git.LocalDiskRepositoryManager$Lifecycle start INFO: Defaulting core.streamFileThreshold to 2047m Jun 10, 2022 12:51:06 PM com.google.gerrit.server.cache.h2.H2CacheFactory getCacheDir INFO: Enabling disk cache /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781/cache Jun 10, 2022 12:51:06 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'WorkQueue' queue Jun 10, 2022 12:51:06 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'Index-Interactive' queue Jun 10, 2022 12:51:06 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'Index-Batch' queue Jun 10, 2022 12:51:06 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'ReceiveCommits' queue Jun 10, 2022 12:51:06 PM com.google.gerrit.server.rules.PrologEnvironment$Args INFO: reductionLimit: 100000, compileLimit: 1000000 Jun 10, 2022 12:51:07 PM com.google.gerrit.server.plugins.PluginLoader start INFO: Loading plugins from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781/plugins Reindexed 0 documents in accounts index in 0.0s (0.0/s) Index accounts in version 11 is ready Reindexing groups: 50% (1/2) Reindexing groups: 100% (2/2) Reindexing groups: 100% (2/2) Reindexed 2 documents in groups index in 0.3s (6.7/s) Index groups in version 8 is ready Reindexing changes: Slicing projects: 100% (2/2) Reindexing changes: Slicing projects: 100% (2/2), done Reindexed 0 documents in changes index in 0.0s (0.0/s) Index changes in version 60 is ready Reindexing projects: 50% (1/2) Reindexing projects: 100% (2/2) Reindexing projects: 100% (2/2) Reindexed 2 documents in projects index in 0.8s (2.5/s) Index projects in version 4 is ready Cache Statistics at the end of reindexing Name |Entries | AvgGet |Hit Ratio| | Mem Disk Space| |Mem Disk| --------------------------------+---------------------+---------+---------+ groups_external | | | | projects | 2 | 311.8ms | 33% | groups_byname | | | | groups_bysubgroup | | | | groups_external_persisted | | | | groups_bymember | | | | project_list | 1 | 4.0ms | 50% | change_notes | | | | groups_byuuid | 2 | 69.3ms | 0% | default_preferences | | | | permission_sort | | | | external_ids_map | | | | prolog_rules | | | | groups | | | | D diff_summary | 0.00k| | | D pure_revert | 0.00k| | | D accounts | 0.00k| | | D change_kind | 0.00k| | | D persisted_projects | 0.00k| 223.4ms | 0% | D mergeability | 0.00k| | | D diff_intraline | 0.00k| | | D diff | 0.00k| | | D git_tags | 0.00k| | | Jun 10, 2022 12:51:09 PM com.google.gerrit.server.cache.h2.H2CacheFactory stop INFO: Finishing 1 disk cache updates Jun 10, 2022 12:51:14 PM com.google.gerrit.server.cache.h2.H2CacheFactory getCacheDir INFO: Enabling disk cache /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781/cache Jun 10, 2022 12:51:14 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'WorkQueue' queue Jun 10, 2022 12:51:15 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'Index-Interactive' queue Jun 10, 2022 12:51:15 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'ReceiveCommits' queue Jun 10, 2022 12:51:15 PM com.google.gerrit.server.documentation.QueryDocumentationExecutor readIndexDirectory WARNING: No index available Jun 10, 2022 12:51:15 PM com.google.gerrit.server.rules.PrologEnvironment$Args INFO: reductionLimit: 100000, compileLimit: 1000000 Jun 10, 2022 12:51:15 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "gc". Jun 10, 2022 12:51:15 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "changeCleanup". Jun 10, 2022 12:51:15 PM com.google.gerrit.server.config.GitwebCgiConfig WARNING: gitweb not installed (no /usr/lib/cgi-bin/gitweb.cgi found) Jun 10, 2022 12:51:17 PM com.google.gerrit.server.git.SystemReaderInstaller start INFO: Set JGit's SystemReader to read system config from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781/etc/jgit.config Jun 10, 2022 12:51:17 PM com.google.gerrit.server.git.LocalDiskRepositoryManager$Lifecycle start INFO: Defaulting core.streamFileThreshold to 2047m Jun 10, 2022 12:51:17 PM com.google.gerrit.server.plugins.PluginLoader start INFO: Loading plugins from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit17701561025015030781/plugins Jun 10, 2022 12:51:17 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "accountDeactivation". Jun 10, 2022 12:51:18 PM com.google.gerrit.pgm.Daemon run INFO: Gerrit Code Review [headless] (dev) ready Gerrit Server Started [2022-06-10T12:51:21.147Z] [main] WARN org.testcontainers.utility.TestcontainersConfiguration : Attempted to read Testcontainers configuration file at file:/home/jenkins/.testcontainers.properties but the file was not found. Exception message: FileNotFoundException: /home/jenkins/.testcontainers.properties (No such file or directory) [2022-06-10T12:51:27.018Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Creating container for image: confluentinc/cp-kafka:5.4.3 [2022-06-10T12:51:27.322Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Starting container with ID: 59b034b34bf58c1a195a908dcdfd99662d25c8c007146430b082d3adfb7d763b [2022-06-10T12:51:29.085Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Container confluentinc/cp-kafka:5.4.3 is starting: 59b034b34bf58c1a195a908dcdfd99662d25c8c007146430b082d3adfb7d763b [2022-06-10T12:51:44.620Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Container confluentinc/cp-kafka:5.4.3 started in PT17.882677S [2022-06-10T12:51:44.726Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Connect to PLAINTEXT://10.0.66.1:49027... [2022-06-10T12:51:44.758Z] [main] INFO org.apache.kafka.clients.producer.ProducerConfig : ProducerConfig values: acks = all batch.size = 16384 bootstrap.servers = [PLAINTEXT://10.0.66.1:49027] buffer.memory = 33554432 client.dns.lookup = default client.id = f48b9c12-cc7f-43f9-901c-2e4528b80553 compression.type = none connections.max.idle.ms = 540000 delivery.timeout.ms = 120000 enable.idempotence = false interceptor.classes = [] key.serializer = class org.apache.kafka.common.serialization.StringSerializer linger.ms = 1 max.block.ms = 60000 max.in.flight.requests.per.connection = 5 max.request.size = 1048576 metadata.max.age.ms = 300000 metric.reporters = [] metrics.num.samples = 2 metrics.recording.level = INFO metrics.sample.window.ms = 30000 partitioner.class = class org.apache.kafka.clients.producer.internals.DefaultPartitioner receive.buffer.bytes = 32768 reconnect.backoff.max.ms = 5000 reconnect.backoff.ms = 5000 request.timeout.ms = 30000 retries = 0 retry.backoff.ms = 100 sasl.client.callback.handler.class = null sasl.jaas.config = null sasl.kerberos.kinit.cmd = /usr/bin/kinit sasl.kerberos.min.time.before.relogin = 60000 sasl.kerberos.service.name = null sasl.kerberos.ticket.renew.jitter = 0.05 sasl.kerberos.ticket.renew.window.factor = 0.8 sasl.login.callback.handler.class = null sasl.login.class = null sasl.login.refresh.buffer.seconds = 300 sasl.login.refresh.min.period.seconds = 60 sasl.login.refresh.window.factor = 0.8 sasl.login.refresh.window.jitter = 0.05 sasl.mechanism = GSSAPI security.protocol = PLAINTEXT send.buffer.bytes = 131072 ssl.cipher.suites = null ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1] ssl.endpoint.identification.algorithm = https ssl.key.password = null ssl.keymanager.algorithm = SunX509 ssl.keystore.location = null ssl.keystore.password = null ssl.keystore.type = JKS ssl.protocol = TLS ssl.provider = null ssl.secure.random.implementation = null ssl.trustmanager.algorithm = PKIX ssl.truststore.location = null ssl.truststore.password = null ssl.truststore.type = JKS transaction.timeout.ms = 60000 transactional.id = null value.serializer = class org.apache.kafka.common.serialization.StringSerializer [2022-06-10T12:51:44.997Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'key.deserializer' was supplied but isn't a known config. [2022-06-10T12:51:44.997Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'value.deserializer' was supplied but isn't a known config. [2022-06-10T12:51:44.997Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'group.id' was supplied but isn't a known config. [2022-06-10T12:51:44.998Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'polling.interval.ms' was supplied but isn't a known config. [2022-06-10T12:51:44.998Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'heartbeat.interval.ms' was supplied but isn't a known config. [2022-06-10T12:51:44.998Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'auto.offset.reset' was supplied but isn't a known config. [2022-06-10T12:51:45.017Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka version : 2.1.0 [2022-06-10T12:51:45.017Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka commitId : eec43959745f444f [2022-06-10T12:51:45.022Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Connection established. [2022-06-10T12:51:45.427Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Error while fetching metadata with correlation id 1 : {a_topic=LEADER_NOT_AVAILABLE} [2022-06-10T12:51:45.432Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] INFO org.apache.kafka.clients.Metadata : Cluster ID: vSpIiWELQxezUykvM6av2A [2022-06-10T12:51:45.647Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Error while fetching metadata with correlation id 3 : {a_topic=LEADER_NOT_AVAILABLE} [2022-06-10T12:51:45.763Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Error while fetching metadata with correlation id 4 : {a_topic=LEADER_NOT_AVAILABLE} [2022-06-10T12:51:45.888Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Error while fetching metadata with correlation id 5 : {a_topic=LEADER_NOT_AVAILABLE} [2022-06-10T12:51:46.032Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Error while fetching metadata with correlation id 6 : {a_topic=LEADER_NOT_AVAILABLE} [2022-06-10T12:51:46.182Z] [kafka-producer-network-thread | f48b9c12-cc7f-43f9-901c-2e4528b80553] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Error while fetching metadata with correlation id 7 : {a_topic=LEADER_NOT_AVAILABLE} Jun 10, 2022 12:51:46 PM com.googlesource.gerrit.plugins.kafka.subscribe.KafkaEventSubscriber subscribe INFO: Kafka consumer subscribing to topic alias [a_topic] for event topic [a_topic] [2022-06-10T12:51:46.384Z] [main] INFO org.apache.kafka.clients.consumer.ConsumerConfig : ConsumerConfig values: auto.commit.interval.ms = 5000 auto.offset.reset = earliest bootstrap.servers = [PLAINTEXT://10.0.66.1:49027] check.crcs = true client.dns.lookup = default client.id = 12bdec90-54c1-452c-9d92-f0eff0ce0dab connections.max.idle.ms = 540000 default.api.timeout.ms = 60000 enable.auto.commit = true exclude.internal.topics = true fetch.max.bytes = 52428800 fetch.max.wait.ms = 500 fetch.min.bytes = 1 group.id = tc-06818892-ff87-4d26-aa3e-26c750f8f19c heartbeat.interval.ms = 1000 interceptor.classes = [] internal.leave.group.on.close = true isolation.level = read_uncommitted key.deserializer = class org.apache.kafka.common.serialization.ByteArrayDeserializer max.partition.fetch.bytes = 1048576 max.poll.interval.ms = 300000 max.poll.records = 500 metadata.max.age.ms = 300000 metric.reporters = [] metrics.num.samples = 2 metrics.recording.level = INFO metrics.sample.window.ms = 30000 partition.assignment.strategy = [class org.apache.kafka.clients.consumer.RangeAssignor] receive.buffer.bytes = 65536 reconnect.backoff.max.ms = 5000 reconnect.backoff.ms = 5000 request.timeout.ms = 30000 retry.backoff.ms = 100 sasl.client.callback.handler.class = null sasl.jaas.config = null sasl.kerberos.kinit.cmd = /usr/bin/kinit sasl.kerberos.min.time.before.relogin = 60000 sasl.kerberos.service.name = null sasl.kerberos.ticket.renew.jitter = 0.05 sasl.kerberos.ticket.renew.window.factor = 0.8 sasl.login.callback.handler.class = null sasl.login.class = null sasl.login.refresh.buffer.seconds = 300 sasl.login.refresh.min.period.seconds = 60 sasl.login.refresh.window.factor = 0.8 sasl.login.refresh.window.jitter = 0.05 sasl.mechanism = GSSAPI security.protocol = PLAINTEXT send.buffer.bytes = 131072 session.timeout.ms = 10000 ssl.cipher.suites = null ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1] ssl.endpoint.identification.algorithm = https ssl.key.password = null ssl.keymanager.algorithm = SunX509 ssl.keystore.location = null ssl.keystore.password = null ssl.keystore.type = JKS ssl.protocol = TLS ssl.provider = null ssl.secure.random.implementation = null ssl.trustmanager.algorithm = PKIX ssl.truststore.location = null ssl.truststore.password = null ssl.truststore.type = JKS value.deserializer = class org.apache.kafka.common.serialization.ByteArrayDeserializer [2022-06-10T12:51:46.496Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'acks' was supplied but isn't a known config. [2022-06-10T12:51:46.497Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'batch.size' was supplied but isn't a known config. [2022-06-10T12:51:46.497Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'polling.interval.ms' was supplied but isn't a known config. [2022-06-10T12:51:46.497Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'buffer.memory' was supplied but isn't a known config. [2022-06-10T12:51:46.497Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'key.serializer' was supplied but isn't a known config. [2022-06-10T12:51:46.498Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'retries' was supplied but isn't a known config. [2022-06-10T12:51:46.498Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'value.serializer' was supplied but isn't a known config. [2022-06-10T12:51:46.498Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'linger.ms' was supplied but isn't a known config. [2022-06-10T12:51:46.498Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka version : 2.1.0 [2022-06-10T12:51:46.499Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka commitId : eec43959745f444f [2022-06-10T12:51:46.582Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.Metadata : Cluster ID: vSpIiWELQxezUykvM6av2A [2022-06-10T12:51:46.990Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Discovered group coordinator 10.0.66.1:49027 (id: 2147483646 rack: null) [2022-06-10T12:51:47.001Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.consumer.internals.ConsumerCoordinator : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Revoking previously assigned partitions [] [2022-06-10T12:51:47.001Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] (Re-)joining group [2022-06-10T12:51:47.282Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Successfully joined group with generation 1 [2022-06-10T12:51:47.289Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.consumer.internals.ConsumerCoordinator : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Setting newly assigned partitions [a_topic-0] [2022-06-10T12:51:47.378Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.consumer.internals.Fetcher : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Resetting offset for partition a_topic-0 to offset 0. [2022-06-10T12:51:47.529Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Disconnecting... [2022-06-10T12:51:47.531Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Closing Producer org.apache.kafka.clients.producer.KafkaProducer@4adcc981... [2022-06-10T12:51:47.531Z] [main] INFO org.apache.kafka.clients.producer.KafkaProducer : [Producer clientId=f48b9c12-cc7f-43f9-901c-2e4528b80553] Closing the Kafka producer with timeoutMillis = 9223372036854775807 ms. [2022-06-10T12:51:47.771Z] [kafka-subscriber-1[java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@5c129261[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@56eaf911[Wrapped task = com.google.gerrit.server.logging.LoggingContextAwareRunnable@582d0dbc]]]] INFO org.apache.kafka.clients.FetchSessionHandler : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Error sending fetch request (sessionId=2077750504, epoch=1) to node 1: org.apache.kafka.common.errors.DisconnectException. [2022-06-10T12:51:47.779Z] [kafka-coordinator-heartbeat-thread | tc-06818892-ff87-4d26-aa3e-26c750f8f19c] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=12bdec90-54c1-452c-9d92-f0eff0ce0dab, groupId=tc-06818892-ff87-4d26-aa3e-26c750f8f19c] Group coordinator 10.0.66.1:49027 (id: 2147483646 rack: null) is unavailable or invalid, will attempt rediscovery Jun 10, 2022 12:51:50 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "accountDeactivation". Gerrit Server Shutdown E.Auto-configured "receive.autogc = false" to disable auto-gc after git-receive-pack. Auto-configured "protocol.version = 2" to activate git wire protocol version 2. Initialized /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013 Jun 10, 2022 12:52:18 PM com.google.gerrit.server.git.SystemReaderInstaller start INFO: Set JGit's SystemReader to read system config from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013/etc/jgit.config Jun 10, 2022 12:52:18 PM com.google.gerrit.server.git.LocalDiskRepositoryManager$Lifecycle start INFO: Defaulting core.streamFileThreshold to 2047m Jun 10, 2022 12:52:18 PM com.google.gerrit.server.cache.h2.H2CacheFactory getCacheDir INFO: Enabling disk cache /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013/cache Jun 10, 2022 12:52:18 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'WorkQueue' queue Jun 10, 2022 12:52:18 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'Index-Interactive' queue Jun 10, 2022 12:52:18 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'Index-Batch' queue Jun 10, 2022 12:52:18 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'ReceiveCommits' queue Jun 10, 2022 12:52:18 PM com.google.gerrit.server.rules.PrologEnvironment$Args INFO: reductionLimit: 100000, compileLimit: 1000000 Jun 10, 2022 12:52:18 PM com.google.gerrit.server.plugins.PluginLoader start INFO: Loading plugins from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013/plugins Reindexed 0 documents in accounts index in 0.0s (NaN/s) Index accounts in version 11 is ready Reindexing groups: 50% (1/2) Reindexing groups: 100% (2/2) Reindexing groups: 100% (2/2) Reindexed 2 documents in groups index in 0.1s (19.6/s) Index groups in version 8 is ready Reindexing changes: Slicing projects: 100% (2/2) Reindexing changes: Slicing projects: 100% (2/2), done Reindexed 0 documents in changes index in 0.0s (0.0/s) Index changes in version 60 is ready Reindexing projects: 50% (1/2) Reindexing projects: 100% (2/2) Reindexing projects: 100% (2/2) Reindexed 2 documents in projects index in 0.2s (11.0/s) Index projects in version 4 is ready Cache Statistics at the end of reindexing Name |Entries | AvgGet |Hit Ratio| | Mem Disk Space| |Mem Disk| --------------------------------+---------------------+---------+---------+ groups_external | | | | projects | 2 | 35.1ms | 33% | groups_byname | | | | groups_bysubgroup | | | | groups_external_persisted | | | | groups_bymember | | | | project_list | 1 | 733.3us | 50% | change_notes | | | | groups_byuuid | 2 | 10.4ms | 0% | default_preferences | | | | permission_sort | | | | external_ids_map | | | | prolog_rules | | | | groups | | | | D diff_summary | 0.00k| | | D pure_revert | 0.00k| | | D accounts | 0.00k| | | D change_kind | 0.00k| | | D persisted_projects | 0.00k| 33.7ms | 0% | D mergeability | 0.00k| | | D diff_intraline | 0.00k| | | D diff | 0.00k| | | D git_tags | 0.00k| | | Jun 10, 2022 12:52:19 PM com.google.gerrit.server.cache.h2.H2CacheFactory stop INFO: Finishing 3 disk cache updates Jun 10, 2022 12:52:19 PM com.google.gerrit.server.cache.h2.H2CacheFactory getCacheDir INFO: Enabling disk cache /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013/cache Jun 10, 2022 12:52:20 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'WorkQueue' queue Jun 10, 2022 12:52:20 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'Index-Interactive' queue Jun 10, 2022 12:52:20 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'ReceiveCommits' queue Jun 10, 2022 12:52:20 PM com.google.gerrit.server.documentation.QueryDocumentationExecutor readIndexDirectory WARNING: No index available Jun 10, 2022 12:52:20 PM com.google.gerrit.server.rules.PrologEnvironment$Args INFO: reductionLimit: 100000, compileLimit: 1000000 Jun 10, 2022 12:52:20 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "gc". Jun 10, 2022 12:52:20 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "changeCleanup". Jun 10, 2022 12:52:20 PM com.google.gerrit.server.config.GitwebCgiConfig WARNING: gitweb not installed (no /usr/lib/cgi-bin/gitweb.cgi found) Jun 10, 2022 12:52:20 PM com.google.gerrit.server.git.SystemReaderInstaller start INFO: Set JGit's SystemReader to read system config from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013/etc/jgit.config Jun 10, 2022 12:52:20 PM com.google.gerrit.server.git.LocalDiskRepositoryManager$Lifecycle start INFO: Defaulting core.streamFileThreshold to 2047m Jun 10, 2022 12:52:20 PM com.google.gerrit.server.plugins.PluginLoader start INFO: Loading plugins from /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/sandbox/processwrapper-sandbox/692/execroot/gerrit/_tmp/34f7356409605351a20d563daec3da43/junit12036472102442186698/junit7969897051226051013/plugins Jun 10, 2022 12:52:20 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "accountDeactivation". Jun 10, 2022 12:52:20 PM com.google.gerrit.pgm.Daemon run INFO: Gerrit Code Review [headless] (dev) ready Gerrit Server Started [2022-06-10T12:52:21.438Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Creating container for image: confluentinc/cp-kafka:5.4.3 [2022-06-10T12:52:22.572Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Starting container with ID: 0d0830aad9f0551bb2b73c374507f21bfc42b703dd2ea85daeda39775b9b931f [2022-06-10T12:52:23.256Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Container confluentinc/cp-kafka:5.4.3 is starting: 0d0830aad9f0551bb2b73c374507f21bfc42b703dd2ea85daeda39775b9b931f [2022-06-10T12:52:31.622Z] [main] INFO docker[confluentinc/cp-kafka:5.4.3] : Container confluentinc/cp-kafka:5.4.3 started in PT10.185418S [2022-06-10T12:52:31.689Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Connect to PLAINTEXT://10.0.66.1:49030... [2022-06-10T12:52:31.690Z] [main] INFO org.apache.kafka.clients.producer.ProducerConfig : ProducerConfig values: acks = all batch.size = 16384 bootstrap.servers = [PLAINTEXT://10.0.66.1:49030] buffer.memory = 33554432 client.dns.lookup = default client.id = ee32ab67-97fa-4585-afca-e694ce7cd5bc compression.type = none connections.max.idle.ms = 540000 delivery.timeout.ms = 120000 enable.idempotence = false interceptor.classes = [] key.serializer = class org.apache.kafka.common.serialization.StringSerializer linger.ms = 1 max.block.ms = 60000 max.in.flight.requests.per.connection = 5 max.request.size = 1048576 metadata.max.age.ms = 300000 metric.reporters = [] metrics.num.samples = 2 metrics.recording.level = INFO metrics.sample.window.ms = 30000 partitioner.class = class org.apache.kafka.clients.producer.internals.DefaultPartitioner receive.buffer.bytes = 32768 reconnect.backoff.max.ms = 5000 reconnect.backoff.ms = 5000 request.timeout.ms = 30000 retries = 0 retry.backoff.ms = 100 sasl.client.callback.handler.class = null sasl.jaas.config = null sasl.kerberos.kinit.cmd = /usr/bin/kinit sasl.kerberos.min.time.before.relogin = 60000 sasl.kerberos.service.name = null sasl.kerberos.ticket.renew.jitter = 0.05 sasl.kerberos.ticket.renew.window.factor = 0.8 sasl.login.callback.handler.class = null sasl.login.class = null sasl.login.refresh.buffer.seconds = 300 sasl.login.refresh.min.period.seconds = 60 sasl.login.refresh.window.factor = 0.8 sasl.login.refresh.window.jitter = 0.05 sasl.mechanism = GSSAPI security.protocol = PLAINTEXT send.buffer.bytes = 131072 ssl.cipher.suites = null ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1] ssl.endpoint.identification.algorithm = https ssl.key.password = null ssl.keymanager.algorithm = SunX509 ssl.keystore.location = null ssl.keystore.password = null ssl.keystore.type = JKS ssl.protocol = TLS ssl.provider = null ssl.secure.random.implementation = null ssl.trustmanager.algorithm = PKIX ssl.truststore.location = null ssl.truststore.password = null ssl.truststore.type = JKS transaction.timeout.ms = 60000 transactional.id = null value.serializer = class org.apache.kafka.common.serialization.StringSerializer [2022-06-10T12:52:31.710Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'key.deserializer' was supplied but isn't a known config. [2022-06-10T12:52:31.710Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'value.deserializer' was supplied but isn't a known config. [2022-06-10T12:52:31.710Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'group.id' was supplied but isn't a known config. [2022-06-10T12:52:31.710Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'heartbeat.interval.ms' was supplied but isn't a known config. [2022-06-10T12:52:31.710Z] [main] WARN org.apache.kafka.clients.producer.ProducerConfig : The configuration 'auto.offset.reset' was supplied but isn't a known config. [2022-06-10T12:52:31.710Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka version : 2.1.0 [2022-06-10T12:52:31.710Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka commitId : eec43959745f444f [2022-06-10T12:52:31.711Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Connection established. [2022-06-10T12:52:32.287Z] [kafka-producer-network-thread | ee32ab67-97fa-4585-afca-e694ce7cd5bc] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc] Error while fetching metadata with correlation id 1 : {gerrit=LEADER_NOT_AVAILABLE} [2022-06-10T12:52:32.293Z] [kafka-producer-network-thread | ee32ab67-97fa-4585-afca-e694ce7cd5bc] INFO org.apache.kafka.clients.Metadata : Cluster ID: WuShulRLQhaEfPIm-Bdefg [2022-06-10T12:52:32.442Z] [kafka-producer-network-thread | ee32ab67-97fa-4585-afca-e694ce7cd5bc] WARN org.apache.kafka.clients.NetworkClient : [Producer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc] Error while fetching metadata with correlation id 3 : {gerrit=LEADER_NOT_AVAILABLE} [2022-06-10T12:52:37.255Z] [main] INFO org.apache.kafka.clients.consumer.ConsumerConfig : ConsumerConfig values: auto.commit.interval.ms = 5000 auto.offset.reset = earliest bootstrap.servers = [PLAINTEXT://10.0.66.1:49030] check.crcs = true client.dns.lookup = default client.id = ee32ab67-97fa-4585-afca-e694ce7cd5bc connections.max.idle.ms = 540000 default.api.timeout.ms = 60000 enable.auto.commit = true exclude.internal.topics = true fetch.max.bytes = 52428800 fetch.max.wait.ms = 500 fetch.min.bytes = 1 group.id = tc-2e19afb4-0d42-48d2-bb15-ece776ad574d heartbeat.interval.ms = 1000 interceptor.classes = [] internal.leave.group.on.close = true isolation.level = read_uncommitted key.deserializer = class org.apache.kafka.common.serialization.StringDeserializer max.partition.fetch.bytes = 1048576 max.poll.interval.ms = 300000 max.poll.records = 500 metadata.max.age.ms = 300000 metric.reporters = [] metrics.num.samples = 2 metrics.recording.level = INFO metrics.sample.window.ms = 30000 partition.assignment.strategy = [class org.apache.kafka.clients.consumer.RangeAssignor] receive.buffer.bytes = 65536 reconnect.backoff.max.ms = 5000 reconnect.backoff.ms = 5000 request.timeout.ms = 30000 retry.backoff.ms = 100 sasl.client.callback.handler.class = null sasl.jaas.config = null sasl.kerberos.kinit.cmd = /usr/bin/kinit sasl.kerberos.min.time.before.relogin = 60000 sasl.kerberos.service.name = null sasl.kerberos.ticket.renew.jitter = 0.05 sasl.kerberos.ticket.renew.window.factor = 0.8 sasl.login.callback.handler.class = null sasl.login.class = null sasl.login.refresh.buffer.seconds = 300 sasl.login.refresh.min.period.seconds = 60 sasl.login.refresh.window.factor = 0.8 sasl.login.refresh.window.jitter = 0.05 sasl.mechanism = GSSAPI security.protocol = PLAINTEXT send.buffer.bytes = 131072 session.timeout.ms = 10000 ssl.cipher.suites = null ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1] ssl.endpoint.identification.algorithm = https ssl.key.password = null ssl.keymanager.algorithm = SunX509 ssl.keystore.location = null ssl.keystore.password = null ssl.keystore.type = JKS ssl.protocol = TLS ssl.provider = null ssl.secure.random.implementation = null ssl.trustmanager.algorithm = PKIX ssl.truststore.location = null ssl.truststore.password = null ssl.truststore.type = JKS value.deserializer = class org.apache.kafka.common.serialization.StringDeserializer [2022-06-10T12:52:37.262Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'acks' was supplied but isn't a known config. [2022-06-10T12:52:37.262Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'batch.size' was supplied but isn't a known config. [2022-06-10T12:52:37.262Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'buffer.memory' was supplied but isn't a known config. [2022-06-10T12:52:37.262Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'key.serializer' was supplied but isn't a known config. [2022-06-10T12:52:37.263Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'retries' was supplied but isn't a known config. [2022-06-10T12:52:37.263Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'value.serializer' was supplied but isn't a known config. [2022-06-10T12:52:37.263Z] [main] WARN org.apache.kafka.clients.consumer.ConsumerConfig : The configuration 'linger.ms' was supplied but isn't a known config. [2022-06-10T12:52:37.263Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka version : 2.1.0 [2022-06-10T12:52:37.263Z] [main] INFO org.apache.kafka.common.utils.AppInfoParser : Kafka commitId : eec43959745f444f [2022-06-10T12:52:37.298Z] [main] INFO org.apache.kafka.clients.Metadata : Cluster ID: WuShulRLQhaEfPIm-Bdefg [2022-06-10T12:52:37.549Z] [main] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] Discovered group coordinator 10.0.66.1:49030 (id: 2147483646 rack: null) [2022-06-10T12:52:37.550Z] [main] INFO org.apache.kafka.clients.consumer.internals.ConsumerCoordinator : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] Revoking previously assigned partitions [] [2022-06-10T12:52:37.552Z] [main] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] (Re-)joining group [2022-06-10T12:52:37.697Z] [main] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] Successfully joined group with generation 1 [2022-06-10T12:52:37.697Z] [main] INFO org.apache.kafka.clients.consumer.internals.ConsumerCoordinator : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] Setting newly assigned partitions [gerrit-0] [2022-06-10T12:52:37.732Z] [main] INFO org.apache.kafka.clients.consumer.internals.Fetcher : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] Resetting offset for partition gerrit-0 to offset 0. [2022-06-10T12:52:37.883Z] [main] INFO org.apache.kafka.clients.consumer.internals.AbstractCoordinator : [Consumer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc, groupId=tc-2e19afb4-0d42-48d2-bb15-ece776ad574d] Sending LeaveGroup request to coordinator 10.0.66.1:49030 (id: 2147483646 rack: null) [2022-06-10T12:52:37.941Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Disconnecting... [2022-06-10T12:52:37.941Z] [main] INFO com.googlesource.gerrit.plugins.kafka.session.KafkaSession : Closing Producer org.apache.kafka.clients.producer.KafkaProducer@1a67bde5... [2022-06-10T12:52:37.941Z] [main] INFO org.apache.kafka.clients.producer.KafkaProducer : [Producer clientId=ee32ab67-97fa-4585-afca-e694ce7cd5bc] Closing the Kafka producer with timeoutMillis = 9223372036854775807 ms. Jun 10, 2022 12:52:42 PM com.google.gerrit.server.config.ScheduleConfig isInvalidOrMissing INFO: No schedule configuration for "accountDeactivation". Gerrit Server Shutdown .Jun 10, 2022 12:52:52 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'WorkQueue' queue Jun 10, 2022 12:52:53 PM com.googlesource.gerrit.plugins.kafka.subscribe.KafkaEventSubscriber subscribe INFO: Kafka consumer subscribing to topic alias [test_topic_sync] for event topic [test_topic_sync] .Jun 10, 2022 12:52:55 PM com.google.gerrit.server.git.WorkQueue createQueue INFO: Adding metrics for 'WorkQueue' queue Jun 10, 2022 12:52:56 PM com.googlesource.gerrit.plugins.kafka.subscribe.KafkaEventSubscriber subscribe INFO: Kafka consumer subscribing to topic alias [test_topic_async] for event topic [test_topic_async] ......... Time: 122.54 There was 1 failure: 1) shouldReplayAllEvents(com.googlesource.gerrit.plugins.kafka.EventConsumerIT) java.lang.InterruptedException at com.googlesource.gerrit.plugins.kafka.EventConsumerIT.waitUntil(EventConsumerIT.java:185) at com.googlesource.gerrit.plugins.kafka.EventConsumerIT.shouldReplayAllEvents(EventConsumerIT.java:158) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:566) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) at com.google.gerrit.acceptance.AbstractDaemonTest$1$1.evaluate(AbstractDaemonTest.java:236) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.run(ParentRunner.java:363) at org.junit.runners.Suite.runChild(Suite.java:128) at org.junit.runners.Suite.runChild(Suite.java:27) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.run(ParentRunner.java:363) at org.junit.runners.Suite.runChild(Suite.java:128) at org.junit.runners.Suite.runChild(Suite.java:27) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) at org.junit.runners.ParentRunner.run(ParentRunner.java:363) at com.google.testing.junit.runner.internal.junit4.CancellableRequestFactory$CancellableRunner.run(CancellableRequestFactory.java:108) at org.junit.runner.JUnitCore.run(JUnitCore.java:137) at org.junit.runner.JUnitCore.run(JUnitCore.java:115) at com.google.testing.junit.runner.junit4.JUnit4Runner.run(JUnit4Runner.java:116) at com.google.testing.junit.runner.BazelTestRunner.runTestsInSuite(BazelTestRunner.java:159) at com.google.testing.junit.runner.BazelTestRunner.main(BazelTestRunner.java:85) FAILURES!!! Tests run: 13, Failures: 1 BazelTestRunner exiting with a return value of 1 JVM shutdown hooks (if any) will run now. The JVM will exit once they complete. -- JVM shutdown starting at 2022-06-10 12:53:00 -- Jun 10, 2022 12:53:00 PM com.google.gerrit.pgm.Daemon lambda$run$1 INFO: caught shutdown, cleaning up Jun 10, 2022 12:53:00 PM com.google.gerrit.pgm.Daemon lambda$run$1 INFO: caught shutdown, cleaning up ================================================================================ INFO: Elapsed time: 133.078s, Critical Path: 127.75s INFO: 6 processes: 3 internal, 3 processwrapper-sandbox. INFO: Build completed, 1 test FAILED, 6 total actions //tools/bzl:always_pass_test PASSED in 0.2s //plugins/kafka-events:kafka_events_tests FAILED in 124.7s /home/jenkins/.cache/bazel/_bazel_jenkins/12c44d047b18f871a8b344af7ff88990/execroot/gerrit/bazel-out/k8-fastbuild/testlogs/plugins/kafka-events/kafka_events_tests/test.log Executed 2 out of 2 tests: 1 test passes and 1 fails locally. There were tests whose specified size is too big. Use the --test_verbose_timeout_warnings command line option to see which ones these are. INFO: Build completed, 1 test FAILED, 6 total actions Build step 'Execute shell' marked build as failure Archiving artifacts Finished: FAILURE