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

Fix TestPictureConversions.pictureConversionsAlwaysHaveExtensions randomly failing against PostgreSQL

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Major
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 11.1, 2021.0
    • Component/s: CI/CD
    • Team:
      PLATFORM
    • Sprint:
      nxplatform 11.1.33, nxplatform 11.1.34
    • Story Points:
      2

      Description

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

      [2020-04-06T20:46:41.740Z] [INFO] Running org.nuxeo.ecm.platform.picture.core.test.TestPictureConversions
      [2020-04-06T20:46:43.119Z] 2020-04-06 20:46:42,709 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:46:44.055Z] 2020-04-06 20:46:43,853 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:46:44.989Z] 2020-04-06 20:46:44,929 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:46:46.382Z] 2020-04-06 20:46:46,080 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:46:56.372Z] 2020-04-06 20:46:55,549 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:46:57.765Z] 2020-04-06 20:46:57,417 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:46:58.025Z] 2020-04-06 20:46:57,834 [recomputeViewsPool-00] WARN  [TracerFactory] 
      [2020-04-06T20:46:58.025Z] 
      [2020-04-06T20:46:58.025Z] ****** chain ******
      [2020-04-06T20:46:58.025Z] Name: Error.Operation
      [2020-04-06T20:46:58.025Z] Exception: OperationException
      [2020-04-06T20:46:58.025Z] Caught error: Error
      [2020-04-06T20:46:58.025Z] Caused by: org.nuxeo.ecm.automation.OperationException: Error
      [2020-04-06T20:46:58.025Z] ****** Hierarchy calls ******
      [2020-04-06T20:46:58.025Z] 	org.nuxeo.ecm.platform.picture.core.test.ErrorOperation
      [2020-04-06T20:46:58.025Z] 
      [2020-04-06T20:46:58.025Z] 2020-04-06 20:46:57,835 [recomputeViewsPool-00] ERROR [TransactionHelper] Could not mark transaction as rollback only
      [2020-04-06T20:46:58.025Z] java.lang.IllegalStateException: No transaction associated with current thread
      [2020-04-06T20:46:58.025Z] 	at org.apache.geronimo.transaction.manager.TransactionManagerImpl.setRollbackOnly(TransactionManagerImpl.java:241) ~[geronimo-transaction-2.2.1.jar:2.2.1]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.runtime.jtajca.NuxeoContainer$UserTransactionImpl.setRollbackOnly(NuxeoContainer.java:509) ~[nuxeo-runtime-jtajca-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.setTransactionRollbackOnly(TransactionHelper.java:504) ~[nuxeo-runtime-jtajca-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.automation.OperationContext.setRollback(OperationContext.java:219) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.automation.core.impl.OperationServiceImpl.run(OperationServiceImpl.java:123) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.automation.core.impl.OperationServiceImpl.lambda$run$0(OperationServiceImpl.java:105) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.automation.OperationContext.call(OperationContext.java:326) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.automation.OperationContext.callWithChainParameters(OperationContext.java:291) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.automation.core.impl.OperationServiceImpl.run(OperationServiceImpl.java:105) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.ImagingComponent.callPictureConversionChain(ImagingComponent.java:361) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.ImagingComponent.computeView(ImagingComponent.java:303) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.ImagingComponent.computeView(ImagingComponent.java:270) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.ImagingComponent.computeViewsFor(ImagingComponent.java:383) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.api.adapters.AbstractPictureAdapter.addViews(AbstractPictureAdapter.java:290) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.api.adapters.DefaultPictureAdapter.fillPictureViews(DefaultPictureAdapter.java:98) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.platform.picture.recompute.RecomputeViewsAction$RecomputeViewsComputation.compute(RecomputeViewsAction.java:106) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.core.bulk.action.computation.AbstractBulkComputation.lambda$processBatchOfDocuments$1(AbstractBulkComputation.java:141) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.lambda$runInTransaction$5(TransactionHelper.java:587) ~[nuxeo-runtime-jtajca-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.runInTransaction(TransactionHelper.java:607) ~[nuxeo-runtime-jtajca-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.runtime.transaction.TransactionHelper.runInTransaction(TransactionHelper.java:587) ~[nuxeo-runtime-jtajca-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.core.bulk.action.computation.AbstractBulkComputation.processBatchOfDocuments(AbstractBulkComputation.java:135) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.ecm.core.bulk.action.computation.AbstractBulkComputation.processRecord(AbstractBulkComputation.java:98) ~[classes/:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.lib.stream.computation.log.ComputationRunner.lambda$processRecordWithRetry$10(ComputationRunner.java:377) ~[nuxeo-stream-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at net.jodah.failsafe.Functions$10.call(Functions.java:252) [failsafe-1.1.0.jar:1.1.0]
      [2020-04-06T20:46:58.025Z] 	at net.jodah.failsafe.SyncFailsafe.call(SyncFailsafe.java:145) [failsafe-1.1.0.jar:1.1.0]
      [2020-04-06T20:46:58.025Z] 	at net.jodah.failsafe.SyncFailsafe.run(SyncFailsafe.java:81) [failsafe-1.1.0.jar:1.1.0]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processRecordWithRetry(ComputationRunner.java:377) [nuxeo-stream-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processRecord(ComputationRunner.java:360) [nuxeo-stream-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processLoop(ComputationRunner.java:250) [nuxeo-stream-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at org.nuxeo.lib.stream.computation.log.ComputationRunner.run(ComputationRunner.java:187) [nuxeo-stream-11.1.8.jar:?]
      [2020-04-06T20:46:58.025Z] 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
      [2020-04-06T20:46:58.025Z] 	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
      [2020-04-06T20:46:58.025Z] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
      [2020-04-06T20:46:58.025Z] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
      [2020-04-06T20:46:58.025Z] 	at java.lang.Thread.run(Thread.java:834) [?:?]
      [2020-04-06T20:46:58.025Z] 2020-04-06 20:46:57,857 [recomputeViewsPool-00] WARN  [ImagingComponent] Unable to compute view: withError for document: DocumentModelImpl(79c88e66-d2cd-47af-8fab-5d25f0753e64, path=/picture, title=picture) exception message: org.nuxeo.ecm.automation.OperationException: Error
      [2020-04-06T20:47:00.562Z] RuntimeStreamFeature: Deploying Nuxeo Stream using Chronicle
      [2020-04-06T20:47:00.562Z] WorkManagerFeature: Deploying WorkManager using default implementation
      [2020-04-06T20:47:00.562Z] StorageConfiguration: Deploying JDBC using DatabasePostgreSQL
      [2020-04-06T20:47:00.562Z] StorageConfiguration: Deploying a VCS repository
      [2020-04-06T20:47:00.821Z] Warning: Nashorn engine is planned to be removed from a future JDK release
      [2020-04-06T20:47:02.198Z] 2020-04-06 20:47:01,772 [main] WARN  [TransientStorageComponent] Missing configuration for default transient store, using in-memory
      [2020-04-06T20:47:02.198Z] 2020-04-06 20:47:01,854 [main] ERROR [OSGiRuntimeService] Nuxeo Platform Started
      [2020-04-06T20:47:02.198Z] ======================================================================
      [2020-04-06T20:47:02.198Z] = Component Loading Warnings:
      [2020-04-06T20:47:02.198Z]   * Missing configuration for default transient store, using in-memory
      [2020-04-06T20:47:02.198Z] ======================================================================
      [2020-04-06T20:47:02.198Z] = Component Loading Status: Pending: 2 / Missing: 7 / Unstarted: 0 / Total: 190
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.platform.picture.web.preview.adapter.contrib requires [service:org.nuxeo.ecm.platform.preview.adapter.contrib]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.platform.picture.renditions requires [service:org.nuxeo.ecm.platform.rendition.contrib]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.platform.picture.binary.metadata.contrib references missing [target=org.nuxeo.binary.metadata;point=metadataMappings, target=org.nuxeo.binary.metadata;point=metadataRules]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.platform.picture.filemanager.contrib references missing [target=org.nuxeo.ecm.platform.filemanager.service.FileManagerService;point=plugins]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.platform.picture.jsf.types references missing [target=org.nuxeo.ecm.platform.types.TypeService;point=types]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.core.automation.features.bindings references missing [target=org.nuxeo.ecm.automation.server.AutomationServer;point=bindings]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.core.coldstorage.contrib references missing [target=org.nuxeo.ecm.platform.ec.notification.service.NotificationService;point=notifications, target=org.nuxeo.ecm.platform.ec.notification.service.NotificationService;point=templates]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.runtime.stream.service.managment.contrib references missing [target=org.nuxeo.ecm.core.management.CoreManagementComponent;point=probes, target=org.nuxeo.ecm.core.management.CoreManagementComponent;point=healthCheck]
      [2020-04-06T20:47:02.198Z]   * service:org.nuxeo.ecm.platform.picture.operation references missing [target=org.nuxeo.ecm.automation.server.AutomationServer;point=bindings]
      [2020-04-06T20:47:02.198Z] ======================================================================
      [2020-04-06T20:47:02.198Z] [ERROR] Tests run: 7, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 20.166 s <<< FAILURE! - in org.nuxeo.ecm.platform.picture.core.test.TestPictureConversions
      [2020-04-06T20:47:02.198Z] [ERROR] pictureConversionsAlwaysHaveExtensions(org.nuxeo.ecm.platform.picture.core.test.TestPictureConversions)  Time elapsed: 1.821 s  <<< FAILURE!
      [2020-04-06T20:47:02.198Z] java.lang.AssertionError
      [2020-04-06T20:47:02.198Z] 	at org.junit.Assert.fail(Assert.java:87)
      [2020-04-06T20:47:02.198Z] 	at org.junit.Assert.assertTrue(Assert.java:42)
      [2020-04-06T20:47:02.198Z] 	at org.junit.Assert.assertTrue(Assert.java:53)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.ecm.platform.picture.core.test.TestPictureConversions.pictureConversionsAlwaysHaveExtensions(TestPictureConversions.java:213)
      [2020-04-06T20:47:02.198Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      [2020-04-06T20:47:02.198Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      [2020-04-06T20:47:02.198Z] 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      [2020-04-06T20:47:02.198Z] 	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
      [2020-04-06T20:47:02.198Z] 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
      [2020-04-06T20:47:02.198Z] 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeMethodRunStatement.evaluate(FeaturesRunner.java:308)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeSetupStatement.evaluate(FeaturesRunner.java:322)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterMethodRunStatement.evaluate(FeaturesRunner.java:345)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterTeardownStatement.evaluate(FeaturesRunner.java:362)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.RuntimeDeployment$DeploymentStatement.evaluate(RuntimeDeployment.java:326)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      [2020-04-06T20:47:02.198Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      [2020-04-06T20:47:02.198Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$AfterClassStatement.evaluate(FeaturesRunner.java:251)
      [2020-04-06T20:47:02.199Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$RulesFactory$1.evaluate(FeaturesRunner.java:435)
      [2020-04-06T20:47:02.199Z] 	at org.nuxeo.runtime.test.runner.FeaturesRunner$BeforeClassStatement.evaluate(FeaturesRunner.java:234)
      [2020-04-06T20:47:02.199Z] 	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.Suite.runChild(Suite.java:128)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.Suite.runChild(Suite.java:27)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
      [2020-04-06T20:47:02.199Z] 	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
      [2020-04-06T20:47:02.199Z] 	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
      

        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 - 15 minutes
                  15m