Uploaded image for project: 'Nuxeo Platform'
  1. Nuxeo Platform
  2. NXP-22796

Fix random UT failure on nuxeo-mqueue TestPatternQueuingChronicle.killConsumers

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Minor
    • Resolution: Fixed
    • Affects Version/s: 9.2
    • Fix Version/s: 9.3
    • Component/s: Events / Works

      Description

      java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.CompletableFuture$AsyncSupply@3892fa28 rejected from java.util.concurrent.ThreadPoolExecutor@42abf99a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
      
      Stacktrace
      
      java.util.concurrent.ExecutionException: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.CompletableFuture$AsyncSupply@3892fa28 rejected from java.util.concurrent.ThreadPoolExecutor@42abf99a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
      	at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:357)
      	at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1895)
      	at org.nuxeo.ecm.platform.importer.mqueues.tests.pattern.TestPatternQueuing.killConsumers(TestPatternQueuing.java:158)
      	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:47)
      	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
      	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 org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
      	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
      	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
      	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
      	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
      	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
      	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
      	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
      	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
      	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
      	at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
      	at org.junit.runners.Suite.runChild(Suite.java:127)
      	at org.junit.runners.Suite.runChild(Suite.java:26)
      	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
      	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
      	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
      	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
      	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
      	at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
      	at org.junit.runner.JUnitCore.run(JUnitCore.java:160)
      	at org.junit.runner.JUnitCore.run(JUnitCore.java:138)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:113)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:85)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:54)
      	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:134)
      	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200)
      	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153)
      	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
      Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.CompletableFuture$AsyncSupply@3892fa28 rejected from java.util.concurrent.ThreadPoolExecutor@42abf99a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
      	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
      	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
      	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
      	at java.util.concurrent.CompletableFuture.asyncSupplyStage(CompletableFuture.java:1604)
      	at java.util.concurrent.CompletableFuture.supplyAsync(CompletableFuture.java:1830)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.AbstractCallablePool.runPool(AbstractCallablePool.java:89)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.AbstractCallablePool.lambda$start$0(AbstractCallablePool.java:70)
      	at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1590)
      	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:748)
      
      Standard Output
      
      08:46:37,720 [Nuxeo-ConsumerPool-00] WARN  [AbstractCallablePool] Start Nuxeo-Consumer Pool on 2 thread(s).
      08:46:37,725 [Nuxeo-Consumer-00-1-batch now] ERROR [AbstractCallablePool] Exception catch in runner: sleep interrupted
      java.lang.InterruptedException: sleep interrupted
      	at java.lang.Thread.sleep(Native Method)
      	at org.nuxeo.ecm.platform.importer.mqueues.mqueues.chronicle.ChronicleMQTailer.read(ChronicleMQTailer.java:88)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.ConsumerRunner.acceptBatch(ConsumerRunner.java:265)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.ConsumerRunner.processBatch(ConsumerRunner.java:222)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.ConsumerRunner.processBatchWithRetry(ConsumerRunner.java:185)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.ConsumerRunner.consumerLoop(ConsumerRunner.java:169)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.ConsumerRunner.call(ConsumerRunner.java:120)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.ConsumerRunner.call(ConsumerRunner.java:57)
      	at org.nuxeo.ecm.platform.importer.mqueues.pattern.consumer.internals.AbstractCallablePool.lambda$runPool$1(AbstractCallablePool.java:91)
      	at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1590)
      	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:748)
      
      

      Seen twice:
      https://qa.nuxeo.org/jenkins/job/Deploy/job/IT-nuxeo-master-build/534/testReport/junit/org.nuxeo.ecm.platform.importer.mqueues.tests.pattern/TestPatternQueuingChronicle/killConsumers/

      https://qa.nuxeo.org/jenkins/job/master/job/nuxeo-master-fullbuild-maven3.1/417/testReport/junit/org.nuxeo.ecm.platform.importer.mqueues.tests.pattern/TestPatternQueuingChronicle/killConsumers/

        Attachments

          Issue Links

            Activity

              People

              • Votes:
                0 Vote for this issue
                Watchers:
                2 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved:

                  Time Tracking

                  Estimated:
                  Original Estimate - Not Specified
                  Not Specified
                  Remaining:
                  Remaining Estimate - 0 minutes
                  0m
                  Logged:
                  Time Spent - 1 day, 3 hours
                  1d 3h