You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@geode.apache.org by "Dan Smith (JIRA)" <ji...@apache.org> on 2015/09/08 19:57:46 UTC

[jira] [Created] (GEODE-314) Failure from PartitionManagerDUnitTest.testConcurrent in CI

Dan Smith created GEODE-314:
-------------------------------

             Summary: Failure from PartitionManagerDUnitTest.testConcurrent in CI
                 Key: GEODE-314
                 URL: https://issues.apache.org/jira/browse/GEODE-314
             Project: Geode
          Issue Type: Bug
            Reporter: Dan Smith
            Assignee: Bruce Schuchardt


This test failed in CI

https://builds.apache.org/job/Geode-nightly/208/testReport/junit/com.gemstone.gemfire.cache.partition/PartitionManagerDUnitTest/testConcurrent/

{noformat}
java.lang.Exception: An exception occured during async invocation
	at dunit.AsyncInvocation.getResult(AsyncInvocation.java:186)
	at com.gemstone.gemfire.cache.partition.PartitionManagerDUnitTest.testConcurrent(PartitionManagerDUnitTest.java:414)
	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:606)
	at junit.framework.TestCase.runTest(TestCase.java:176)
	at junit.framework.TestCase.runBare(TestCase.java:141)
	at junit.framework.TestResult$1.protect(TestResult.java:122)
	at junit.framework.TestResult.runProtected(TestResult.java:142)
	at junit.framework.TestResult.run(TestResult.java:125)
	at junit.framework.TestCase.run(TestCase.java:129)
	at junit.framework.TestSuite.runTest(TestSuite.java:252)
	at junit.framework.TestSuite.run(TestSuite.java:247)
	at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:86)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:86)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:49)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:64)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:50)
	at sun.reflect.GeneratedMethodAccessor14.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.messaging.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
	at org.gradle.messaging.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
	at com.sun.proxy.$Proxy2.processTestClass(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:106)
	at sun.reflect.GeneratedMethodAccessor13.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.messaging.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:360)
	at org.gradle.internal.concurrent.DefaultExecutorFactory$StoppableExecutorImpl$1.run(DefaultExecutorFactory.java:64)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
Caused by: dunit.RMIException: While invoking com.gemstone.gemfire.cache.partition.PartitionManagerDUnitTest$17.run in VM 1 running on Host asf902.gq1.ygridcore.net with 4 VMs
	at dunit.VM.invoke(VM.java:359)
	at dunit.VM$2.run(VM.java:211)
	at java.lang.Thread.run(Thread.java:745)
	at dunit.AsyncInvocation.run(AsyncInvocation.java:200)
Caused by: com.gemstone.gemfire.InternalGemFireError: recordVersion invoked for a local version tag that is higher than our local version. rvv=RegionVersionVector{rv0 gc0}@1277419153, tag={v1; rv1; mbr=asf902<v1300>:34949; time=1441600063882; remote} region /__PR/_B__region1_5
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.internal.Assert.throwError(Assert.java:82)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.internal.Assert.fail(Assert.java:57)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.internal.cache.versions.RegionVersionVector.recordVersion(RegionVersionVector.java:586)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.internal.cache.InitialImageOperation.processChunk(InitialImageOperation.java:1005)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.internal.cache.InitialImageOperation$ImageProcessor.process(InitialImageOperation.java:1314)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.ReplyMessage.process(ReplyMessage.java:222)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.internal.cache.InitialImageOperation$ImageReplyMessage.process(InitialImageOperation.java:2808)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.ReplyMessage.dmProcess(ReplyMessage.java:203)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.ReplyMessage.process(ReplyMessage.java:192)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.DistributionMessage.scheduleAction(DistributionMessage.java:371)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.DistributionMessage$1.run(DistributionMessage.java:442)
	at Remote Member 'asf902(19609)<v1300>:34949' in java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at Remote Member 'asf902(19609)<v1300>:34949' in java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.DistributionManager.runUntilShutdown(DistributionManager.java:692)
	at Remote Member 'asf902(19609)<v1300>:34949' in com.gemstone.gemfire.distributed.internal.DistributionManager$5$1.run(DistributionManager.java:1000)
	at Remote Member 'asf902(19609)<v1300>:34949' in java.lang.Thread.run(Thread.java:745)
	at com.gemstone.gemfire.distributed.internal.ReplyException.handleAsUnexpected(ReplyException.java:79)
	at com.gemstone.gemfire.internal.cache.InitialImageOperation.getFromOne(InitialImageOperation.java:530)
	at com.gemstone.gemfire.internal.cache.DistributedRegion.getInitialImageAndRecovery(DistributedRegion.java:1429)
	at com.gemstone.gemfire.internal.cache.DistributedRegion.initialize(DistributedRegion.java:1215)
	at com.gemstone.gemfire.internal.cache.BucketRegion.initialize(BucketRegion.java:290)
	at com.gemstone.gemfire.internal.cache.LocalRegion.createSubregion(LocalRegion.java:1059)
	at com.gemstone.gemfire.internal.cache.PartitionedRegionDataStore.createBucketRegion(PartitionedRegionDataStore.java:800)
	at com.gemstone.gemfire.internal.cache.PartitionedRegionDataStore.grabFreeBucket(PartitionedRegionDataStore.java:477)
	at com.gemstone.gemfire.internal.cache.PartitionedRegionDataStore.grabBucket(PartitionedRegionDataStore.java:3013)
	at com.gemstone.gemfire.internal.cache.PRHARedundancyProvider.createBackupBucketOnMember(PRHARedundancyProvider.java:1140)
	at com.gemstone.gemfire.cache.partition.PartitionManager.createBucket(PartitionManager.java:249)
	at com.gemstone.gemfire.cache.partition.PartitionManager.createPrimaryBucket(PartitionManager.java:200)
	at com.gemstone.gemfire.cache.partition.PartitionManagerDUnitTest$17.run(PartitionManagerDUnitTest.java:372)
	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:606)
	at hydra.MethExecutor.executeObject(MethExecutor.java:258)
	at dunit.standalone.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:67)
	at sun.reflect.GeneratedMethodAccessor8.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:322)
	at sun.rmi.transport.Transport$1.run(Transport.java:177)
	at sun.rmi.transport.Transport$1.run(Transport.java:174)
	at java.security.AccessController.doPrivileged(Native Method)
	at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:556)
	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:811)
	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:670)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)


[setup] START TEST PartitionManagerDUnitTest.testConcurrent


[vm_2][info 2015/09/07 04:27:52.264 UTC <RMI TCP Connection(90)-67.195.81.146> tid=0x29e4] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args
[vm_2]
[vm_2][info 2015/09/07 04:27:52.265 UTC <RMI TCP Connection(90)-67.195.81.146> tid=0x29e4] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)
[vm_2]
[vm_2][info 2015/09/07 04:27:52.269 UTC <RMI TCP Connection(90)-67.195.81.146> tid=0x29e4] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args
[vm_2]
[vm_2][info 2015/09/07 04:27:52.269 UTC <RMI TCP Connection(90)-67.195.81.146> tid=0x29e4] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)
[vm_2]
[locator][info 2015/09/07 04:27:52.271 UTC <RMI TCP Connection(22)-67.195.81.146> tid=0x11] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@57f08a50
[locator]
[locator][info 2015/09/07 04:27:52.271 UTC <RMI TCP Connection(22)-67.195.81.146> tid=0x11] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@57f08a50 (took 0 ms)
[locator]
{noformat}

There was an old GemFire bug that looked similar that is now closed - 50566



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)