[ 
https://issues.apache.org/jira/browse/IGNITE-9666?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Aleksey Plekhanov updated IGNITE-9666:
--------------------------------------
    Fix Version/s:     (was: 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
>
> 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)

Reply via email to