Anton Vinogradov created IGNITE-1601: ----------------------------------------
Summary: [Test Failed] GridCacheNearTxExceptionSelfTest.testTransformBackup Key: IGNITE-1601 URL: https://issues.apache.org/jira/browse/IGNITE-1601 Project: Ignite Issue Type: Test Affects Versions: ignite-1.5 Reporter: Anton Vinogradov Priority: Blocker Fix For: ignite-1.5 There is no guarantee that test failure will be produced each test run, please analyze logs in case of reproduction problem: junit.framework.AssertionFailedError: Unexpected entry for grid [i=2, entry=GridNearCacheEntry [topVer=3, dhtVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], part=5, super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=CacheObjectImpl [val=1, hasValBytes=true], startVer=1443635677720, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=3, globalTime=1443635682301, order=1443635677719], hash=-871541811, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null, rmts=[GridCacheMvccCandidate [nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], timeout=0, ts=1443635682299, threadId=80062, id=6177849, topVer=AffinityTopologyVersion [topVer=-1, minorTopVer=0], reentry=null, otherNodeId=null, otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, key=KeyCacheObjectImpl [val=5, hasValBytes=true], masks=local=0|owner=1|ready=0|reentry=0|used=1|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0, prevVer=null, nextVer=null]]]], flags=0]]]] at junit.framework.Assert.fail(Assert.java:57) at junit.framework.Assert.assertTrue(Assert.java:22) at junit.framework.Assert.assertFalse(Assert.java:39) at junit.framework.TestCase.assertFalse(TestCase.java:210) at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest.checkUnlocked(IgniteTxExceptionAbstractSelfTest.java:396) at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest.checkTransform(IgniteTxExceptionAbstractSelfTest.java:480) at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest.testTransformBackup(IgniteTxExceptionAbstractSelfTest.java:234) 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 org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665) at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111) at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603) ------- Stdout: ------- [20:54:42,285][INFO ][main][root] >>> Starting test: testTransformBackup <<< [20:54:42,286][INFO ][test-runner][root] Going to transform: 2 [20:54:42,298][INFO ][test-runner][root] Caught expected exception: class org.apache.ignite.transactions.TransactionHeuristicException: Commit produced a runtime exception (all transaction entries will be invalidated): GridDhtTxRemote[id=a1a446f1051-0348ff9e-0000-0002-0000-01501f645bf8, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=COMMITTING, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] [20:54:42,298][INFO ][test-runner][root] Check key: 2 [20:54:42,298][INFO ][test-runner][root] Entry: null [20:54:42,299][INFO ][test-runner][root] Dht entry: GridDhtCacheEntry [rdrs=[], locPart=GridDhtLocalPartition [id=2, mapPubSize=0, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=2], state=OWNING, reservations=0, empty=false, createTime=09/30/2015 20:54:35, mapPubSize=0], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], val=null, startVer=1443635676707, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682296, order=1443635677722], hash=821347078, extras=null, flags=3]]] [20:54:42,299][INFO ][test-runner][root] Entry: null [20:54:42,299][INFO ][test-runner][root] Dht entry: GridDhtCacheEntry [rdrs=[], locPart=GridDhtLocalPartition [id=2, mapPubSize=0, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=2], state=OWNING, reservations=0, empty=false, createTime=09/30/2015 20:54:35, mapPubSize=0], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], val=null, startVer=1443635676707, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682280, order=1443635677702], hash=821347078, extras=null, flags=3]]] [20:54:42,299][INFO ][test-runner][root] Entry: null [20:54:42,299][INFO ][test-runner][root] Dht entry: null [20:54:42,299][INFO ][test-runner][root] Put key: 5 [20:54:42,301][INFO ][test-runner][root] Going to transform: 5 [20:54:42,303][INFO ][test-runner][root] Caught expected exception: class org.apache.ignite.transactions.TransactionHeuristicException: Commit produced a runtime exception (all transaction entries will be invalidated): GridDhtTxRemote[id=32a446f1051-0348ff9e-0000-0002-0000-01501f645bfd, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=COMMITTING, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] [20:54:42,303][INFO ][test-runner][root] Check key: 5 [20:54:42,304][INFO ][test-runner][root] Entry: GridNearCacheEntry [topVer=3, dhtVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], part=5, super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=CacheObjectImpl [val=1, hasValBytes=true], startVer=1443635677731, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], hash=-871541811, extras=null, flags=0]]] [20:54:42,304][INFO ][test-runner][root] Dht entry: GridDhtCacheEntry [rdrs=[], locPart=GridDhtLocalPartition [id=5, mapPubSize=1, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=1], state=OWNING, reservations=0, empty=false, createTime=09/30/2015 20:54:35, mapPubSize=1], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=null, startVer=1443635676721, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], hash=-871541811, extras=null, flags=2]]] [20:54:42,304][INFO ][test-runner][root] Entry: null [20:54:42,304][INFO ][test-runner][root] Dht entry: GridDhtCacheEntry [rdrs=[ReaderId [nodeId=20c863f0-057b-47b8-a807-aa3b20e15002, msgId=6116062, txFut=null]], locPart=GridDhtLocalPartition [id=5, mapPubSize=1, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=1], state=OWNING, reservations=0, empty=false, createTime=09/30/2015 20:54:35, mapPubSize=1], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=CacheObjectImpl [val=1, hasValBytes=true], startVer=1443635676721, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], hash=-871541811, extras=null, flags=2]]] [20:54:42,304][INFO ][test-runner][root] Entry: GridNearCacheEntry [topVer=3, dhtVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], part=5, super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=CacheObjectImpl [val=1, hasValBytes=true], startVer=1443635677720, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=3, globalTime=1443635682301, order=1443635677719], hash=-871541811, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null, rmts=[GridCacheMvccCandidate [nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], timeout=0, ts=1443635682299, threadId=80062, id=6177849, topVer=AffinityTopologyVersion [topVer=-1, minorTopVer=0], reentry=null, otherNodeId=null, otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, key=KeyCacheObjectImpl [val=5, hasValBytes=true], masks=local=0|owner=1|ready=0|reentry=0|used=1|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0, prevVer=null, nextVer=null]]]], flags=0]]] [20:54:42,306][INFO ][main][root] >>> Stopping test: testTransformBackup in 21 ms <<< ------- Stderr: ------- [20:54:42,297][ERROR][ignite-#62809%sys-near.GridCacheNearTxExceptionSelfTest0%][GridNearTxLocal] Commit failed. class org.apache.ignite.internal.transactions.IgniteTxHeuristicCheckedException: Commit produced a runtime exception (all transaction entries will be invalidated): GridDhtTxRemote[id=a1a446f1051-0348ff9e-0000-0002-0000-01501f645bf8, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=COMMITTING, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:672) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commit(GridDistributedTxRemoteAdapter.java:723) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.finish(IgniteTxHandler.java:1027) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.processDhtTxPrepareRequest(IgniteTxHandler.java:809) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:138) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:136) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:508) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:274) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:198) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:77) at org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:160) at org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811) at org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106) at org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774) 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: class org.apache.ignite.spi.IgniteSpiException: Test exception. at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest$TestIndexingSpi.store(IgniteTxExceptionAbstractSelfTest.java:646) at org.apache.ignite.internal.managers.indexing.GridIndexingManager.store(GridIndexingManager.java:101) at org.apache.ignite.internal.processors.query.GridQueryProcessor.store(GridQueryProcessor.java:482) at org.apache.ignite.internal.processors.cache.query.GridCacheQueryManager.store(GridCacheQueryManager.java:408) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.updateIndex(GridCacheMapEntry.java:3533) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.innerSet(GridCacheMapEntry.java:1098) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:580) ... 16 more [20:54:42,297][WARN ][ignite-#62809%sys-near.GridCacheNearTxExceptionSelfTest0%][IgniteTxHandler] Failed to commit transaction (all transaction entries were invalidated): GridDhtTxRemote[id=a1a446f1051-0348ff9e-0000-0002-0000-01501f645bf8, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=UNKNOWN, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] [20:54:42,298][ERROR][ignite-#62863%sys-near.GridCacheNearTxExceptionSelfTest1%][IgniteTxHandler] Failed to prepare DHT transaction: GridDhtTxLocal [nearNodeId=0076131e-f2eb-4d52-bf16-dd2a9687e000, nearFutId=262dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearMiniId=362dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearFinFutId=772dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearFinMiniId=872dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearXidVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=1, globalTime=1443635682286, order=1443635677714], super=GridDhtTxLocalAdapter [dhtThreadId=79981, needsCompletedVers=true, nearOnOriginatingNode=true, nearNodes=[], dhtNodes=[0076131e-f2eb-4d52-bf16-dd2a9687e000], explicitLock=false, super=IgniteTxLocalAdapter [txMap={IgniteTxKey [key=KeyCacheObjectImpl [val=2, hasValBytes=true], cacheId=1]=IgniteTxEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], cacheId=1, txKey=IgniteTxKey [key=KeyCacheObjectImpl [val=2, hasValBytes=true], cacheId=1], val=[op=TRANSFORM, val=null], prevVal=[op=NOOP, val=null], entryProcessorsCol=[IgniteBiTuple [val1=o.a.i.i.processors.cache.IgniteTxExceptionAbstractSelfTest$2$1@768a1f4e, val2=[Ljava.lang.Object;@660d76ae]], entryProcessorCalcVal=UserCacheObjectImpl [val=2, hasValBytes=false], ttl=-1, conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null, filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], locPart=GridDhtLocalPartition [id=2, mapPubSize=0, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=2], state=OWNING, reservations=0, empty=false, createTime=09/30/2015 20:54:35, mapPubSize=0], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], val=null, startVer=1443635676707, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682280, order=1443635677702], hash=821347078, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=[GridCacheMvccCandidate [nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682296, order=1443635677722], timeout=0, ts=1443635682289, threadId=80132, id=6177841, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, otherNodeId=0076131e-f2eb-4d52-bf16-dd2a9687e000, otherVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=1, globalTime=1443635682286, order=1443635677714], mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, key=KeyCacheObjectImpl [val=2, hasValBytes=true], masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=1|dht_local=1|near_local=0|removed=0, prevVer=null, nextVer=null]], rmts=null]], flags=3]]], prepared=true, locked=false, nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=0, xidVer=null]}, completedBase=null, sndTransformedVals=false, super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682296, order=1443635677722], writeVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682296, order=1443635677723], implicit=true, implicitSingle=true, loc=true, threadId=80132, startTime=1443635682289, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, startVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682296, order=1443635677722], endVer=null, isolation=READ_COMMITTED, concurrency=OPTIMISTIC, timeout=0, sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE, preparing=true, invalidParts={}, state=MARKED_ROLLBACK, timedOut=false, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], duration=0ms, onePhaseCommit=true], size=1]]] class org.apache.ignite.internal.transactions.IgniteTxHeuristicCheckedException: Commit produced a runtime exception (all transaction entries will be invalidated): GridDhtTxRemote[id=a1a446f1051-0348ff9e-0000-0002-0000-01501f645bf8, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=COMMITTING, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:672) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commit(GridDistributedTxRemoteAdapter.java:723) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.finish(IgniteTxHandler.java:1027) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.processDhtTxPrepareRequest(IgniteTxHandler.java:809) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:138) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:136) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:508) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:274) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:198) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:77) at org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:160) at org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811) at org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106) at org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774) 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: class org.apache.ignite.spi.IgniteSpiException: Test exception. at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest$TestIndexingSpi.store(IgniteTxExceptionAbstractSelfTest.java:646) at org.apache.ignite.internal.managers.indexing.GridIndexingManager.store(GridIndexingManager.java:101) at org.apache.ignite.internal.processors.query.GridQueryProcessor.store(GridQueryProcessor.java:482) at org.apache.ignite.internal.processors.cache.query.GridCacheQueryManager.store(GridCacheQueryManager.java:408) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.updateIndex(GridCacheMapEntry.java:3533) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.innerSet(GridCacheMapEntry.java:1098) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:580) ... 16 more [20:54:42,302][ERROR][ignite-#62805%sys-near.GridCacheNearTxExceptionSelfTest0%][GridNearTxLocal] Commit failed. class org.apache.ignite.internal.transactions.IgniteTxHeuristicCheckedException: Commit produced a runtime exception (all transaction entries will be invalidated): GridDhtTxRemote[id=32a446f1051-0348ff9e-0000-0002-0000-01501f645bfd, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=COMMITTING, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:672) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commit(GridDistributedTxRemoteAdapter.java:723) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.finish(IgniteTxHandler.java:1027) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.processDhtTxPrepareRequest(IgniteTxHandler.java:809) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:138) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:136) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:508) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:274) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:198) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:77) at org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:160) at org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811) at org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106) at org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774) 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: class org.apache.ignite.spi.IgniteSpiException: Test exception. at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest$TestIndexingSpi.store(IgniteTxExceptionAbstractSelfTest.java:646) at org.apache.ignite.internal.managers.indexing.GridIndexingManager.store(GridIndexingManager.java:101) at org.apache.ignite.internal.processors.query.GridQueryProcessor.store(GridQueryProcessor.java:482) at org.apache.ignite.internal.processors.cache.query.GridCacheQueryManager.store(GridCacheQueryManager.java:408) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.updateIndex(GridCacheMapEntry.java:3533) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.innerSet(GridCacheMapEntry.java:1098) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:580) ... 16 more [20:54:42,302][WARN ][ignite-#62805%sys-near.GridCacheNearTxExceptionSelfTest0%][IgniteTxHandler] Failed to commit transaction (all transaction entries were invalidated): GridDhtTxRemote[id=32a446f1051-0348ff9e-0000-0002-0000-01501f645bfd, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=UNKNOWN, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] [20:54:42,303][ERROR][ignite-#62873%sys-near.GridCacheNearTxExceptionSelfTest1%][IgniteTxHandler] Failed to prepare DHT transaction: GridDhtTxLocal [nearNodeId=0076131e-f2eb-4d52-bf16-dd2a9687e000, nearFutId=792dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearMiniId=892dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearFinFutId=4a2dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearFinMiniId=5a2dfa32051-ba6a5651-6ec8-48dc-9d55-1ed91b0759f5, nearXidVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=1, globalTime=1443635682301, order=1443635677730], super=GridDhtTxLocalAdapter [dhtThreadId=79987, needsCompletedVers=true, nearOnOriginatingNode=true, nearNodes=[20c863f0-057b-47b8-a807-aa3b20e15002], dhtNodes=[0076131e-f2eb-4d52-bf16-dd2a9687e000], explicitLock=false, super=IgniteTxLocalAdapter [txMap={IgniteTxKey [key=KeyCacheObjectImpl [val=5, hasValBytes=true], cacheId=1]=IgniteTxEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], cacheId=1, txKey=IgniteTxKey [key=KeyCacheObjectImpl [val=5, hasValBytes=true], cacheId=1], val=[op=TRANSFORM, val=null], prevVal=[op=NOOP, val=null], entryProcessorsCol=[IgniteBiTuple [val1=o.a.i.i.processors.cache.IgniteTxExceptionAbstractSelfTest$2$1@531fb8b, val2=[Ljava.lang.Object;@14796557]], entryProcessorCalcVal=UserCacheObjectImpl [val=2, hasValBytes=false], ttl=-1, conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null, filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry [rdrs=[ReaderId [nodeId=20c863f0-057b-47b8-a807-aa3b20e15002, msgId=6116062, txFut=null]], locPart=GridDhtLocalPartition [id=5, mapPubSize=1, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=1], state=OWNING, reservations=0, empty=false, createTime=09/30/2015 20:54:35, mapPubSize=1], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=CacheObjectImpl [val=1, hasValBytes=true], startVer=1443635676721, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], hash=-871541811, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=[GridCacheMvccCandidate [nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], timeout=0, ts=1443635682299, threadId=80132, id=6177847, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, otherNodeId=0076131e-f2eb-4d52-bf16-dd2a9687e000, otherVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=1, globalTime=1443635682301, order=1443635677730], mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, key=KeyCacheObjectImpl [val=5, hasValBytes=true], masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=1|dht_local=1|near_local=0|removed=0, prevVer=null, nextVer=null]], rmts=null]], flags=2]]], prepared=true, locked=false, nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=0, xidVer=null]}, completedBase=null, sndTransformedVals=false, super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], writeVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677732], implicit=true, implicitSingle=true, loc=true, threadId=80132, startTime=1443635682299, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, startVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], endVer=null, isolation=READ_COMMITTED, concurrency=OPTIMISTIC, timeout=0, sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE, preparing=true, invalidParts={}, state=MARKED_ROLLBACK, timedOut=false, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], duration=0ms, onePhaseCommit=true], size=1]]] class org.apache.ignite.internal.transactions.IgniteTxHeuristicCheckedException: Commit produced a runtime exception (all transaction entries will be invalidated): GridDhtTxRemote[id=32a446f1051-0348ff9e-0000-0002-0000-01501f645bfd, concurrency=OPTIMISTIC, isolation=READ_COMMITTED, state=COMMITTING, invalidate=false, rollbackOnly=false, nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, duration=0] at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:672) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commit(GridDistributedTxRemoteAdapter.java:723) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.finish(IgniteTxHandler.java:1027) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.processDhtTxPrepareRequest(IgniteTxHandler.java:809) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:138) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$5.apply(IgniteTxHandler.java:136) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:508) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:274) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:198) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:77) at org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:160) at org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811) at org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106) at org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774) 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: class org.apache.ignite.spi.IgniteSpiException: Test exception. at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest$TestIndexingSpi.store(IgniteTxExceptionAbstractSelfTest.java:646) at org.apache.ignite.internal.managers.indexing.GridIndexingManager.store(GridIndexingManager.java:101) at org.apache.ignite.internal.processors.query.GridQueryProcessor.store(GridQueryProcessor.java:482) at org.apache.ignite.internal.processors.cache.query.GridCacheQueryManager.store(GridCacheQueryManager.java:408) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.updateIndex(GridCacheMapEntry.java:3533) at org.apache.ignite.internal.processors.cache.GridCacheMapEntry.innerSet(GridCacheMapEntry.java:1098) at org.apache.ignite.internal.processors.cache.distributed.GridDistributedTxRemoteAdapter.commitIfLocked(GridDistributedTxRemoteAdapter.java:580) ... 16 more [20:54:42,304][ERROR][main][root] Test failed. junit.framework.AssertionFailedError: Unexpected entry for grid [i=2, entry=GridNearCacheEntry [topVer=3, dhtVer=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682299, order=1443635677727], part=5, super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=5, hasValBytes=true], val=CacheObjectImpl [val=1, hasValBytes=true], startVer=1443635677720, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=3, globalTime=1443635682301, order=1443635677719], hash=-871541811, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null, rmts=[GridCacheMvccCandidate [nodeId=100c7cf0-d287-448c-b6d8-c2d96bda3001, ver=GridCacheVersion [topVer=55115678, nodeOrderDrId=2, globalTime=1443635682301, order=1443635677731], timeout=0, ts=1443635682299, threadId=80062, id=6177849, topVer=AffinityTopologyVersion [topVer=-1, minorTopVer=0], reentry=null, otherNodeId=null, otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, key=KeyCacheObjectImpl [val=5, hasValBytes=true], masks=local=0|owner=1|ready=0|reentry=0|used=1|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0, prevVer=null, nextVer=null]]]], flags=0]]]] at junit.framework.Assert.fail(Assert.java:57) at junit.framework.Assert.assertTrue(Assert.java:22) at junit.framework.Assert.assertFalse(Assert.java:39) at junit.framework.TestCase.assertFalse(TestCase.java:210) at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest.checkUnlocked(IgniteTxExceptionAbstractSelfTest.java:396) at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest.checkTransform(IgniteTxExceptionAbstractSelfTest.java:480) at org.apache.ignite.internal.processors.cache.IgniteTxExceptionAbstractSelfTest.testTransformBackup(IgniteTxExceptionAbstractSelfTest.java:234) 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 org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665) at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111) at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603) -- This message was sent by Atlassian JIRA (v6.3.4#6332)