Uploaded image for project: 'Infinispan'
  1. Infinispan
  2. ISPN-2906

SyncCacheListenerTest.testSyncTxRepl failing randomly

This issue belongs to an archived project. You can view it, but you can't modify it. Learn more

      testSyncTxRepl(org.infinispan.replication.SyncCacheListenerTest)  Time elapsed: 0.04 sec  <<< FAILURE!
      java.lang.AssertionError: "age" obtained from cache2 must be non-null 
      	at org.testng.AssertJUnit.fail(AssertJUnit.java:59)
      	at org.testng.AssertJUnit.assertTrue(AssertJUnit.java:24)
      	at org.testng.AssertJUnit.assertNotNull(AssertJUnit.java:267)
      	at org.infinispan.replication.SyncCacheListenerTest.testSyncTxRepl(SyncCacheListenerTest.java:99)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:601)
      	at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:80)
      	at org.testng.internal.Invoker.invokeMethod(Invoker.java:714)
      	at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901)
      	at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231)
      	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:334)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
      	at java.lang.Thread.run(Thread.java:722)

            [ISPN-2906] SyncCacheListenerTest.testSyncTxRepl failing randomly

            It used to fail quite consistently up to 30 April[1], then no failure. I assume the test os fixed now indirectly, please reopen if needed.

            [1] http://ci.infinispan.org/project.html?testNameId=5143522628130447702&tab=testDetails&projectId=project2&page=14

            Mircea Markus (Inactive) added a comment - It used to fail quite consistently up to 30 April [1] , then no failure. I assume the test os fixed now indirectly, please reopen if needed. [1] http://ci.infinispan.org/project.html?testNameId=5143522628130447702&tab=testDetails&projectId=project2&page=14

            Failed again with 'java.lang.AssertionError: "age" obtained from cache2 must be non-null'. New trace log attached.

            Adrian Nistor (Inactive) added a comment - Failed again with 'java.lang.AssertionError: "age" obtained from cache2 must be non-null'. New trace log attached.

            I've seen it fail again on master with age==null but unfortunately I only have debug log, no trace .

            Adrian Nistor (Inactive) added a comment - I've seen it fail again on master with age==null but unfortunately I only have debug log, no trace .

            galderz I can see a GetKeyValueCommand returning null and right before that a message is logged:

            2013-03-11 12:57:45,988 285839 TRACE [org.infinispan.interceptors.ReplicationInterceptor] (testng-SyncCacheListenerTest:cache) Not doing a remote get for key age since entry is mapped to current node (SyncCacheListenerTest-NodeB-23486) or is in L1. Owners are [SyncCacheListenerTest-NodeA-26028, SyncCacheListenerTest-NodeB-23486]
            

            I think this was caused by ISPN-2777 which was resolved 3 days before you created this issue. Did you run on latest? Why do you think this is a TX issue.

            I tried to reproduce this but could not get the same type of failure. Instead I got a failure due to suspect exception on prepare aborting the tx which is actually the same as ISPN-2402.

            Can you still reproduce this on latest code? Please add a fresh log if so. Thanks!

            Adrian Nistor (Inactive) added a comment - galderz I can see a GetKeyValueCommand returning null and right before that a message is logged: 2013-03-11 12:57:45,988 285839 TRACE [org.infinispan.interceptors.ReplicationInterceptor] (testng-SyncCacheListenerTest:cache) Not doing a remote get for key age since entry is mapped to current node (SyncCacheListenerTest-NodeB-23486) or is in L1. Owners are [SyncCacheListenerTest-NodeA-26028, SyncCacheListenerTest-NodeB-23486] I think this was caused by ISPN-2777 which was resolved 3 days before you created this issue. Did you run on latest? Why do you think this is a TX issue. I tried to reproduce this but could not get the same type of failure. Instead I got a failure due to suspect exception on prepare aborting the tx which is actually the same as ISPN-2402 . Can you still reproduce this on latest code? Please add a fresh log if so. Thanks!

            Mircea, can you look into this? It's transactional stuff. For some reason in the end the cache does not contain the expected entry. There's a TRACE log attached from the testsuite. Needs grepping by test name.

            Galder Zamarreño added a comment - Mircea, can you look into this? It's transactional stuff. For some reason in the end the cache does not contain the expected entry. There's a TRACE log attached from the testsuite. Needs grepping by test name.

              pruivo@redhat.com Pedro Ruivo
              rh-ee-galder Galder Zamarreño
              Archiver:
              rhn-support-adongare Amol Dongare

                Created:
                Updated:
                Resolved:
                Archived: