You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Ksenia Rybakova (JIRA)" <ji...@apache.org> on 2018/04/18 08:47:00 UTC
[jira] [Created] (IGNITE-8308) Sporadic NPE in
IgniteAtomicSequenceExample at GridDhtTxPrepareFuture.map
Ksenia Rybakova created IGNITE-8308:
---------------------------------------
Summary: Sporadic NPE in IgniteAtomicSequenceExample at GridDhtTxPrepareFuture.map
Key: IGNITE-8308
URL: https://issues.apache.org/jira/browse/IGNITE-8308
Project: Ignite
Issue Type: Bug
Affects Versions: 1.8
Reporter: Ksenia Rybakova
Sporadic NPE occures in IgniteAtomicSequenceExample at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.map
{noformat}
java.lang.NullPointerException
at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.map(GridDhtTxPrepareFuture.java:1513)
at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.map(GridDhtTxPrepareFuture.java:1462)
at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.prepare0(GridDhtTxPrepareFuture.java:1194)
at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.mapIfLocked(GridDhtTxPrepareFuture.java:667)
at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.prepare(GridDhtTxPrepareFuture.java:1040)
at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxLocal.prepareAsync(GridDhtTxLocal.java:481)
at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.prepareNearTx(IgniteTxHandler.java:465)
at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.prepareTx(IgniteTxHandler.java:237)
at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.processNearTxPrepareRequest(IgniteTxHandler.java:123)
at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$1.apply(IgniteTxHandler.java:145)
at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$1.apply(IgniteTxHandler.java:143)
at org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:836)
at org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:378)
at org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:302)
at org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:96)
at org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:247)
at org.apache.ignite.internal.managers.communication.GridIoManager.invokeListener(GridIoManager.java:1512)
at org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:1140)
at org.apache.ignite.internal.managers.communication.GridIoManager.access$4200(GridIoManager.java:119)
at org.apache.ignite.internal.managers.communication.GridIoManager$8.run(GridIoManager.java:1080)
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}
For instance with 2 nodes:
Node1:
{noformat}
[17:17:56] Ignite node started OK (id=ac7dda5e) [17:17:56] Topology snapshot [ver=2, servers=2, clients=0, CPUs=32, heap=3.0GB] >>> Cache atomic sequence example started. Sequence initial value on local node: 0 Sequence [currentValue=0, afterIncrement=1] Sequence [currentValue=1, afterIncrement=2] Sequence [currentValue=2, afterIncrement=3] Sequence [currentValue=3, afterIncrement=4] Sequence [currentValue=4, afterIncrement=5] Sequence [currentValue=5, afterIncrement=6] Sequence [currentValue=6, afterIncrement=7] Sequence [currentValue=7, afterIncrement=8] Sequence [currentValue=8, afterIncrement=9] Sequence [currentValue=9, afterIncrement=10] Sequence [currentValue=10, afterIncrement=11] Sequence [currentValue=11, afterIncrement=12] Sequence [currentValue=12, afterIncrement=13] Sequence [currentValue=13, afterIncrement=14] Sequence [currentValue=14, afterIncrement=15] Sequence [currentValue=15, afterIncrement=16] Sequence [currentValue=16, afterIncrement=17] Sequence [currentValue=17, afterIncrement=18] Sequence [currentValue=18, afterIncrement=19] Sequence [currentValue=19, afterIncrement=20] Sequence after incrementing [expected=20, actual=20] Finished atomic sequence example... Check all nodes for output (this node is also part of the cluster).
{noformat}
Node2:
{noformat}
[17:17:42] Ignite node started OK (id=734c312e) [17:17:42] Topology snapshot [ver=1, servers=1, clients=0, CPUs=32, heap=2.0GB] [17:17:55] Topology snapshot [ver=2, servers=2, clients=0, CPUs=32, heap=3.0GB] [17:17:56,719][SEVERE][utility-#87%null%][GridCacheIoManager] Failed processing message [senderId=ac7dda5e-491b-43ce-aa22-d5442fb4db8c, msg=GridNearTxPrepareRequest [futId=14f91dec261-f8b65720-5df3-4f06-b1d5-f74b356049cf, miniId=24f91dec261-f8b65720-5df3-4f06-b1d5-f74b356049cf, near=false, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], last=true, lastBackups=null, retVal=false, implicitSingle=false, explicitLock=false, subjId=ac7dda5e-491b-43ce-aa22-d5442fb4db8c, taskNameHash=0, firstClientReq=false, super=GridDistributedTxPrepareRequest [threadId=157, concurrency=PESSIMISTIC, isolation=REPEATABLE_READ, writeVer=GridCacheVersion [topVer=135368263, time=1523888276573, order=1523888269497, nodeOrder=2], timeout=0, invalidate=false, reads=[], writes=[IgniteTxEntry [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], cacheId=-2100569601, partId=-1, txKey=IgniteTxKey [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], cacheId=-2100569601], val=[op=TRANSFORM, val=null], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null], entryProcessorsCol=[IgniteBiTuple [val1=AddAtomicProcessor [info=DataStructureInfo [name=example-sequence, type=ATOMIC_SEQ]], val2=[Ljava.lang.Object;@193a5daf]], ttl=-1, conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null, filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtColocatedCacheEntry [super=GridDhtCacheEntry [rdrs=[], locPart=GridDhtLocalPartition [rmvQueueMaxSize=128, rmvdEntryTtl=10000, id=21, map=o.a.i.i.processors.cache.GridCacheConcurrentMapImpl@53c3ddca, cntr=1, shouldBeRenting=false, state=OWNING, reservations=0, empty=false, createTime=04/16/2018 17:17:42], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], val=CacheObjectImpl [val=\{example-sequence=DataStructureInfo [name=example-sequence, type=ATOMIC_SEQ]}, hasValBytes=true], startVer=1523888269500, ver=GridCacheVersion [topVer=135368263, time=1523888276671, order=1523888269506, nodeOrder=1], hash=-1424345221, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=[GridCacheMvccCandidate [nodeId=734c312e-69ad-4476-a25a-2736692947ff, ver=GridCacheVersion [topVer=135368263, time=1523888276616, order=1523888269501, nodeOrder=1], threadId=137, id=6, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], reentry=null, otherNodeId=734c312e-69ad-4476-a25a-2736692947ff, otherVer=GridCacheVersion [topVer=135368263, time=1523888276616, order=1523888269501, nodeOrder=1], mappedDhtNodes=null, mappedNearNodes=null, ownerVer=GridCacheVersion [topVer=135368263, time=1523888276593, order=1523888269498, nodeOrder=1], serOrder=null, key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], 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=GridCacheVersion [topVer=135368263, time=1523888276678, order=1523888269508, nodeOrder=1]]], rmts=null]], flags=0]]]], prepared=0, locked=false, nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=0, partUpdateCntr=0, serReadVer=null, xidVer=null]], dhtVers=\{IgniteTxKey [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], cacheId=-2100569601]=null}, txSize=0, onePhaseCommit=true, sys=true, plc=5, txState=IgniteTxStateImpl [activeCacheIds=GridLongList [idx=1, arr=[-2100569601]], txMap=\{IgniteTxKey [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], cacheId=-2100569601]=IgniteTxEntry [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], cacheId=-2100569601, partId=21, txKey=IgniteTxKey [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], cacheId=-2100569601], val=[op=TRANSFORM, val=null], prevVal=[op=NOOP, val=null], oldVal=[op=NOOP, val=null], entryProcessorsCol=[IgniteBiTuple [val1=AddAtomicProcessor [info=DataStructureInfo [name=example-sequence, type=ATOMIC_SEQ]], val2=[Ljava.lang.Object;@193a5daf]], ttl=-1, conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null, filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtColocatedCacheEntry [super=GridDhtCacheEntry [rdrs=[], locPart=GridDhtLocalPartition [rmvQueueMaxSize=128, rmvdEntryTtl=10000, id=21, map=o.a.i.i.processors.cache.GridCacheConcurrentMapImpl@53c3ddca, cntr=1, shouldBeRenting=false, state=OWNING, reservations=0, empty=false, createTime=04/16/2018 17:17:42], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], hasValBytes=true], val=CacheObjectImpl [val={example-sequence=DataStructureInfo [name=example-sequence, type=ATOMIC_SEQ]}, hasValBytes=true], startVer=1523888269500, ver=GridCacheVersion [topVer=135368263, time=1523888276671, order=1523888269506, nodeOrder=1], hash=-1424345221, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=[GridCacheMvccCandidate [nodeId=734c312e-69ad-4476-a25a-2736692947ff, ver=GridCacheVersion [topVer=135368263, time=1523888276616, order=1523888269501, nodeOrder=1], threadId=137, id=6, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], reentry=null, otherNodeId=734c312e-69ad-4476-a25a-2736692947ff, otherVer=GridCacheVersion [topVer=135368263, time=1523888276616, order=1523888269501, nodeOrder=1], mappedDhtNodes=null, mappedNearNodes=null, ownerVer=GridCacheVersion [topVer=135368263, time=1523888276593, order=1523888269498, nodeOrder=1], serOrder=null, key=KeyCacheObjectImpl [val=CacheDataStructuresConfigurationKey [], 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=GridCacheVersion [topVer=135368263, time=1523888276678, order=1523888269508, nodeOrder=1]]], rmts=null]], flags=0]]]], prepared=0, locked=false, nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=0, partUpdateCntr=1, serReadVer=null, xidVer=GridCacheVersion [topVer=135368263, time=1523888276593, order=1523888269498, nodeOrder=1]]}], super=GridDistributedBaseMessage [ver=GridCacheVersion [topVer=135368263, time=1523888276573, order=1523888269497, nodeOrder=2], committedVers=null, rolledbackVers=null, cnt=0, super=GridCacheMessage [msgId=19, depInfo=GridDeploymentInfoBean [clsLdrId=c0f91dec261-ac7dda5e-491b-43ce-aa22-d5442fb4db8c, depMode=SHARED, userVer=0, locDepOwner=false, participants=null], err=null, skipPrepare=false, cacheId=0, cacheId=0]]]]] java.lang.NullPointerException at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.map(GridDhtTxPrepareFuture.java:1513) at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.map(GridDhtTxPrepareFuture.java:1462) at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.prepare0(GridDhtTxPrepareFuture.java:1194) at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.mapIfLocked(GridDhtTxPrepareFuture.java:667) at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareFuture.prepare(GridDhtTxPrepareFuture.java:1040) at org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxLocal.prepareAsync(GridDhtTxLocal.java:481) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.prepareNearTx(IgniteTxHandler.java:465) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.prepareTx(IgniteTxHandler.java:237) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler.processNearTxPrepareRequest(IgniteTxHandler.java:123) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$1.apply(IgniteTxHandler.java:145) at org.apache.ignite.internal.processors.cache.transactions.IgniteTxHandler$1.apply(IgniteTxHandler.java:143) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.processMessage(GridCacheIoManager.java:836) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:378) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:302) at org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:96) at org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:247) at org.apache.ignite.internal.managers.communication.GridIoManager.invokeListener(GridIoManager.java:1512) at org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:1140) at org.apache.ignite.internal.managers.communication.GridIoManager.access$4200(GridIoManager.java:119) at org.apache.ignite.internal.managers.communication.GridIoManager$8.run(GridIoManager.java:1080) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Sequence initial value on local node: 1000 Sequence [currentValue=1000, afterIncrement=1001] Sequence [currentValue=1001, afterIncrement=1002] Sequence [currentValue=1002, afterIncrement=1003] Sequence [currentValue=1003, afterIncrement=1004] Sequence [currentValue=1004, afterIncrement=1005] Sequence [currentValue=1005, afterIncrement=1006] Sequence [currentValue=1006, afterIncrement=1007] Sequence [currentValue=1007, afterIncrement=1008] Sequence [currentValue=1008, afterIncrement=1009] Sequence [currentValue=1009, afterIncrement=1010] Sequence [currentValue=1010, afterIncrement=1011] Sequence [currentValue=1011, afterIncrement=1012] Sequence [currentValue=1012, afterIncrement=1013] Sequence [currentValue=1013, afterIncrement=1014] Sequence [currentValue=1014, afterIncrement=1015] Sequence [currentValue=1015, afterIncrement=1016] Sequence [currentValue=1016, afterIncrement=1017] Sequence [currentValue=1017, afterIncrement=1018] Sequence [currentValue=1018, afterIncrement=1019] Sequence [currentValue=1019, afterIncrement=1020] Sequence after incrementing [expected=1020, actual=1020] [17:17:56] Topology snapshot [ver=3, servers=1, clients=0, CPUs=32, heap=2.0GB]
{noformat}
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)