Uploaded image for project: 'Kafka'
  1. Kafka
  2. KAFKA-2054 Fix Kafka transient unit test failures
  3. KAFKA-5182

Transient failure: RequestQuotaTest.testResponseThrottleTime

    XMLWordPrintableJSON

    Details

    • Type: Sub-task
    • Status: Resolved
    • Priority: Major
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 0.11.0.0
    • Component/s: None
    • Labels:
      None

      Description

      Stacktrace

      java.util.concurrent.ExecutionException: java.lang.AssertionError: Response not throttled: Client JOIN_GROUP apiKey JOIN_GROUP requests 3 requestTime 0.009982775502048156 throttleTime 0.0
      	at java.util.concurrent.FutureTask.report(FutureTask.java:122)
      	at java.util.concurrent.FutureTask.get(FutureTask.java:206)
      	at kafka.server.RequestQuotaTest$$anonfun$waitAndCheckResults$1.apply(RequestQuotaTest.scala:326)
      	at kafka.server.RequestQuotaTest$$anonfun$waitAndCheckResults$1.apply(RequestQuotaTest.scala:324)
      	at scala.collection.immutable.List.foreach(List.scala:392)
      	at scala.collection.generic.TraversableForwarder$class.foreach(TraversableForwarder.scala:35)
      	at scala.collection.mutable.ListBuffer.foreach(ListBuffer.scala:45)
      	at kafka.server.RequestQuotaTest.waitAndCheckResults(RequestQuotaTest.scala:324)
      	at kafka.server.RequestQuotaTest.testResponseThrottleTime(RequestQuotaTest.scala:105)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	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.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.runners.ParentRunner.run(ParentRunner.java:363)
      	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:114)
      	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:57)
      	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
      	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
      	at sun.reflect.GeneratedMethodAccessor3.invoke(Unknown Source)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
      	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
      	at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
      	at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
      	at com.sun.proxy.$Proxy2.processTestClass(Unknown Source)
      	at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109)
      	at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
      	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
      	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:147)
      	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:129)
      	at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:404)
      	at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:63)
      	at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:46)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
      	at java.lang.Thread.run(Thread.java:745)
      Caused by: java.lang.AssertionError: Response not throttled: Client JOIN_GROUP apiKey JOIN_GROUP requests 3 requestTime 0.009982775502048156 throttleTime 0.0
      	at org.junit.Assert.fail(Assert.java:88)
      	at org.junit.Assert.assertTrue(Assert.java:41)
      	at kafka.server.RequestQuotaTest.kafka$server$RequestQuotaTest$$checkRequestThrottleTime(RequestQuotaTest.scala:371)
      	at kafka.server.RequestQuotaTest$$anonfun$testResponseThrottleTime$1$$anonfun$apply$1.apply$mcV$sp(RequestQuotaTest.scala:102)
      	at kafka.server.RequestQuotaTest$$anon$1.run(RequestQuotaTest.scala:317)
      	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	... 3 more
      

      Standard Output

      [2017-05-05 20:30:20,005] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:20,424] ERROR [KafkaApi-0] Error when handling request Name: ControlledShutdownRequest; Version: 1; CorrelationId: 1;ClientId:Some(unauthorized-CONTROLLED_SHUTDOWN_KEY); BrokerId: 0 (kafka.server.KafkaApis:105)
      org.apache.kafka.common.errors.ClusterAuthorizationException: Request Request(0,127.0.0.1:40246-127.0.0.1:39392,Session(User:Unauthorized,/127.0.0.1),null,17268305409421634,ListenerName(PLAINTEXT),PLAINTEXT) is not authorized.
      [2017-05-05 20:30:20,447] ERROR [KafkaApi-0] Error when handling request {topics=[{topic=topic-1,partitions=[{partition_id=0,leader_epoch=0}]}]} (kafka.server.KafkaApis:105)
      org.apache.kafka.common.errors.ClusterAuthorizationException: Request Request(1,127.0.0.1:40246-127.0.0.1:39412,Session(User:Unauthorized,/127.0.0.1),null,17268305429105788,ListenerName(PLAINTEXT),PLAINTEXT) is not authorized.
      [2017-05-05 20:30:20,448] ERROR [KafkaApi-0] Error when handling request {transaction_markers=[]} (kafka.server.KafkaApis:105)
      org.apache.kafka.common.errors.ClusterAuthorizationException: Request Request(0,127.0.0.1:40246-127.0.0.1:39428,Session(User:Unauthorized,/127.0.0.1),null,17268305433361297,ListenerName(PLAINTEXT),PLAINTEXT) is not authorized.
      [2017-05-05 20:30:20,486] ERROR [KafkaApi-0] Error when handling request {transaction_markers=[]} (kafka.server.KafkaApis:105)
      org.apache.kafka.common.errors.ClusterAuthorizationException: Request Request(0,127.0.0.1:40246-127.0.0.1:39428,Session(User:Unauthorized,/127.0.0.1),null,17268305472193133,ListenerName(PLAINTEXT),PLAINTEXT) is not authorized.
      [2017-05-05 20:30:20,487] ERROR [KafkaApi-0] Error when handling request {topics=[{topic=topic-1,partitions=[{partition_id=0,leader_epoch=0}]}]} (kafka.server.KafkaApis:105)
      org.apache.kafka.common.errors.ClusterAuthorizationException: Request Request(1,127.0.0.1:40246-127.0.0.1:39412,Session(User:Unauthorized,/127.0.0.1),null,17268305472395201,ListenerName(PLAINTEXT),PLAINTEXT) is not authorized.
      [2017-05-05 20:30:20,636] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:21,466] ERROR [KafkaApi-0] Error when handling request Name: ControlledShutdownRequest; Version: 1; CorrelationId: 2;ClientId:Some(unauthorized-CONTROLLED_SHUTDOWN_KEY); BrokerId: 0 (kafka.server.KafkaApis:105)
      org.apache.kafka.common.errors.ClusterAuthorizationException: Request Request(0,127.0.0.1:40246-127.0.0.1:39392,Session(User:Unauthorized,/127.0.0.1),null,17268305452139005,ListenerName(PLAINTEXT),PLAINTEXT) is not authorized.
      [2017-05-05 20:30:22,024] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:23,393] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:23,705] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:23,723] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:24,637] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:26,384] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:26,403] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:26,692] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:27,855] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:27,887] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      [2017-05-05 20:30:28,056] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:29,656] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:31,002] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:32,746] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:34,205] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:35,947] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:37,076] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:38,463] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:39,819] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:40,986] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:41,286] ERROR Test failed for api-key JOIN_GROUP with exception java.util.concurrent.ExecutionException: java.lang.AssertionError: Response not throttled: Client JOIN_GROUP apiKey JOIN_GROUP requests 3 requestTime 0.009982775502048156 throttleTime 0.0 (kafka.server.RequestQuotaTest:99)
      [2017-05-05 20:30:42,499] WARN Session 0x15bda4d34eb0002 for server null, unexpected error, closing socket connection and attempting reconnect (org.apache.zookeeper.ClientCnxn:1162)
      java.net.ConnectException: Connection refused
      	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
      	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
      	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
      	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
      [2017-05-05 20:30:44,304] ERROR ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes (org.apache.zookeeper.server.ZooKeeperServer:472)
      

      https://builds.apache.org/job/kafka-pr-jdk8-scala2.11/3569/testReport/junit/kafka.server/RequestQuotaTest/testResponseThrottleTime/

        Attachments

          Activity

            People

            • Assignee:
              rsivaram Rajini Sivaram
              Reporter:
              xvrl Xavier Léauté
            • Votes:
              0 Vote for this issue
              Watchers:
              4 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: