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

Fix random PG TestElasticsearchObject.shouldRunIndexingByNXQLQuery

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Minor
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 11.x, 2021.1
    • Component/s: Elasticsearch
    • Team:
      PLATFORM
    • Sprint:
      nxplatform #23
    • Story Points:
      1

      Description

      Stacktrace:

      java.lang.AssertionError: expected:<2> but was:<0>
      	at org.junit.Assert.fail(Assert.java:89)
      	at org.junit.Assert.failNotEquals(Assert.java:835)
      	at org.junit.Assert.assertEquals(Assert.java:647)
      	at org.junit.Assert.assertEquals(Assert.java:633)
      	at org.nuxeo.ecm.restapi.server.jaxrs.management.TestElasticsearchObject.verifyIndexingResponse(TestElasticsearchObject.java:175)
      	at org.nuxeo.ecm.restapi.server.jaxrs.management.TestElasticsearchObject.shouldRunIndexingByNXQLQuery(TestElasticsearchObject.java:113)
      	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:59)
      	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
      	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeMethodRunStatement.evaluate(FeaturesRunner.java:396)
      	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeSetupStatement.evaluate(FeaturesRunner.java:410)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner.evaluateRunnable(FeaturesRunner.java:267)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterMethodRunStatement.evaluate(FeaturesRunner.java:432)
      	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner.evaluateRunnable(FeaturesRunner.java:267)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterTeardownStatement.evaluate(FeaturesRunner.java:445)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:515)
      	at org.nuxeo.runtime.test.runner.RuntimeDeployment$DeploymentStatement.evaluate(RuntimeDeployment.java:326)
      	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
      	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
      	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
      	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
      	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterClassStatement.evaluate(FeaturesRunner.java:343)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:515)
      	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeClassStatement.evaluate(FeaturesRunner.java:326)
      	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
      	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      	at org.junit.runners.Suite.runChild(Suite.java:128)
      	at org.junit.runners.Suite.runChild(Suite.java:27)
      	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83)
      	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75)
      	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158)
      	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
      	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
      	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
      	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
      

      Standard output:

      2020-10-06 11:31:42,726 [I/O dispatcher 1] WARN  [RequestLogger] request [PUT http://test-release-elasticsearch-client.nuxeo-unit-tests-master-670-postgresql.svc.cluster.local:9200/nxutest/doc/_mapping] returned 1 warnings: [299 Elasticsearch-6.8.6-3d9f765 "[types removal] Specifying types in put mapping requests is deprecated. To be compatible with 7.0, the mapping definition should not be nested under the type name, and the parameter include_type_name must be provided and set to false."]
      2020-10-06 11:31:42,806 [bulk/scrollerPool-01,in:1,inCheckpoint:1,out:1,lastRead:1601983899884,lastTimer:0,wm:209975233707900929,loop:28,checkpoint] WARN  [SwallowedExceptionLogger] An internal object pool swallowed an Exception.
      org.postgresql.util.PSQLException: Cannot rollback when autoCommit is enabled.
      	at org.postgresql.jdbc.PgConnection.rollback(PgConnection.java:867) ~[postgresql-42.2.9.jar:42.2.9]
      	at org.apache.commons.dbcp2.DelegatingConnection.rollback(DelegatingConnection.java:484) ~[commons-dbcp2-2.7.0.jar:2.7.0]
      	at org.apache.commons.dbcp2.PoolableConnectionFactory.passivateObject(PoolableConnectionFactory.java:424) ~[commons-dbcp2-2.7.0.jar:2.7.0]
      	at org.apache.commons.pool2.impl.GenericObjectPool.returnObject(GenericObjectPool.java:555) ~[commons-pool2-2.8.0.jar:2.8.0]
      	at org.apache.commons.dbcp2.PoolableConnection.close(PoolableConnection.java:200) ~[commons-dbcp2-2.7.0.jar:2.7.0]
      	at org.apache.commons.dbcp2.DelegatingConnection.closeInternal(DelegatingConnection.java:238) ~[commons-dbcp2-2.7.0.jar:2.7.0]
      	at org.apache.commons.dbcp2.DelegatingConnection.close(DelegatingConnection.java:209) ~[commons-dbcp2-2.7.0.jar:2.7.0]
      	at org.apache.commons.dbcp2.managed.ManagedConnection.close(ManagedConnection.java:111) ~[commons-dbcp2-2.7.0.jar:2.7.0]
      	at org.nuxeo.ecm.core.storage.sql.jdbc.JDBCConnection.closeConnection(JDBCConnection.java:152) ~[classes/:?]
      	at org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper.close(JDBCMapper.java:111) ~[classes/:?]
      	at org.nuxeo.ecm.core.storage.sql.SoftRefCachingMapper.close(SoftRefCachingMapper.java:73) ~[classes/:?]
      	at org.nuxeo.ecm.core.storage.sql.SessionImpl.closeSession(SessionImpl.java:197) ~[classes/:?]
      	at org.nuxeo.ecm.core.storage.sql.SessionImpl.close(SessionImpl.java:188) ~[classes/:?]
      	at org.nuxeo.ecm.core.storage.sql.coremodel.SQLSession.destroy(SQLSession.java:122) ~[classes/:?]
      	at org.nuxeo.ecm.core.repository.RepositoryService$SessionFactory.destroyObject(RepositoryService.java:398) ~[classes/:?]
      	at org.nuxeo.ecm.core.repository.RepositoryService$SessionFactory.destroyObject(RepositoryService.java:380) ~[classes/:?]
      	at org.apache.commons.pool2.impl.GenericKeyedObjectPool.destroy(GenericKeyedObjectPool.java:1104) ~[commons-pool2-2.8.0.jar:2.8.0]
      	at org.apache.commons.pool2.impl.GenericKeyedObjectPool.invalidateObject(GenericKeyedObjectPool.java:572) ~[commons-pool2-2.8.0.jar:2.8.0]
      	at org.apache.commons.pool2.PoolUtils$ErodingKeyedObjectPool.invalidateObject(PoolUtils.java:1682) ~[commons-pool2-2.8.0.jar:2.8.0]
      	at org.nuxeo.ecm.core.repository.RepositoryService$SessionSynchronization.afterCompletion(RepositoryService.java:365) ~[classes/:?]
      	at org.apache.geronimo.transaction.manager.TransactionImpl.afterCompletion(TransactionImpl.java:559) ~[geronimo-transaction-3.1.4.jar:3.1.4]
      	at org.apache.geronimo.transaction.manager.TransactionImpl.afterCompletion(TransactionImpl.java:552) ~[geronimo-transaction-3.1.4.jar:3.1.4]
      	at org.apache.geronimo.transaction.manager.TransactionImpl.rollback(TransactionImpl.java:503) ~[geronimo-transaction-3.1.4.jar:3.1.4]
      	at org.apache.geronimo.transaction.manager.TransactionManagerImpl.rollback(TransactionManagerImpl.java:265) ~[geronimo-transaction-3.1.4.jar:3.1.4]
      	at org.nuxeo.runtime.jtajca.NuxeoContainer$UserTransactionImpl.rollback(NuxeoContainer.java:440) ~[nuxeo-runtime-jtajca-11.3.56.jar:?]
      	at org.nuxeo.runtime.transaction.TransactionHelper.commitOrRollbackTransaction(TransactionHelper.java:408) ~[nuxeo-runtime-jtajca-11.3.56.jar:?]
      	at org.nuxeo.ecm.core.bulk.computation.BulkScrollerComputation.processRecord(BulkScrollerComputation.java:137) ~[classes/:?]
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.lambda$processRecordWithRetry$10(ComputationRunner.java:466) ~[nuxeo-stream-11.3.56.jar:?]
      	at net.jodah.failsafe.Functions$10.call(Functions.java:252) [failsafe-1.1.0.jar:1.1.0]
      	at net.jodah.failsafe.SyncFailsafe.call(SyncFailsafe.java:145) [failsafe-1.1.0.jar:1.1.0]
      	at net.jodah.failsafe.SyncFailsafe.run(SyncFailsafe.java:81) [failsafe-1.1.0.jar:1.1.0]
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processRecordWithRetry(ComputationRunner.java:466) [nuxeo-stream-11.3.56.jar:?]
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processRecordWithTracing(ComputationRunner.java:418) [nuxeo-stream-11.3.56.jar:?]
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processRecord(ComputationRunner.java:409) [nuxeo-stream-11.3.56.jar:?]
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processLoop(ComputationRunner.java:272) [nuxeo-stream-11.3.56.jar:?]
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.run(ComputationRunner.java:206) [nuxeo-stream-11.3.56.jar:?]
      	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
      	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
      	at java.lang.Thread.run(Thread.java:834) [?:?]
      2020-10-06 11:31:42,888 [bulk/indexCompletionPool-00,in:1,inCheckpoint:1,out:0,lastRead:1601983901770,lastTimer:0,wm:209975233971879937,loop:42,checkpoint] WARN  [IndexCompletionComputation] Index command: ba35d4f7-385c-49a6-b204-1b16d2f08541 completed: 0 in 0.02s (wait: 0.01s, scroll: 0.01s) rate: 0.00 docs/s
      2020-10-06 11:31:42,891 [bulk/indexCompletionPool-00,in:1,inCheckpoint:1,out:0,lastRead:1601983901770,lastTimer:0,wm:209975233971879937,loop:42,checkpoint] WARN  [IndexCompletionComputation] Update alias requested by command: ba35d4f7-385c-49a6-b204-1b16d2f08541
      2020-10-06 11:31:43,164 [I/O dispatcher 1] WARN  [RequestLogger] request [GET http://test-release-elasticsearch-client.nuxeo-unit-tests-master-670-postgresql.svc.cluster.local:9200/nxutest/doc/42c076fe-3fb1-43c5-bf91-600cfb6dc86e?_source_include=ecm%3Apath] returned 1 warnings: [299 Elasticsearch-6.8.6-3d9f765 "Deprecated parameter [_source_include] used, expected [_source_includes] instead"]
      

        Attachments

          Issue Links

            Activity

              People

              • Assignee:
                kleturc Kevin Leturc
                Reporter:
                kleturc Kevin Leturc
                Participants:
              • Votes:
                0 Vote for this issue
                Watchers:
                1 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: