Bug 987927

Vitalii Chepeliuk <vchepeli> made a comment on jira ISPN-3365

Added trace log for lucene-v3 module

Anna Manukyan <amanukya> updated the status of jira ISPN-3365 to Reopened

Anna Manukyan <amanukya> made a comment on jira ISPN-3365

The issue seems to appear again - very randomly but it is there.

For JDG 6.2.0.DR4 the test failed once for Windows Machine with Oracle JDK7 and once for the RHEL7 x64 Oracle JDK7.

For both cases the assertion error is the same:

{code}
java.lang.AssertionError: 
	at org.testng.AssertJUnit.fail(AssertJUnit.java:59)
	at org.testng.AssertJUnit.assertTrue(AssertJUnit.java:24)
	at org.testng.AssertJUnit.assertFalse(AssertJUnit.java:41)
	at org.testng.AssertJUnit.assertFalse(AssertJUnit.java:49)
	at org.infinispan.lucene.DatabaseStoredIndexTest.indexWasStored(DatabaseStoredIndexTest.java:130)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:80)
	at org.testng.internal.Invoker.invokeMethod(Invoker.java:715)
	at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:907)
	at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1237)
	at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)
	at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
	at org.testng.TestRunner.privateRun(TestRunner.java:767)
	at org.testng.TestRunner.run(TestRunner.java:617)
	at org.testng.SuiteRunner.runTest(SuiteRunner.java:334)
	at org.testng.SuiteRunner.access$000(SuiteRunner.java:37)
	at org.testng.SuiteRunner$SuiteWorker.run(SuiteRunner.java:368)
	at org.testng.internal.thread.ThreadUtil$2.call(ThreadUtil.java:64)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
{code}

But the log view for both cases is different. I will attach the trace logs for both cases.

Anna Manukyan <amanukya> made a comment on jira ISPN-3365

Unfortunately the trace logs for the lucene/ module are not available. So the only logs that I can provide are:

For Windows machine during the test execution the following output was printed:

{code}
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.frq|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.fdt|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.tis|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:segments_4|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.nrm|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.fnm|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3_1.del|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.prx|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.fdx|RL|testing index
Key found in store, shouldn't have persisted this or should have cleaned up all readlocks on directory close:_3.tii|RL|testing index
{code}

For RHEL machine the following output was printed:

{code}
Failure on key[segments_4|0|16384|testing index] expected value:
	[B0xfffffff500000141..[824]	actual value:
	null
{code}

Anna Manukyan <amanukya> made a comment on jira ISPN-3365

The issue seems to appear again - very randomly but it is there.

For JDG 6.2.0.DR4 the test failed once for Windows Machine with Oracle JDK7 and once for the RHEL6 x64 Oracle JDK7.

For both cases the assertion error is the same:

{code}
java.lang.AssertionError: 
	at org.testng.AssertJUnit.fail(AssertJUnit.java:59)
	at org.testng.AssertJUnit.assertTrue(AssertJUnit.java:24)
	at org.testng.AssertJUnit.assertFalse(AssertJUnit.java:41)
	at org.testng.AssertJUnit.assertFalse(AssertJUnit.java:49)
	at org.infinispan.lucene.DatabaseStoredIndexTest.indexWasStored(DatabaseStoredIndexTest.java:130)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:80)
	at org.testng.internal.Invoker.invokeMethod(Invoker.java:715)
	at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:907)
	at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1237)
	at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)
	at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
	at org.testng.TestRunner.privateRun(TestRunner.java:767)
	at org.testng.TestRunner.run(TestRunner.java:617)
	at org.testng.SuiteRunner.runTest(SuiteRunner.java:334)
	at org.testng.SuiteRunner.access$000(SuiteRunner.java:37)
	at org.testng.SuiteRunner$SuiteWorker.run(SuiteRunner.java:368)
	at org.testng.internal.thread.ThreadUtil$2.call(ThreadUtil.java:64)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
{code}

But the log view for both cases is different. I will attach the trace logs for both cases.

Now, the tests are failing constantly on windows2012x64bit && Oracle JDK6 machine. The failure message is the same.

The tests also fail for Open JDK6 && RHEL6_x64 environment.

Dan Berindei <dberinde> updated the status of jira ISPN-3365 to Resolved

Gustavo Fernandes <gustavo> updated the status of jira ISPN-3329 to Reopened

Galder Zamarreño <galder.zamarreno> updated the status of jira ISPN-3329 to Resolved

I'm seeing this fail with 6.4.1.ER2 on RHEL with IBM8 with Assertion error on at org.infinispan.lucene.DatabaseStoredIndexTest.indexWasStored(DatabaseStoredIndexTest.java:127) and standard output:

Failure on key[_3.nrm|0|1048576|testing index] expected value:
	[B0x4e524dff7c76	actual value:
	null
Failure on key[segments_4|0|1048576|testing index] expected value:
	[B0xfffffff50000014c..[780]	actual value:
	null
Failure on key[_3.prx|0|1048576|testing index] expected value:
	[B0x0006050302010400	actual value:
	null
[testng-DatabaseStoredIndexTest] Test indexWasStored(org.infinispan.lucene.DatabaseStoredIndexTest) failed.