Andrey Kuznetsov created IGNITE-9666:
----------------------------------------
Summary:
TxPessimisticDeadlockDetectionCrossCacheTest.testDeadlockAnotherNear is flaky
on master
Key: IGNITE-9666
URL: https://issues.apache.org/jira/browse/IGNITE-9666
Project: Ignite
Issue Type: Bug
Affects Versions: 2.6
Reporter: Andrey Kuznetsov
Fix For: 2.8
Sometimes the test cannot pass {{assertTrue(deadlock.get())}}.
Presumably, it's due to ignoring possible long JVM pauses. For example, one can
see near the first 'put' pair (note timestamps) :
{noformat}
[2018-09-23 11:16:55,975][INFO ][tx-thread-1][root] >>> Performs put
[node=TcpDiscoveryNode [id=dd46ab0e-ed28-4c67-b3c4-98900bb00000,
addrs=ArrayList [127.0.0.1], sockAddrs=HashSet [/127.0.0.1:47500],
discPort=47500, order=1, intOrder=1, lastExchangeTime=1537690615852, loc=true,
ver=2.7.0#19700101-sha1:00000000, isClient=false], tx=TransactionProxyImpl
[tx=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=149170604,
order=1537690611182, nodeOrder=1], writeVer=null, implicit=false, loc=true,
threadId=129, startTime=1537690615791,
nodeId=dd46ab0e-ed28-4c67-b3c4-98900bb00000, startVer=GridCacheVersion
[topVer=149170604, order=1537690611182, nodeOrder=1], endVer=null,
isolation=REPEATABLE_READ, concurrency=PESSIMISTIC, timeout=500,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=ACTIVE, timedOut=false, topVer=AffinityTopologyVersion
[topVer=-1, minorTopVer=0],
txCounters=org.apache.ignite.internal.processors.cache.transactions.TxCounters@31c7393f,
duration=155ms, onePhaseCommit=false]IgniteTxLocalAdapter [completedBase=null,
sndTransformedVals=false, depEnabled=false, txState=IgniteTxStateImpl
[activeCacheIds=[], recovery=null, mvccEnabled=null, txMap=EmptySet []],
mvccWaitTxs=null, qryEnlisted=false, super=, size=0]GridDhtTxLocalAdapter
[nearOnOriginatingNode=false, nearNodes=KeySetView [], dhtNodes=KeySetView [],
explicitLock=false, super=]GridNearTxLocal [mappings=IgniteTxMappingsImpl [],
nearLocallyMapped=false, colocatedLocallyMapped=false, needCheckBackup=null,
hasRemoteLocks=false, trackTimeout=true, lb=null, mvccTracker=null, sql=null,
thread=tx-thread-1, mappings=IgniteTxMappingsImpl [], super=], async=false,
asyncRes=null], key=2, cache=cache0]
[2018-09-23 11:16:55,975][INFO ][tx-thread-2][root] >>> Performs put
[node=TcpDiscoveryNode [id=dd46ab0e-ed28-4c67-b3c4-98900bb00000,
addrs=ArrayList [127.0.0.1], sockAddrs=HashSet [/127.0.0.1:47500],
discPort=47500, order=1, intOrder=1, lastExchangeTime=1537690615852, loc=true,
ver=2.7.0#19700101-sha1:00000000, isClient=false], tx=TransactionProxyImpl
[tx=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=149170604,
order=1537690611181, nodeOrder=1], writeVer=null, implicit=false, loc=true,
threadId=130, startTime=1537690615791,
nodeId=dd46ab0e-ed28-4c67-b3c4-98900bb00000, startVer=GridCacheVersion
[topVer=149170604, order=1537690611182, nodeOrder=1], endVer=null,
isolation=REPEATABLE_READ, concurrency=PESSIMISTIC, timeout=500,
sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
invalidParts=null, state=ACTIVE, timedOut=false, topVer=AffinityTopologyVersion
[topVer=-1, minorTopVer=0],
txCounters=org.apache.ignite.internal.processors.cache.transactions.TxCounters@14d54c9c,
duration=155ms, onePhaseCommit=false]IgniteTxLocalAdapter [completedBase=null,
sndTransformedVals=false, depEnabled=false, txState=IgniteTxStateImpl
[activeCacheIds=[], recovery=null, mvccEnabled=null, txMap=EmptySet []],
mvccWaitTxs=null, qryEnlisted=false, super=, size=0]GridDhtTxLocalAdapter
[nearOnOriginatingNode=false, nearNodes=KeySetView [], dhtNodes=KeySetView [],
explicitLock=false, super=]GridNearTxLocal [mappings=IgniteTxMappingsImpl [],
nearLocallyMapped=false, colocatedLocallyMapped=false, needCheckBackup=null,
hasRemoteLocks=false, trackTimeout=true, lb=null, mvccTracker=null, sql=null,
thread=tx-thread-2, mappings=IgniteTxMappingsImpl [], super=], async=false,
asyncRes=null], key=2, cache=cache1]
[2018-09-23 11:16:56,378][INFO
][exchange-worker-#38%transactions.TxPessimisticDeadlockDetectionCrossCacheTest0%][time]
Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=3], mvccCrd=MvccCoordinator
[nodeId=dd46ab0e-ed28-4c67-b3c4-98900bb00000, crdVer=1537690602134,
topVer=AffinityTopologyVersion [topVer=1, minorTopVer=0]], mvccCrdChange=false,
crd=true, evt=DISCOVERY_CUSTOM_EVT,
evtNode=dd46ab0e-ed28-4c67-b3c4-98900bb00000,
customEvt=CacheAffinityChangeMessage
[id=d7540850661-799b6d10-6e53-4f8b-9595-98f8c060efa1,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], allowMerge=false]
{noformat}
And then, transactions have to roll back due to 500 ms timeout, leaving no
possibility to produce deadlock.
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
