[
https://issues.apache.org/jira/browse/IGNITE-9548?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Alexei Scherbakov updated IGNITE-9548:
--------------------------------------
Priority: Major (was: Critical)
Summary: Transaction with short timeout is not rolled back on primary node
(was: Transaction with short timeout is not rolled back on primary node
resulting in blocked PME)
> Transaction with short timeout is not rolled back on primary node
> -----------------------------------------------------------------
>
> Key: IGNITE-9548
> URL: https://issues.apache.org/jira/browse/IGNITE-9548
> Project: Ignite
> Issue Type: Bug
> Reporter: Alexei Scherbakov
> Assignee: Alexei Scherbakov
> Priority: Major
>
> UPDATE: this is no longer critical ticket because after recent changes node
> will be stopped by failure handler unblocking PME. Changed ticket name to
> reflect this.
> Looks like primary node have not received finish(false) message
> {noformat}
> 2018-09-10 12:38:24.237 [WARN
> ][exchange-worker-#153%DPL_GRID%DplGridNodeName%][o.apache.ignite.internal.diagnostic]
> Pending transactions:
> 2018-09-10 12:38:24.242 [WARN
> ][exchange-worker-#153%DPL_GRID%DplGridNodeName%][o.apache.ignite.internal.diagnostic]
> >>> [txVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> exchWait=true, tx=GridDhtTxLocal
> [nearNodeId=eb94406c-a132-4998-bf22-b7d74960b866, nearFut
> Id=b7cff46b561-0b500010-3ed6-4b79-8cc8-65b3b3b16738, nearMiniId=1,
> nearFinFutId=null, nearFinMiniId=0, nearXidVer=GridCacheVersion
> [topVer=147809766, order=1536687716227, nodeOrder=182],
> super=GridDhtTxLocalAdapter [nearOnOriginatingNode=false, nearNodes=[],
> dhtNodes=[],
> explicitLock=false, super=IgniteTxLocalAdapter [completedBase=null,
> sndTransformedVals=false, depEnabled=false, txState=IgniteTxStateImpl
> [activeCacheIds=[-1934881220], recovery=false, txMap=[IgniteTxEntry
> [key=KeyCacheObjectImpl [part=12715, val=ucp_ids_counter_name_DP
> L_ucp_ids_section_name, hasValBytes=true], cacheId=-1934881220,
> txKey=IgniteTxKey [key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> cacheId=-1934881220], val=[op=NOOP, val=null], prevVal=[op=NOOP, val=null],
> oldVal
> =[op=NOOP, val=null], entryProcessorsCol=null, ttl=-1, conflictExpireTime=-1,
> conflictVer=null, explicitVer=null, dhtVer=null, filters=[],
> filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry [rdrs=[],
> part=12715, super=GridDistributedCacheEntry [super=GridCach
> eMapEntry [key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> val=CacheObjectImpl [val=null, hasValBytes=true], startVer=1536665387604,
> ver=GridCacheVersion [topVer=147809766, order=1536737564543, nodeOrder=33],
> hash
> =-864500235, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc
> [locs=[GridCacheMvccCandidate [nodeId=a4823893-be8f-4b24-abca-0a28efde604a,
> ver=GridCacheVersion [topVer=147809766, order=1536737580715, nodeOrder=33],
> threadId=887, id=43118359, topVer=AffinityTopologyVers
> ion [topVer=343, minorTopVer=0], reentry=null,
> otherNodeId=eb94406c-a132-4998-bf22-b7d74960b866, otherVer=GridCacheVersion
> [topVer=147809766, order=1536687716227, nodeOrder=182], mappedDhtNodes=null,
> mappedNearNodes=null, ownerVer=GridCacheVersion [topVer=147809766, orde
> r=1536737580560, nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl
> [part=12715, val=ucp_ids_counter_name_DPL_ucp_ids_section_name,
> hasValBytes=true],
> masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevV
> er=null, nextVer=null]], rmts=null]], flags=2]]], prepared=0, locked=false,
> nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false,
> flags=2, partUpdateCntr=0, serReadVer=null, xidVer=GridCacheVersion
> [topVer=147809766, order=1536737580715, nodeOrder=33]]]]
> , super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=147809766,
> order=1536737580715, nodeOrder=33], writeVer=null, implicit=false, loc=true,
> threadId=887, startTime=1536416065902,
> nodeId=a4823893-be8f-4b24-abca-0a28efde604a, startVer=GridCacheVersion
> [topVer=14780976
> 6, order=1536737580715, nodeOrder=33], endVer=null,
> isolation=REPEATABLE_READ, concurrency=PESSIMISTIC, timeout=200,
> sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
> invalidParts=null, state=MARKED_ROLLBACK, timedOut=false,
> topVer=AffinityTopologyV
> ersion [topVer=343, minorTopVer=0], duration=156238330ms,
> onePhaseCommit=false], size=1]]]]
> {noformat}
> This condition is likely to be triggered by assertion happening on same entry
> but in another transaction:
> {noformat}
> java.lang.AssertionError: Entry does not own lock for tx
> [locNodeId=a4823893-be8f-4b24-abca-0a28efde604a, entry=GridDhtCacheEntry
> [rdrs=[], part=12715, super=GridDistributedCacheEntry
> [super=GridCacheMapEntry [key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> val=CacheObjectImpl [val=null, hasValBytes=true], startVer=1536665387604,
> ver=GridCacheVersion [topVer=147809766, order=1536737564543, nodeOrder=33],
> hash=-864500235, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc
> [locs=[GridCacheMvccCandidate [nodeId=a4823893-be8f-4b24-abca-0a28efde604a,
> ver=GridCacheVersion [topVer=147809766, order=1536737580560, nodeOrder=33],
> threadId=1094, id=43118204, topVer=AffinityTopologyVersion [topVer=343,
> minorTopVer=0], reentry=null,
> otherNodeId=e3caa79d-8593-4c91-bc92-36b6715ee2e0, otherVer=GridCacheVersion
> [topVer=147809766, order=1536672087052, nodeOrder=216], mappedDhtNodes=null,
> mappedNearNodes=null, ownerVer=GridCacheVersion [topVer=147809766,
> order=1536737580404, nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl
> [part=12715, val=ucp_ids_counter_name_DPL_ucp_ids_section_name,
> hasValBytes=true],
> masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580561, nodeOrder=33], threadId=1005,
> id=43118205, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=32ba52b4-77eb-410f-9c5f-1143113984fc,
> otherVer=GridCacheVersion [topVer=147809766, order=1536688435283,
> nodeOrder=214], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580405,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580562, nodeOrder=33], threadId=950,
> id=43118206, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=e03a96ae-f504-41ac-8141-97568767c18d,
> otherVer=GridCacheVersion [topVer=147809766, order=1536668701020,
> nodeOrder=325], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580406,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580563, nodeOrder=33], threadId=1067,
> id=43118207, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=77dd0f7e-5d88-4ca6-9492-f99b6810e88b,
> otherVer=GridCacheVersion [topVer=147809766, order=1536674900889,
> nodeOrder=308], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580407,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580564, nodeOrder=33], threadId=1071,
> id=43118208, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=c5784fb0-ad45-41f8-a702-877676c51d7d,
> otherVer=GridCacheVersion [topVer=147809766, order=1536671147833,
> nodeOrder=313], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580408,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580565, nodeOrder=33], threadId=1001,
> id=43118209, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=ed2b053b-9df5-41eb-be86-06a970c09978,
> otherVer=GridCacheVersion [topVer=147809766, order=1536704719759,
> nodeOrder=193], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580408,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580566, nodeOrder=33], threadId=957,
> id=43118210, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=fb6a6b86-4e86-467d-8a59-36dcb3fe934a,
> otherVer=GridCacheVersion [topVer=147809766, order=1536737532666,
> nodeOrder=206], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580408,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580567, nodeOrder=33], threadId=999,
> id=43118211, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=d2610f41-f4ca-4a4c-ac52-fc0f470bdd28,
> otherVer=GridCacheVersion [topVer=147809766, order=1536737555682,
> nodeOrder=201], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580408,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580568, nodeOrder=33], threadId=964,
> id=43118212, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=7ca95cbf-9514-4770-a254-dd7f6cc3be52,
> otherVer=GridCacheVersion [topVer=147809766, order=1536737353240,
> nodeOrder=332], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580408,
> nodeOrder=33], serOrder=null, key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null], GridCacheMvccCandidate
> [nodeId=a4823893-be8f-4b24-abca-0a28efde604a, ver=GridCacheVersion
> [topVer=147809766, order=1536737580569, nodeOrder=33], threadId=960,
> id=43118213, topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0],
> reentry=null, otherNodeId=784fdc21-ad82-4239-b7ca-8cb93c581ba5,
> otherVer=GridCacheVersion [topVer=147809766, order=1536737486975,
> nodeOrder=247], mappedDhtNodes=null, mappedNearNodes=null,
> ownerVer=GridCacheVersion [topVer=147809766, order=1536737580408,
> nodeOrder=33], serOrder=null, key=KeyCach... and 13164 skipped ...sion
> [topVer=147809766, order=1536737580483, nodeOrder=33], serOrder=null,
> key=KeyCacheObjectImpl [part=12715,
> val=ucp_ids_counter_name_DPL_ucp_ids_section_name, hasValBytes=true],
> masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=1|single_implicit=0|dht_local=1|near_local=0|removed=0|read=0,
> prevVer=null, nextVer=null]]54 more], rmts=null]], flags=2]]], prepared=0,
> locked=false, nodeId=null, locMapped=false, expiryPlc=null,
> transferExpiryPlc=false, flags=2, partUpdateCntr=0, serReadVer=null,
> xidVer=GridCacheVersion [topVer=147809766, order=1536737580559,
> nodeOrder=33]]]], super=IgniteTxAdapter [xidVer=GridCacheVersion
> [topVer=147809766, order=1536737580559, nodeOrder=33], writeVer=null,
> implicit=false, loc=true, threadId=972, startTime=1536416061213,
> nodeId=a4823893-be8f-4b24-abca-0a28efde604a, startVer=GridCacheVersion
> [topVer=147809766, order=1536737580559, nodeOrder=33], endVer=null,
> isolation=REPEATABLE_READ, concurrency=PESSIMISTIC, timeout=200,
> sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE,
> invalidParts=null, state=ROLLED_BACK, timedOut=false,
> topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0], duration=4649ms,
> onePhaseCommit=false], size=1]]], req=GridNearLockRequest
> [topVer=AffinityTopologyVersion [topVer=343, minorTopVer=0], miniId=1,
> dhtVers=[null], subjId=9ca8fb3e-b65b-4dd6-a885-77db6c3ffba2, taskNameHash=0,
> createTtl=-1, accessTtl=-1, flags=7, filter=null,
> super=GridDistributedLockRequest
> [nodeId=9ca8fb3e-b65b-4dd6-a885-77db6c3ffba2, nearXidVer=GridCacheVersion
> [topVer=147809766, order=1536670223349, nodeOrder=281], threadId=972,
> futId=8776656b561-953357cf-82c8-4016-a3ae-8d0a76b3c990, timeout=200,
> isInTx=true, isInvalidate=false, isRead=true, isolation=REPEATABLE_READ,
> retVals=[true], txSize=0, flags=2, keysCnt=1,
> super=GridDistributedBaseMessage [ver=GridCacheVersion [topVer=147809766,
> order=1536670223349, nodeOrder=281], committedVers=null, rolledbackVers=null,
> cnt=0, super=GridCacheIdMessage [cacheId=-1934881220]]]]]
> {noformat}
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
