[
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)
