[
https://issues.apache.org/jira/browse/IGNITE-8297?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16450471#comment-16450471
]
Andrey Kuznetsov commented on IGNITE-8297:
------------------------------------------
After commit [1] was merged to master, failures became very infrequent. But
still (typically after about 1 hour of successful launches) it can fail (more
details can be found in the full log attached) :
{noformat}
[2018-04-24 17:53:38,727][ERROR][main][root] Test failed.
class org.apache.ignite.internal.IgniteFutureTimeoutCheckedException: Timeout
was reached before computation completed.
at
org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:242)
at
org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
at
org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:151)
at
org.apache.ignite.internal.processors.cache.transactions.TxRollbackOnTimeoutTest.testRandomMixedTxConfigurations(TxRollbackOnTimeoutTest.java:472)
[2018-04-24 17:53:38,728][INFO ][main][root] >>> Stopping grid [name=client,
id=b5904b5a-90f1-4613-ad82-2074ca539a4a]
at sun.reflect.GeneratedMethodAccessor30.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at junit.framework.TestCase.runTest(TestCase.java:176)
at
org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:2080)
at
org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:140)
at
org.apache.ignite.testframework.junits.GridAbstractTest$5.run(GridAbstractTest.java:1995)
at java.lang.Thread.run(Thread.java:748)
[2018-04-24 17:54:24,491][WARN
][grid-timeout-worker-#12447%transactions.TxRollbackOnTimeoutNearCacheTest0%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:54:24.442,
tx=GridDhtTxLocal [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
nearFutId=9f6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04, nearMiniId=1,
nearFinFutId=null, nearFinMiniId=0, nearXidVer=GridCacheVersion
[topVer=136061548, order=1524581551807, nodeOrder=4],
super=GridDhtTxLocalAdapter [nearOnOriginatingNode=true, nearNodes=[],
dhtNodes=[aee10068-8399-4390-b186-e40275a00001,
283741a6-e6cf-4a9c-96ea-08a4af700002], explicitLock=false,
super=IgniteTxLocalAdapter [completedBase=null, sndTransformedVals=false,
depEnabled=false, txState=IgniteTxStateImpl [activeCacheIds=[3556498],
recovery=false, txMap=[IgniteTxEntry [key=KeyCacheObjectImpl [part=2, val=2,
hasValBytes=true], cacheId=3556498, txKey=IgniteTxKey [key=KeyCacheObjectImpl
[part=2, val=2, hasValBytes=true], cacheId=3556498], val=[op=UPDATE,
val=CacheObjectImpl [val=null, hasValBytes=true]], prevVal=[op=NOOP, val=null],
oldVal=[op=NOOP, val=null], entryProcessorsCol=null, ttl=-1,
conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null,
filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry
[rdrs=[ReaderId [nodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a, msgId=800207,
txFut=null]], part=2, super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581544734, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc
[locs=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15288, id=252832, topVer=AffinityTopologyVersion [topVer=4,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=GridCacheVersion [topVer=136061548, order=1524581551807, nodeOrder=4],
mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, serOrder=null,
key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]], rmts=null]], flags=2]]], prepared=1,
locked=false, nodeId=null, locMapped=false, expiryPlc=null,
transferExpiryPlc=false, flags=8, partUpdateCntr=0, serReadVer=null,
xidVer=null]]], super=IgniteTxAdapter [xidVer=GridCacheVersion
[topVer=136061548, order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion
[topVer=136061548, order=1524581551826, nodeOrder=1], implicit=false, loc=true,
threadId=15288, startTime=1524581604160,
nodeId=9ef1e430-586e-47db-977c-df1739400000, startVer=GridCacheVersion
[topVer=136061548, order=1524581551825, nodeOrder=1], endVer=null,
isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=60323ms,
onePhaseCommit=false], size=1]]]]
[2018-04-24 17:54:25,341][WARN
][grid-timeout-worker-#12512%transactions.TxRollbackOnTimeoutNearCacheTest2%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:54:25.339,
tx=GridDhtTxRemote [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
rmtFutId=af6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04,
nearXidVer=GridCacheVersion [topVer=136061548, order=1524581551807,
nodeOrder=4], storeWriteThrough=false, super=GridDistributedTxRemoteAdapter
[explicitVers=null, started=true, commitAllowed=0,
txState=IgniteTxRemoteSingleStateImpl [entry=IgniteTxEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], cacheId=3556498,
txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
cacheId=3556498], val=[op=UPDATE, val=CacheObjectImpl [val=null,
hasValBytes=true]], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null],
entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1, conflictVer=null,
explicitVer=null, dhtVer=null, filters=[], filtersPassed=false,
filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], part=2,
super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581551827, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null,
rmts=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15124, id=252833, topVer=AffinityTopologyVersion [topVer=-1,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null,
serOrder=null, key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=0|owner=0|ready=0|reentry=0|used=0|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]]]], flags=2]]], prepared=1, locked=false,
nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=8,
partUpdateCntr=0, serReadVer=null, xidVer=null]], skipCompletedVers=false,
super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=136061548,
order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion [topVer=136061548,
order=1524581551826, nodeOrder=1], implicit=false, loc=false, threadId=15124,
startTime=1524581604160, nodeId=9ef1e430-586e-47db-977c-df1739400000,
startVer=GridCacheVersion [topVer=136061548, order=1524581551826, nodeOrder=2],
endVer=null, isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=61179ms,
onePhaseCommit=false]]]]
[2018-04-24 17:54:25,731][WARN
][grid-timeout-worker-#12508%transactions.TxRollbackOnTimeoutNearCacheTest1%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:54:25.730,
tx=GridDhtTxRemote [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
rmtFutId=af6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04,
nearXidVer=GridCacheVersion [topVer=136061548, order=1524581551807,
nodeOrder=4], storeWriteThrough=false, super=GridDistributedTxRemoteAdapter
[explicitVers=null, started=true, commitAllowed=0,
txState=IgniteTxRemoteSingleStateImpl [entry=IgniteTxEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], cacheId=3556498,
txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
cacheId=3556498], val=[op=UPDATE, val=CacheObjectImpl [val=null,
hasValBytes=true]], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null],
entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1, conflictVer=null,
explicitVer=null, dhtVer=null, filters=[], filtersPassed=false,
filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], part=2,
super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581551825, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null,
rmts=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15108, id=252834, topVer=AffinityTopologyVersion [topVer=-1,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null,
serOrder=null, key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=0|owner=0|ready=0|reentry=0|used=0|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]]]], flags=2]]], prepared=1, locked=false,
nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=8,
partUpdateCntr=0, serReadVer=null, xidVer=null]], skipCompletedVers=false,
super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=136061548,
order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion [topVer=136061548,
order=1524581551826, nodeOrder=1], implicit=false, loc=false, threadId=15108,
startTime=1524581604160, nodeId=9ef1e430-586e-47db-977c-df1739400000,
startVer=GridCacheVersion [topVer=136061548, order=1524581551824, nodeOrder=3],
endVer=null, isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=61570ms,
onePhaseCommit=false]]]]
[2018-04-24 17:55:24,509][WARN
][grid-timeout-worker-#12447%transactions.TxRollbackOnTimeoutNearCacheTest0%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:55:24.507,
tx=GridDhtTxLocal [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
nearFutId=9f6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04, nearMiniId=1,
nearFinFutId=null, nearFinMiniId=0, nearXidVer=GridCacheVersion
[topVer=136061548, order=1524581551807, nodeOrder=4],
super=GridDhtTxLocalAdapter [nearOnOriginatingNode=true, nearNodes=[],
dhtNodes=[aee10068-8399-4390-b186-e40275a00001,
283741a6-e6cf-4a9c-96ea-08a4af700002], explicitLock=false,
super=IgniteTxLocalAdapter [completedBase=null, sndTransformedVals=false,
depEnabled=false, txState=IgniteTxStateImpl [activeCacheIds=[3556498],
recovery=false, txMap=[IgniteTxEntry [key=KeyCacheObjectImpl [part=2, val=2,
hasValBytes=true], cacheId=3556498, txKey=IgniteTxKey [key=KeyCacheObjectImpl
[part=2, val=2, hasValBytes=true], cacheId=3556498], val=[op=UPDATE,
val=CacheObjectImpl [val=null, hasValBytes=true]], prevVal=[op=NOOP, val=null],
oldVal=[op=NOOP, val=null], entryProcessorsCol=null, ttl=-1,
conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null,
filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry
[rdrs=[ReaderId [nodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a, msgId=800207,
txFut=null]], part=2, super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581544734, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc
[locs=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15288, id=252832, topVer=AffinityTopologyVersion [topVer=4,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=GridCacheVersion [topVer=136061548, order=1524581551807, nodeOrder=4],
mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, serOrder=null,
key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]], rmts=null]], flags=2]]], prepared=1,
locked=false, nodeId=null, locMapped=false, expiryPlc=null,
transferExpiryPlc=false, flags=8, partUpdateCntr=0, serReadVer=null,
xidVer=null]]], super=IgniteTxAdapter [xidVer=GridCacheVersion
[topVer=136061548, order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion
[topVer=136061548, order=1524581551826, nodeOrder=1], implicit=false, loc=true,
threadId=15288, startTime=1524581604160,
nodeId=9ef1e430-586e-47db-977c-df1739400000, startVer=GridCacheVersion
[topVer=136061548, order=1524581551825, nodeOrder=1], endVer=null,
isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=120347ms,
onePhaseCommit=false], size=1]]]]
[2018-04-24 17:55:25,349][WARN
][grid-timeout-worker-#12512%transactions.TxRollbackOnTimeoutNearCacheTest2%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:55:25.342,
tx=GridDhtTxRemote [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
rmtFutId=af6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04,
nearXidVer=GridCacheVersion [topVer=136061548, order=1524581551807,
nodeOrder=4], storeWriteThrough=false, super=GridDistributedTxRemoteAdapter
[explicitVers=null, started=true, commitAllowed=0,
txState=IgniteTxRemoteSingleStateImpl [entry=IgniteTxEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], cacheId=3556498,
txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
cacheId=3556498], val=[op=UPDATE, val=CacheObjectImpl [val=null,
hasValBytes=true]], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null],
entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1, conflictVer=null,
explicitVer=null, dhtVer=null, filters=[], filtersPassed=false,
filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], part=2,
super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581551827, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null,
rmts=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15124, id=252833, topVer=AffinityTopologyVersion [topVer=-1,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null,
serOrder=null, key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=0|owner=0|ready=0|reentry=0|used=0|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]]]], flags=2]]], prepared=1, locked=false,
nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=8,
partUpdateCntr=0, serReadVer=null, xidVer=null]], skipCompletedVers=false,
super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=136061548,
order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion [topVer=136061548,
order=1524581551826, nodeOrder=1], implicit=false, loc=false, threadId=15124,
startTime=1524581604160, nodeId=9ef1e430-586e-47db-977c-df1739400000,
startVer=GridCacheVersion [topVer=136061548, order=1524581551826, nodeOrder=2],
endVer=null, isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=121182ms,
onePhaseCommit=false]]]]
[2018-04-24 17:55:25,733][WARN
][grid-timeout-worker-#12508%transactions.TxRollbackOnTimeoutNearCacheTest1%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:55:25.731,
tx=GridDhtTxRemote [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
rmtFutId=af6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04,
nearXidVer=GridCacheVersion [topVer=136061548, order=1524581551807,
nodeOrder=4], storeWriteThrough=false, super=GridDistributedTxRemoteAdapter
[explicitVers=null, started=true, commitAllowed=0,
txState=IgniteTxRemoteSingleStateImpl [entry=IgniteTxEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], cacheId=3556498,
txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
cacheId=3556498], val=[op=UPDATE, val=CacheObjectImpl [val=null,
hasValBytes=true]], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null],
entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1, conflictVer=null,
explicitVer=null, dhtVer=null, filters=[], filtersPassed=false,
filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], part=2,
super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581551825, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null,
rmts=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15108, id=252834, topVer=AffinityTopologyVersion [topVer=-1,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null,
serOrder=null, key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=0|owner=0|ready=0|reentry=0|used=0|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]]]], flags=2]]], prepared=1, locked=false,
nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=8,
partUpdateCntr=0, serReadVer=null, xidVer=null]], skipCompletedVers=false,
super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=136061548,
order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion [topVer=136061548,
order=1524581551826, nodeOrder=1], implicit=false, loc=false, threadId=15108,
startTime=1524581604160, nodeId=9ef1e430-586e-47db-977c-df1739400000,
startVer=GridCacheVersion [topVer=136061548, order=1524581551824, nodeOrder=3],
endVer=null, isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=121571ms,
onePhaseCommit=false]]]]
[2018-04-24 17:57:24,520][WARN
][grid-timeout-worker-#12447%transactions.TxRollbackOnTimeoutNearCacheTest0%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:57:24.519,
tx=GridDhtTxLocal [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
nearFutId=9f6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04, nearMiniId=1,
nearFinFutId=null, nearFinMiniId=0, nearXidVer=GridCacheVersion
[topVer=136061548, order=1524581551807, nodeOrder=4],
super=GridDhtTxLocalAdapter [nearOnOriginatingNode=true, nearNodes=[],
dhtNodes=[aee10068-8399-4390-b186-e40275a00001,
283741a6-e6cf-4a9c-96ea-08a4af700002], explicitLock=false,
super=IgniteTxLocalAdapter [completedBase=null, sndTransformedVals=false,
depEnabled=false, txState=IgniteTxStateImpl [activeCacheIds=[3556498],
recovery=false, txMap=[IgniteTxEntry [key=KeyCacheObjectImpl [part=2, val=2,
hasValBytes=true], cacheId=3556498, txKey=IgniteTxKey [key=KeyCacheObjectImpl
[part=2, val=2, hasValBytes=true], cacheId=3556498], val=[op=UPDATE,
val=CacheObjectImpl [val=null, hasValBytes=true]], prevVal=[op=NOOP, val=null],
oldVal=[op=NOOP, val=null], entryProcessorsCol=null, ttl=-1,
conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null,
filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry
[rdrs=[ReaderId [nodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a, msgId=800207,
txFut=null]], part=2, super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581544734, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc
[locs=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15288, id=252832, topVer=AffinityTopologyVersion [topVer=4,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=GridCacheVersion [topVer=136061548, order=1524581551807, nodeOrder=4],
mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, serOrder=null,
key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]], rmts=null]], flags=2]]], prepared=1,
locked=false, nodeId=null, locMapped=false, expiryPlc=null,
transferExpiryPlc=false, flags=8, partUpdateCntr=0, serReadVer=null,
xidVer=null]]], super=IgniteTxAdapter [xidVer=GridCacheVersion
[topVer=136061548, order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion
[topVer=136061548, order=1524581551826, nodeOrder=1], implicit=false, loc=true,
threadId=15288, startTime=1524581604160,
nodeId=9ef1e430-586e-47db-977c-df1739400000, startVer=GridCacheVersion
[topVer=136061548, order=1524581551825, nodeOrder=1], endVer=null,
isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=240359ms,
onePhaseCommit=false], size=1]]]]
[2018-04-24 17:57:25,358][WARN
][grid-timeout-worker-#12512%transactions.TxRollbackOnTimeoutNearCacheTest2%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:57:25.350,
tx=GridDhtTxRemote [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
rmtFutId=af6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04,
nearXidVer=GridCacheVersion [topVer=136061548, order=1524581551807,
nodeOrder=4], storeWriteThrough=false, super=GridDistributedTxRemoteAdapter
[explicitVers=null, started=true, commitAllowed=0,
txState=IgniteTxRemoteSingleStateImpl [entry=IgniteTxEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], cacheId=3556498,
txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
cacheId=3556498], val=[op=UPDATE, val=CacheObjectImpl [val=null,
hasValBytes=true]], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null],
entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1, conflictVer=null,
explicitVer=null, dhtVer=null, filters=[], filtersPassed=false,
filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], part=2,
super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581551827, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null,
rmts=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15124, id=252833, topVer=AffinityTopologyVersion [topVer=-1,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null,
serOrder=null, key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=0|owner=0|ready=0|reentry=0|used=0|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]]]], flags=2]]], prepared=1, locked=false,
nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=8,
partUpdateCntr=0, serReadVer=null, xidVer=null]], skipCompletedVers=false,
super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=136061548,
order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion [topVer=136061548,
order=1524581551826, nodeOrder=1], implicit=false, loc=false, threadId=15124,
startTime=1524581604160, nodeId=9ef1e430-586e-47db-977c-df1739400000,
startVer=GridCacheVersion [topVer=136061548, order=1524581551826, nodeOrder=2],
endVer=null, isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=241190ms,
onePhaseCommit=false]]]]
[2018-04-24 17:57:25,740][WARN
][grid-timeout-worker-#12508%transactions.TxRollbackOnTimeoutNearCacheTest1%][diagnostic]
Found long running transaction [startTime=17:53:24.160, curTime=17:57:25.740,
tx=GridDhtTxRemote [nearNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
rmtFutId=af6ac08f261-48842a58-c398-43b6-9a7f-4ecc02c50d04,
nearXidVer=GridCacheVersion [topVer=136061548, order=1524581551807,
nodeOrder=4], storeWriteThrough=false, super=GridDistributedTxRemoteAdapter
[explicitVers=null, started=true, commitAllowed=0,
txState=IgniteTxRemoteSingleStateImpl [entry=IgniteTxEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], cacheId=3556498,
txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
cacheId=3556498], val=[op=UPDATE, val=CacheObjectImpl [val=null,
hasValBytes=true]], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null],
entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1, conflictVer=null,
explicitVer=null, dhtVer=null, filters=[], filtersPassed=false,
filtersSet=false, entry=GridDhtCacheEntry [rdrs=[], part=2,
super=GridDistributedCacheEntry [super=GridCacheMapEntry
[key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true], val=CacheObjectImpl
[val=null, hasValBytes=true], startVer=1524581551825, ver=GridCacheVersion
[topVer=136061548, order=1524581551798, nodeOrder=1], hash=2,
extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=null,
rmts=[GridCacheMvccCandidate [nodeId=9ef1e430-586e-47db-977c-df1739400000,
ver=GridCacheVersion [topVer=136061548, order=1524581551825, nodeOrder=1],
threadId=15108, id=252834, topVer=AffinityTopologyVersion [topVer=-1,
minorTopVer=0], reentry=null, otherNodeId=b5904b5a-90f1-4613-ad82-2074ca539a4a,
otherVer=null, mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null,
serOrder=null, key=KeyCacheObjectImpl [part=2, val=2, hasValBytes=true],
masks=local=0|owner=0|ready=0|reentry=0|used=0|tx=1|single_implicit=0|dht_local=0|near_local=0|removed=0|read=0,
prevVer=null, nextVer=null]]]], flags=2]]], prepared=1, locked=false,
nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=8,
partUpdateCntr=0, serReadVer=null, xidVer=null]], skipCompletedVers=false,
super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=136061548,
order=1524581551825, nodeOrder=1], writeVer=GridCacheVersion [topVer=136061548,
order=1524581551826, nodeOrder=1], implicit=false, loc=false, threadId=15108,
startTime=1524581604160, nodeId=9ef1e430-586e-47db-977c-df1739400000,
startVer=GridCacheVersion [topVer=136061548, order=1524581551824, nodeOrder=3],
endVer=null, isolation=REPEATABLE_READ, concurrency=OPTIMISTIC, timeout=9,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=PREPARED, timedOut=false,
topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=241580ms,
onePhaseCommit=false]]]]
{noformat}
[1]
https://github.com/apache/ignite/commit/2b5d2fc5fee9b8d722f919b2d92750f139d07876
> TxRollbackOnTimeoutNearCacheTest.testRandomMixedTxConfigurations fails
> flakily
> -------------------------------------------------------------------------------
>
> Key: IGNITE-8297
> URL: https://issues.apache.org/jira/browse/IGNITE-8297
> Project: Ignite
> Issue Type: Bug
> Affects Versions: 2.4
> Reporter: Andrey Kuznetsov
> Assignee: Andrey Kuznetsov
> Priority: Major
> Labels: MakeTeamcityGreenAgain
> Fix For: 2.6
>
> Attachments: 7770-new2.txt
>
>
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
