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

Fix TestSQLBackendNoPathOptims.testRecursiveRemovalBig randomly failing against PostgreSQL

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Open
    • Priority: Major
    • Resolution: Unresolved
    • Affects Version/s: None
    • Fix Version/s: 11.x
    • Component/s: CI/CD

      Description

      See https://jenkins.platform.dev.nuxeo.com/job/nuxeo/job/nuxeo/job/master/405/.

      [2020-04-08T19:58:13.690Z] [INFO] Running org.nuxeo.ecm.core.storage.sql.TestSQLBackendNoPathOptims
      [2020-04-08T19:58:15.597Z] 2020-04-08 19:58:15,565 [main] WARN  [ConnectionFactoryImpl] cannot dispose connection manager of test
      [2020-04-08T19:58:47.712Z] 2020-04-08 19:58:45,619 [main] WARN  [ConnectionFactoryImpl] cannot dispose connection manager of test
      [2020-04-08T20:00:09.149Z] 2020-04-08 20:00:00,014 [Quartz_Worker-1] ERROR [EventServiceImpl] Exception during findRetentionExpired sync listener execution, continuing to run other listeners
      [2020-04-08T20:00:09.149Z] java.lang.NullPointerException: null
      [2020-04-08T20:00:09.149Z] 	at org.nuxeo.ecm.core.security.RetentionExpiredFinderListener.handleEvent(RetentionExpiredFinderListener.java:53) ~[classes/:?]
      [2020-04-08T20:00:09.149Z] 	at org.nuxeo.ecm.core.event.impl.EventServiceImpl.fireEvent(EventServiceImpl.java:241) [classes/:?]
      [2020-04-08T20:00:09.149Z] 	at org.nuxeo.ecm.core.scheduler.EventJob.execute(EventJob.java:107) [classes/:?]
      [2020-04-08T20:00:09.149Z] 	at org.nuxeo.ecm.core.scheduler.EventJob.execute(EventJob.java:65) [classes/:?]
      [2020-04-08T20:00:09.149Z] 	at org.quartz.core.JobRunShell.run(JobRunShell.java:202) [quartz-2.3.2.jar:?]
      [2020-04-08T20:00:09.149Z] 	at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573) [quartz-2.3.2.jar:?]
      [2020-04-08T20:22:31.566Z] 2020-04-08 20:22:28,262 [main] WARN  [TransactionImpl] Error ending association for XAResource org.nuxeo.ecm.core.storage.sql.TestSQLBackend$1@14339a16; transaction will roll back. XA error code: 104
      [2020-04-08T20:22:31.566Z] javax.transaction.xa.XAException: null
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.ecm.core.storage.sql.TestSQLBackend$1.end(TestSQLBackend.java:1664) ~[test-classes/:?]
      [2020-04-08T20:22:31.566Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.endResources(TransactionImpl.java:569) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.566Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.endResources(TransactionImpl.java:548) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.566Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.beforePrepare(TransactionImpl.java:408) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.566Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:255) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.566Z] 	at org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:252) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.jtajca.NuxeoContainer$UserTransactionImpl.commit(NuxeoContainer.java:538) [nuxeo-runtime-jtajca-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.commitOrRollbackTransaction(TransactionHelper.java:393) [nuxeo-runtime-jtajca-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.ecm.core.storage.sql.TestSQLBackend.testRollback(TestSQLBackend.java:1693) [test-classes/:?]
      [2020-04-08T20:22:31.566Z] 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
      [2020-04-08T20:22:31.566Z] 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
      [2020-04-08T20:22:31.566Z] 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
      [2020-04-08T20:22:31.566Z] 	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeMethodRunStatement.evaluate(FeaturesRunner.java:308) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeSetupStatement.evaluate(FeaturesRunner.java:322) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterMethodRunStatement.evaluate(FeaturesRunner.java:345) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterTeardownStatement.evaluate(FeaturesRunner.java:362) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.RuntimeDeployment$DeploymentStatement.evaluate(RuntimeDeployment.java:326) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterClassStatement.evaluate(FeaturesRunner.java:251) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeClassStatement.evaluate(FeaturesRunner.java:234) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.566Z] 	at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.Suite.runChild(Suite.java:128) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.Suite.runChild(Suite.java:27) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.566Z] 	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.566Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.566Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 2020-04-08 20:22:28,288 [main] WARN  [TransactionImpl] Error ending association for XAResource org.nuxeo.ecm.core.storage.sql.TestSQLBackend$1@14339a16; transaction will roll back. XA error code: 104
      [2020-04-08T20:22:31.567Z] javax.transaction.xa.XAException: null
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.ecm.core.storage.sql.TestSQLBackend$1.end(TestSQLBackend.java:1664) ~[test-classes/:?]
      [2020-04-08T20:22:31.567Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.endResources(TransactionImpl.java:569) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.567Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.endResources(TransactionImpl.java:548) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.567Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.rollback(TransactionImpl.java:488) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.567Z] 	at org.apache.geronimo.transaction.manager.TransactionManagerImpl.rollback(TransactionManagerImpl.java:265) [geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.jtajca.NuxeoContainer$UserTransactionImpl.rollback(NuxeoContainer.java:556) [nuxeo-runtime-jtajca-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.commitOrRollbackTransaction(TransactionHelper.java:416) [nuxeo-runtime-jtajca-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.ecm.core.storage.sql.TestSQLBackend.testRollback(TestSQLBackend.java:1715) [test-classes/:?]
      [2020-04-08T20:22:31.567Z] 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
      [2020-04-08T20:22:31.567Z] 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
      [2020-04-08T20:22:31.567Z] 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
      [2020-04-08T20:22:31.567Z] 	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeMethodRunStatement.evaluate(FeaturesRunner.java:308) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeSetupStatement.evaluate(FeaturesRunner.java:322) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterMethodRunStatement.evaluate(FeaturesRunner.java:345) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterTeardownStatement.evaluate(FeaturesRunner.java:362) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.RuntimeDeployment$DeploymentStatement.evaluate(RuntimeDeployment.java:326) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterClassStatement.evaluate(FeaturesRunner.java:251) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeClassStatement.evaluate(FeaturesRunner.java:234) [nuxeo-runtime-test-11.1.9.jar:?]
      [2020-04-08T20:22:31.567Z] 	at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.Suite.runChild(Suite.java:128) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.Suite.runChild(Suite.java:27) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413) [junit-4.13.jar:4.13]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158) [surefire-junit47-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) [surefire-booter-2.22.2.jar:2.22.2]
      [2020-04-08T20:22:31.567Z] 2020-04-08 20:22:29,102 [main] WARN  [ConnectionFactoryImpl] cannot dispose connection manager of test
      [2020-04-08T20:22:33.472Z] 2020-04-08 20:22:33,133 [main] WARN  [ConnectionFactoryImpl] cannot dispose connection manager of test
      [2020-04-08T20:22:33.472Z] [ERROR] Tests run: 97, Failures: 0, Errors: 1, Skipped: 4, Time elapsed: 1,459.532 s <<< FAILURE! - in org.nuxeo.ecm.core.storage.sql.TestSQLBackendNoPathOptims
      [2020-04-08T20:22:33.472Z] [ERROR] testRecursiveRemovalBig(org.nuxeo.ecm.core.storage.sql.TestSQLBackendNoPathOptims)  Time elapsed: 1,415.586 s  <<< ERROR!
      [2020-04-08T20:22:33.472Z] org.nuxeo.runtime.transaction.TransactionRuntimeException: Unable to commit: Transaction timeout
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.commitOrRollbackTransaction(TransactionHelper.java:410)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.runInTransaction(TransactionHelper.java:617)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapperConnector.doConnectAndInvoke(JDBCMapperConnector.java:95)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapperConnector.invoke(JDBCMapperConnector.java:84)
      [2020-04-08T20:22:33.472Z] 	at com.sun.proxy.$Proxy57.updateReadAcls(Unknown Source)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.SoftRefCachingMapper.updateReadAcls(SoftRefCachingMapper.java:131)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.SessionImpl.updateReadAcls(SessionImpl.java:1268)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.SessionImpl.flush(SessionImpl.java:348)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.SessionImpl.save(SessionImpl.java:325)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.ecm.core.storage.sql.TestSQLBackend.testRecursiveRemovalBig(TestSQLBackend.java:410)
      [2020-04-08T20:22:33.472Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      [2020-04-08T20:22:33.472Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      [2020-04-08T20:22:33.472Z] 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      [2020-04-08T20:22:33.472Z] 	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
      [2020-04-08T20:22:33.472Z] 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
      [2020-04-08T20:22:33.472Z] 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeMethodRunStatement.evaluate(FeaturesRunner.java:308)
      [2020-04-08T20:22:33.472Z] 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeSetupStatement.evaluate(FeaturesRunner.java:322)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterMethodRunStatement.evaluate(FeaturesRunner.java:345)
      [2020-04-08T20:22:33.472Z] 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterTeardownStatement.evaluate(FeaturesRunner.java:362)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.RuntimeDeployment$DeploymentStatement.evaluate(RuntimeDeployment.java:326)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterClassStatement.evaluate(FeaturesRunner.java:251)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435)
      [2020-04-08T20:22:33.472Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeClassStatement.evaluate(FeaturesRunner.java:234)
      [2020-04-08T20:22:33.472Z] 	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.Suite.runChild(Suite.java:128)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.Suite.runChild(Suite.java:27)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      [2020-04-08T20:22:33.472Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      [2020-04-08T20:22:33.473Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      [2020-04-08T20:22:33.473Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      [2020-04-08T20:22:33.473Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-04-08T20:22:33.473Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
      [2020-04-08T20:22:33.473Z] 	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
      [2020-04-08T20:22:33.473Z] Caused by: javax.transaction.RollbackException: Unable to commit: Transaction timeout
      [2020-04-08T20:22:33.473Z] 	at org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:267)
      [2020-04-08T20:22:33.473Z] 	at org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:252)
      [2020-04-08T20:22:33.473Z] 	at org.nuxeo.runtime.jtajca.NuxeoContainer$UserTransactionImpl.commit(NuxeoContainer.java:538)
      [2020-04-08T20:22:33.473Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.commitOrRollbackTransaction(TransactionHelper.java:393)
      [2020-04-08T20:22:33.473Z] 	... 60 more
      

      Not sure how the error in TestSQLBackend.testRollback is related, it happens locally systematically.

      This test was historically added for NXP-20211.
       

        Attachments

          Issue Links

            Activity

              People

              • Assignee:
                ataillefer Antoine Taillefer
                Reporter:
                ataillefer Antoine Taillefer
                Participants:
              • Votes:
                0 Vote for this issue
                Watchers:
                1 Start watching this issue

                Dates

                • Created:
                  Updated: