Denis Magda created IGNITE-1905:
-----------------------------------

             Summary: High contention for CacheLockImpl causes AssertionErrors
                 Key: IGNITE-1905
                 URL: https://issues.apache.org/jira/browse/IGNITE-1905
             Project: Ignite
          Issue Type: Bug
          Components: cache
    Affects Versions: ignite-1.4
         Environment: Windows 7
            Reporter: Denis Magda
            Priority: Critical
             Fix For: 1.5


When multiple threads, running on the same client node, compete for 
CacheLockImpl this leads to AssertionErrors.

Pseudo-code snippet, that is called from multiple threads and causes the 
assertions, looks like this:

{noformat}
boolean locked = lock.tryLock(100, TimeUnit.MILLISECONDS);
if (locked)
    lock.unlock();
{noformat}

Initially the issue was detected on ignite-1.4.
In server's node logs the following assertion appears

{noformat}
java.lang.AssertionError
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.addOwned(GridDhtLockFuture.java:958)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.map(GridDhtLockFuture.java:918)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.onOwnerChanged(GridDhtLockFuture.java:663)
        at 
org.apache.ignite.internal.processors.cache.GridCacheMvccManager$2.onOwnerChanged(GridCacheMvccManager.java:155)
        at 
org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheEntry.checkOwnerChanged(GridDistributedCacheEntry.java:810)
        at 
org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheEntry.readyLock(GridDistributedCacheEntry.java:516)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.readyLocks(GridDhtLockFuture.java:576)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.map(GridDhtLockFuture.java:764)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.lockAllAsync(GridDhtTransactionalCacheAdapter.java:973)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.processNearLockRequest(GridDhtTransactionalCacheAdapter.java:557)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.access$000(GridDhtTransactionalCacheAdapter.java:88)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter$2.apply(GridDhtTransactionalCacheAdapter.java:132)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter$2.apply(GridDhtTransactionalCacheAdapter.java:130)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:580)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:280)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:198)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:77)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:160)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
{noformat}
                      
In addition from time to time a client node also outputs a different kind of 
assertion:

{noformat}
Exception in thread "ignite-#4%sys-null%" java.lang.AssertionError
        at 
org.apache.ignite.internal.processors.cache.GridCacheExplicitLockSpan.markOwned(GridCacheExplicitLockSpan.java:196)
        at 
org.apache.ignite.internal.processors.cache.GridCacheMvccManager.markExplicitOwner(GridCacheMvccManager.java:862)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.colocated.GridDhtColocatedLockFuture$MiniFuture.onResult(GridDhtColocatedLockFuture.java:1412)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.colocated.GridDhtColocatedLockFuture.onResult(GridDhtColocatedLockFuture.java:437)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.colocated.GridDhtColocatedCache.processLockResponse(GridDhtColocatedCache.java:888)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.colocated.GridDhtColocatedCache.access$100(GridDhtColocatedCache.java:80)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.colocated.GridDhtColocatedCache$3.apply(GridDhtColocatedCache.java:145)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.colocated.GridDhtColocatedCache$3.apply(GridDhtColocatedCache.java:143)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:580)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:280)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:198)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:77)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:160)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
{noformat}

Ran the test against ignite-1.5 and got a different kind of assertions on the 
server node:

{noformat}
Exception in thread "ignite-#246%sys-null%" java.lang.AssertionError: Entry 
does not own lock for tx [locNodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, 
entry=GridDhtColocatedCacheEntry [super=GridDhtCacheEntry [rdrs=[], 
locPart=GridDhtLocalPartition [id=49, mapPubSize=5, rmvQueue=GridCircularBuffer 
[sizeMask=511, idxGen=0], state=OWNING, reservations=0, empty=false, 
createTime=11/13/2015 15:08:42, mapPubSize=5], super=GridDistributedCacheEntry 
[super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
val=CacheObjectImpl [val=null, hasValBytes=true], startVer=1447412910273, 
ver=GridCacheVersion [topVer=58892922, nodeOrderDrId=1, globalTime=0, order=0], 
hash=-320443512, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc 
[locs=[GridCacheMvccCandidate [nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, 
ver=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968535, order=1447412964154], timeout=100, ts=1447412968537, 
threadId=101, id=2245, topVer=AffinityTopologyVersion [topVer=3, 
minorTopVer=0], reentry=null, otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, 
otherVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968535, order=1447412964154], 
mappedDhtNodes=[TcpDiscoveryNode [id=ce646644-b983-4d81-ba40-c39a057b4cc5, 
addrs=[0:0:0:0:0:0:0:1, 10.0.0.18, 127.0.0.1], sockAddrs=[/10.0.0.18:47501, 
/0:0:0:0:0:0:0:1:47501, /10.0.0.18:47501, /127.0.0.1:47501], discPort=47501, 
order=2, intOrder=2, lastExchangeTime=1447412934615, loc=false, 
ver=1.5.0#19700101-sha1:00000000, isClient=false]], mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968452, order=1447412964076], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968537, 
order=1447412964156], timeout=100, ts=1447412968547, threadId=99, id=2246, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968537, 
order=1447412964156], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968454, order=1447412964078], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968539, 
order=1447412964158], timeout=100, ts=1447412968547, threadId=75, id=2247, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968539, 
order=1447412964158], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968456, order=1447412964080], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968541, 
order=1447412964160], timeout=100, ts=1447412968547, threadId=83, id=2248, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968541, 
order=1447412964160], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968458, order=1447412964082], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968543, 
order=1447412964162], timeout=100, ts=1447412968547, threadId=86, id=2249, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968543, 
order=1447412964162], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968461, order=1447412964084], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968545, 
order=1447412964164], timeout=100, ts=1447412968557, threadId=69, id=2250, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968545, 
order=1447412964164], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968463, order=1447412964086], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968547, 
order=1447412964166], timeout=100, ts=1447412968557, threadId=95, id=2251, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968547, 
order=1447412964166], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968465, order=1447412964088], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968550, 
order=1447412964168], timeout=100, ts=1447412968557, threadId=102, id=2252, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968550, 
order=1447412964168], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968467, order=1447412964090], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968552, 
order=1447412964170], timeout=100, ts=1447412968557, threadId=67, id=2253, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968552, 
order=1447412964170], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968470, order=1447412964092], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968554, 
order=1447412964172], timeout=100, ts=1447412968557, threadId=78, id=2254, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968554, 
order=1447412964172], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968472, order=1447412964094], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968556, 
order=1447412964174], timeout=100, ts=1447412968567, threadId=70, id=2255, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968556, 
order=1447412964174], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968474, order=1447412964096], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968559, 
order=1447412964176], timeout=100, ts=1447412968567, threadId=79, id=2256, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968559, 
order=1447412964176], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968476, order=1447412964098], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968561, 
order=1447412964178], timeout=100, ts=1447412968567, threadId=103, id=2257, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968561, 
order=1447412964178], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968479, order=1447412964100], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968563, 
order=1447412964180], timeout=100, ts=1447412968567, threadId=90, id=2258, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968563, 
order=1447412964180], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968481, order=1447412964102], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968566, 
order=1447412964182], timeout=100, ts=1447412968577, threadId=66, id=2259, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968566, 
order=1447412964182], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968483, order=1447412964104], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968568, 
order=1447412964184], timeout=100, ts=1447412968577, threadId=82, id=2260, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968568, 
order=1447412964184], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968485, order=1447412964106], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968570, 
order=1447412964186], timeout=100, ts=1447412968577, threadId=91, id=2261, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968570, 
order=1447412964186], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968487, order=1447412964108], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968584, 
order=1447412964188], timeout=100, ts=1447412968597, threadId=94, id=2262, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968584, 
order=1447412964188], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968494, order=1447412964114], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968585, 
order=1447412964189], timeout=100, ts=1447412968597, threadId=68, id=2263, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968585, 
order=1447412964189], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968494, order=1447412964114], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968586, 
order=1447412964192], timeout=100, ts=1447412968597, threadId=84, id=2264, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968586, 
order=1447412964192], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968494, order=1447412964114], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968591, 
order=1447412964194], timeout=100, ts=1447412968597, threadId=85, id=2265, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968591, 
order=1447412964194], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968497, order=1447412964116], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968593, 
order=1447412964196], timeout=100, ts=1447412968597, threadId=104, id=2266, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968593, 
order=1447412964196], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968499, order=1447412964118], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968595, 
order=1447412964198], timeout=100, ts=1447412968597, threadId=92, id=2267, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968595, 
order=1447412964198], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968502, order=1447412964120], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968596, 
order=1447412964200], timeout=100, ts=1447412968607, threadId=88, id=2268, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968596, 
order=1447412964200], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968504, order=1447412964122], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968601, 
order=1447412964202], timeout=100, ts=1447412968607, threadId=98, id=2269, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968601, 
order=1447412964202], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968506, order=1447412964124], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968603, 
order=1447412964204], timeout=100, ts=1447412968607, threadId=72, id=2270, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968603, 
order=1447412964204], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968508, order=1447412964126], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968605, 
order=1447412964206], timeout=100, ts=1447412968617, threadId=96, id=2271, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968605, 
order=1447412964206], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968510, order=1447412964128], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968606, 
order=1447412964208], timeout=100, ts=1447412968617, threadId=80, id=2272, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968606, 
order=1447412964208], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968514, order=1447412964132], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968606, 
order=1447412964210], timeout=100, ts=1447412968617, threadId=81, id=2273, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968606, 
order=1447412964210], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968514, order=1447412964132], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968610, 
order=1447412964212], timeout=100, ts=1447412968617, threadId=97, id=2274, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968610, 
order=1447412964212], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968516, order=1447412964134], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968612, 
order=1447412964214], timeout=100, ts=1447412968617, threadId=65, id=2275, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968612, 
order=1447412964214], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968518, order=1447412964136], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968614, 
order=1447412964216], timeout=100, ts=1447412968617, threadId=89, id=2276, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968614, 
order=1447412964216], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968520, order=1447412964138], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968616, 
order=1447412964218], timeout=100, ts=1447412968627, threadId=73, id=2277, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968616, 
order=1447412964218], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968525, order=1447412964144], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968616, 
order=1447412964220], timeout=100, ts=1447412968627, threadId=74, id=2278, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968616, 
order=1447412964220], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968525, order=1447412964144], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968616, 
order=1447412964222], timeout=100, ts=1447412968627, threadId=71, id=2279, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968616, 
order=1447412964222], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968525, order=1447412964144], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968621, 
order=1447412964224], timeout=100, ts=1447412968627, threadId=87, id=2280, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968621, 
order=1447412964224], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968527, order=1447412964146], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null], GridCacheMvccCandidate 
[nodeId=8f8c5147-2d8f-44e6-8da8-ef8a5bae1fd3, ver=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968624, 
order=1447412964226], timeout=100, ts=1447412968627, threadId=76, id=2281, 
topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], reentry=null, 
otherNodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, otherVer=GridCacheVersion 
[topVer=58892924, nodeOrderDrId=3, globalTime=1447412968624, 
order=1447412964226], mappedDhtNodes=null, mappedNearNodes=null, 
ownerVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968529, order=1447412964148], serOrder=null, 
key=KeyCacheObjectImpl [val=1, hasValBytes=true], 
masks=local=1|owner=0|ready=1|reentry=0|used=0|tx=0|single_implicit=0|dht_local=1|near_local=0|removed=0,
 prevVer=null, nextVer=null]], rmts=null]], flags=0]]]], 
mappedVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968533, order=1447412964152], ver=GridCacheVersion 
[topVer=58892922, nodeOrderDrId=1, globalTime=0, order=0], tx=null, 
req=GridNearLockRequest [topVer=AffinityTopologyVersion [topVer=3, 
minorTopVer=0], miniId=6b229800151-652e9fe6-66fa-4415-aeb1-1ec44adaf8d2, 
implicitTx=false, implicitSingleTx=false, onePhaseCommit=false, dhtVers=[null], 
subjId=null, taskNameHash=0, hasTransforms=false, syncCommit=false, 
accessTtl=-1, retVal=true, firstClientReq=true, filter=null, 
super=GridDistributedLockRequest [nodeId=f35fe3b2-93e7-4da5-8c59-94325ccd971b, 
nearXidVer=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968533, order=1447412964152], threadId=77, 
futId=4b229800151-652e9fe6-66fa-4415-aeb1-1ec44adaf8d2, timeout=100, 
isInTx=false, isInvalidate=false, isRead=false, isolation=null, retVals=[true], 
txSize=1, flags=0, keysCnt=1, super=GridDistributedBaseMessage 
[ver=GridCacheVersion [topVer=58892924, nodeOrderDrId=3, 
globalTime=1447412968533, order=1447412964152], candsByIdx=null, 
committedVers=null, rolledbackVers=null, cnt=0, super=GridCacheMessage 
[msgId=4463, depInfo=null, err=null, skipPrepare=false]]]], err=null]
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.createLockReply(GridDhtTransactionalCacheAdapter.java:1132)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.access$400(GridDhtTransactionalCacheAdapter.java:88)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter$10.apply(GridDhtTransactionalCacheAdapter.java:986)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter$10.apply(GridDhtTransactionalCacheAdapter.java:979)
        at 
