ignite-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Andrey Kuznetsov (JIRA)" <j...@apache.org>
Subject [jira] [Created] (IGNITE-9666) TxPessimisticDeadlockDetectionCrossCacheTest.testDeadlockAnotherNear is flaky on master
Date Sun, 23 Sep 2018 08:38:00 GMT
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)

Mime
View raw message