-
Type: Bug
-
Status: Resolved
-
Priority: Minor
-
Resolution: Won't Do
-
Affects Version/s: None
-
Fix Version/s: None
-
Component/s: Tests
-
Tags:
-
Team:PLATFORM
-
Story Points:3
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
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.