org.apache.ignite.internal.util.future.GridEmbeddedFuture$1.applyx(GridEmbeddedFuture.java:59)
        at 
org.apache.ignite.internal.util.future.GridEmbeddedFuture$AsyncListener1.apply(GridEmbeddedFuture.java:297)
        at 
org.apache.ignite.internal.util.future.GridEmbeddedFuture$AsyncListener1.apply(GridEmbeddedFuture.java:290)
        at 
org.apache.ignite.internal.util.future.GridFutureAdapter.notifyListener(GridFutureAdapter.java:262)
        at 
org.apache.ignite.internal.util.future.GridFutureAdapter.notifyListeners(GridFutureAdapter.java:250)
        at 
org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:380)
        at 
org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:346)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.onComplete(GridDhtLockFuture.java:733)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.onDone(GridDhtLockFuture.java:711)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.onDone(GridDhtLockFuture.java:80)
        at 
org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:323)
        at 
org.apache.ignite.internal.util.future.GridCompoundFuture.checkComplete(GridCompoundFuture.java:285)
        at 
org.apache.ignite.internal.util.future.GridCompoundFuture.markInitialized(GridCompoundFuture.java:276)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.map(GridDhtLockFuture.java:943)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtLockFuture.onOwnerChanged(GridDhtLockFuture.java:666)
        at 
org.apache.ignite.internal.processors.cache.GridCacheMvccManager$2.onOwnerChanged(GridCacheMvccManager.java:156)
        at 
org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheEntry.checkOwnerChanged(GridDistributedCacheEntry.java:814)
        at 
org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheEntry.removeLock(GridDistributedCacheEntry.java:448)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtCacheEntry.removeLock(GridDhtCacheEntry.java:319)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.removeLocks(GridDhtTransactionalCacheAdapter.java:1486)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.processNearUnlockRequest(GridDhtTransactionalCacheAdapter.java:1336)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter.access$200(GridDhtTransactionalCacheAdapter.java:88)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter$5.apply(GridDhtTransactionalCacheAdapter.java:150)
        at 
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTransactionalCacheAdapter$5.apply(GridDhtTransactionalCacheAdapter.java:148)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:540)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:273)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:197)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:76)
        at 
org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:159)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:811)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager.access$1500(GridIoManager.java:106)
        at 
org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:774)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
{noformat}



To reproduce run attached sources in the following order:
- Start two server nodes (ServerTest.java);
- Start a client node (ClientTest.java).

If the issue is not reproduced on your machine try to adjust {{lock.tryLock}} 
timeout value.



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Reply via email to