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

Fix random PG TestElasticsearchObject.shouldRunIndexingOnDocumentAndItsChildren

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Minor
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 11.x, 2021.1
    • Component/s: Elasticsearch, Tests

      Description

      We want to fix the random TestElasticsearchObject.shouldRunIndexingOnDocumentAndItsChildren happening on PostgreSQL, see the stack below:

      [2020-09-09T19:00:17.961Z] [ERROR] shouldRunIndexingOnDocumentAndItsChildren(org.nuxeo.ecm.restapi.server.jaxrs.management.TestElasticsearchObject)  Time elapsed: 0.741 s  <<< FAILURE!
      [2020-09-09T19:00:17.961Z] java.lang.AssertionError: expected:<3> but was:<0
      [2020-09-09T19:00:17.961Z] 	at org.junit.Assert.fail(Assert.java:89)
      [2020-09-09T19:00:17.961Z] 	at org.junit.Assert.failNotEquals(Assert.java:835)
      [2020-09-09T19:00:17.961Z] 	at org.junit.Assert.assertEquals(Assert.java:647)
      [2020-09-09T19:00:17.961Z] 	at org.junit.Assert.assertEquals(Assert.java:633)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.ecm.restapi.server.jaxrs.management.TestElasticsearchObject.verifyIndexingResponse(TestElasticsearchObject.java:175)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.ecm.restapi.server.jaxrs.management.TestElasticsearchObject.shouldRunIndexingOnDocumentAndItsChildren(TestElasticsearchObject.java:131)
      [2020-09-09T19:00:17.961Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      [2020-09-09T19:00:17.961Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      [2020-09-09T19:00:17.961Z] 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      [2020-09-09T19:00:17.961Z] 	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
      [2020-09-09T19:00:17.961Z] 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
      [2020-09-09T19:00:17.961Z] 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeMethodRunStatement.evaluate(FeaturesRunner.java:396)
      [2020-09-09T19:00:17.961Z] 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeSetupStatement.evaluate(FeaturesRunner.java:410)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner.evaluateRunnable(FeaturesRunner.java:267)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterMethodRunStatement.evaluate(FeaturesRunner.java:432)
      [2020-09-09T19:00:17.961Z] 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner.evaluateRunnable(FeaturesRunner.java:267)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterTeardownStatement.evaluate(FeaturesRunner.java:445)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:515)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.RuntimeDeployment$DeploymentStatement.evaluate(RuntimeDeployment.java:326)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      [2020-09-09T19:00:17.961Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterClassStatement.evaluate(FeaturesRunner.java:343)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:515)
      [2020-09-09T19:00:17.961Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeClassStatement.evaluate(FeaturesRunner.java:326)
      [2020-09-09T19:00:17.961Z] 	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.Suite.runChild(Suite.java:128)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.Suite.runChild(Suite.java:27)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-09-09T19:00:17.962Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158)
      [2020-09-09T19:00:18.225Z] 	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
      [2020-09-09T19:00:18.226Z] 	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
      [2020-09-09T19:00:18.226Z] 	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
      [2020-09-09T19:00:18.226Z] 	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
      

      Standard Output:

      2020-09-10 11:33:03,955 [bulk/indexCompletionPool-00,in:2,inCheckpoint:2,out:0,lastRead:1599737583342,lastTimer:0,wm:209680804521050113,loop:83,checkpoint] WARN  [IndexCompletionComputation] Index command: 4b3a117d-60a4-4709-86d5-42692069eff4 completed: 0 in 0.01s (wait: 0.00s, scroll: 0.01s) rate: 0.00 docs/s
      2020-09-10 11:33:03,957 [bulk/indexCompletionPool-00,in:2,inCheckpoint:2,out:0,lastRead:1599737583342,lastTimer:0,wm:209680804521050113,loop:83,checkpoint] WARN  [IndexCompletionComputation] Update alias requested by command: 4b3a117d-60a4-4709-86d5-42692069eff4
      

        Attachments

          Issue Links

            Activity

              People

              • Votes:
                0 Vote for this issue
                Watchers:
                4 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 - 20 minutes
                  20m