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

Fix Random PG TestPatternBoundedQueuingChronicle.producersAndConsumersConcurrently

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Minor
    • Resolution: Won't Do
    • Affects Version/s: None
    • Fix Version/s: None
    • Component/s: Tests

      Description

      Regression
      
      Run unit tests / Run postgresql unit tests / org.nuxeo.lib.stream.tests.pattern.TestPatternBoundedQueuingChronicle.producersAndConsumersConcurrently
      Failing for the past 1 build (Since Failed#637 )
      Took 6 sec.
      add description
      Error Message
      
      expected:<15000> but was:<13000>
      
      Stacktrace
      
      java.lang.AssertionError: expected:<15000> but was:<13000>
      	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.lib.stream.tests.pattern.TestPatternBoundedQueuing.producersAndConsumersConcurrently(TestPatternBoundedQueuing.java:122)
      

      Partial output (see attached file for full output):

      2020-09-11 23:08:47,266 [Nuxeo-ConsumerPool-00] WARN  [Slf4jExceptionHandler$2] Took 3 ms to add mapping for target-postgresql/junit5097951718192540867/junit12060547623164568658/logName/offset-default/metadata.cq4t
      2020-09-11 23:08:47,267 [Nuxeo-Producer-10-18-Random message 18] WARN  [Slf4jExceptionHandler$2] Couldn't acquire write lock after 15000ms for the lock file:target-postgresql/junit5097951718192540867/junit12060547623164568658/logName/P-06/metadata.cq4t, overriding the lock. Lock was held by me
      
      2020-09-11 23:08:47,269 [Nuxeo-Producer-10-18-Random message 18] WARN  [Slf4jExceptionHandler$2] Forced unlock for the lock file:target-postgresql/junit5097951718192540867/junit12060547623164568658/logName/P-06/metadata.cq4t, unlocked: true
      net.openhft.chronicle.core.StackTrace: Forced unlock on Nuxeo-Producer-10-18-Random message 18
      
      2020-09-11 23:08:47,512 [Nuxeo-ConsumerPool-00] INFO  [ChronicleLogOffsetTracker] Invalid offset tailer state: /home/jenkins/workspace/nuxeo_nuxeo_master/modules/platform/nuxeo-platform-importer/nuxeo-importer-stream/target-postgresql/junit5097951718192540867/junit12060547623164568658/logName/offset-default: BEYOND_START_OF_CYCLE taken as uninitialized
      2020-09-11 23:08:47,512 [Nuxeo-Producer-10-35-Random message 35] ERROR [AbstractCallablePool] Exception catch in runner: Data was written after the end of the message, zero out data before rewinding [pos: 132856, rlim: 132856, wlim: 1181356, cap: 140737488355328 ] xeo.lib.stream.pattern.KeyValueMessage\u0082\u0015٠٠٠ñRandom message 16°°٠ǁ‡L٠٠٠Ãmsg¶,org.nuxeo.lib.stream.pattern.KeyValueMessage\u0082\u0015٠٠٠ñRand
      
      2020-09-11 23:08:48,228 [Nuxeo-ProducerPool-00] INFO  [ArrayList] Producer status FAILURE
      

      Seen
      https://jenkins.platform.dev.nuxeo.com/job/nuxeo/job/nuxeo/job/master/637/testReport/junit/org.nuxeo.lib.stream.tests.pattern/TestPatternBoundedQueuingChronicle/Run_unit_tests___Run_postgresql_unit_tests___producersAndConsumersConcurrently/

      Slightly different than NXP-25830, because there is no more SCQIndexing.
      The problem happens on producers that didn't produce enough record because of concurrency problem.

        Attachments

        1. output.txt
          27 kB
          Benoit Delbosc

          Activity

            People

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

              Dates

              • Created:
                Updated:
                Resolved: