You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Maxim Muzafarov (Jira)" <ji...@apache.org> on 2019/10/03 10:03:06 UTC
[jira] [Updated] (IGNITE-9666)
TxPessimisticDeadlockDetectionCrossCacheTest.testDeadlockAnotherNear is
flaky on master
[ https://issues.apache.org/jira/browse/IGNITE-9666?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Maxim Muzafarov updated IGNITE-9666:
------------------------------------
Fix Version/s: (was: 2.8)
2.9
> 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
> Priority: Major
> Fix For: 2.9
>
>
> 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
(v8.3.4#803005)