Alexey Goncharuk created IGNITE-2189:
----------------------------------------
Summary: Race b/w cache message processing and cache close
Key: IGNITE-2189
URL: https://issues.apache.org/jira/browse/IGNITE-2189
Project: Ignite
Issue Type: Sub-task
Components: cache
Affects Versions: 1.5
Reporter: Alexey Goncharuk
Fix For: 1.6
org.apache.ignite.internal.processors.cache.CacheStopAndDestroySelfTest#testNearClose
sporadically hangs with the following assertion:
{code}
[09:20:19]W: [org.apache.ignite:ignite-core] [09:20:19,064][WARN
][main][root] Finished waiting for topology map update
[grid=cache.CacheStopAndDestroySelfTest0, p=1, duration=3014ms]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,084][INFO
][exchange-worker-#94931%cache.CacheStopAndDestroySelfTest1%][GridCacheProcessor]
Started cache [name=cache_near, mode=PARTITIONED]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,097][INFO
][exchange-worker-#94938%cache.CacheStopAndDestroySelfTest0%][GridCacheProcessor]
Started cache [name=cache_near, mode=PARTITIONED]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,137][INFO
][exchange-worker-#94931%cache.CacheStopAndDestroySelfTest1%][GridCachePartitionExchangeManager]
Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=3, minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT,
node=c34b495f-3427-4697-8b0a-9ecf01300000]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,139][INFO
][exchange-worker-#94938%cache.CacheStopAndDestroySelfTest0%][GridCachePartitionExchangeManager]
Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=3, minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT,
node=c34b495f-3427-4697-8b0a-9ecf01300000]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,141][INFO
][exchange-worker-#94927%cache.CacheStopAndDestroySelfTest2%][GridCacheProcessor]
Started cache [name=cache_near, mode=PARTITIONED]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,145][INFO
][exchange-worker-#94931%cache.CacheStopAndDestroySelfTest1%][GridCachePartitionExchangeManager]
Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=3, minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT,
node=b6613820-be62-4d0b-9438-0d4eb5200002]
[09:20:19] : [org.apache.ignite:ignite-core] [09:20:19,145][INFO
][exchange-worker-#94938%cache.CacheStopAndDestroySelfTest0%][GridCachePartitionExchangeManager]
Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=3, minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT,
node=b6613820-be62-4d0b-9438-0d4eb5200002]
[09:20:20] : [org.apache.ignite:ignite-core] [09:20:20,184][INFO
][exchange-worker-#94927%cache.CacheStopAndDestroySelfTest2%][GridCacheProcessor]
Stopped cache: cache_near
[09:20:20]W: [org.apache.ignite:ignite-core]
[09:20:20,186][ERROR][sys-#94769%cache.CacheStopAndDestroySelfTest2%][GridCacheIoManager]
Failed to process message [senderId=c34b495f-3427-4697-8b0a-9ecf01300000,
messageType=class
o.a.i.i.processors.cache.distributed.dht.GridDhtTxPrepareRequest]
[09:20:20]W: [org.apache.ignite:ignite-core]
java.lang.AssertionError: Failed to find cache context [cacheId=28897989,
readyTopVer=AffinityTopologyVersion [topVer=3, minorTopVer=3]]
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.transactions.IgniteTxEntry.unmarshal(IgniteTxEntry.java:842)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.GridCacheMessage.unmarshalTx(GridCacheMessage.java:407)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareRequest.finishUnmarshal(GridDhtTxPrepareRequest.java:325)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.GridCacheIoManager.unmarshall(GridCacheIoManager.java:1038)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.GridCacheIoManager.onMessage0(GridCacheIoManager.java:275)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.GridCacheIoManager.handleMessage(GridCacheIoManager.java:204)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.GridCacheIoManager.access$000(GridCacheIoManager.java:80)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.processors.cache.GridCacheIoManager$1.onMessage(GridCacheIoManager.java:163)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.managers.communication.GridIoManager.processRegularMessage0(GridIoManager.java:819)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.managers.communication.GridIoManager.access$1600(GridIoManager.java:103)
[09:20:20]W: [org.apache.ignite:ignite-core] at
org.apache.ignite.internal.managers.communication.GridIoManager$5.run(GridIoManager.java:782)
[09:20:20]W: [org.apache.ignite:ignite-core] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[09:20:20]W: [org.apache.ignite:ignite-core] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[09:20:20]W: [org.apache.ignite:ignite-core] at
java.lang.Thread.run(Thread.java:745)
[09:20:20]W: [org.apache.ignite:ignite-core] Exception in thread
"sys-#94769%cache.CacheStopAndDestroySelfTest2%" java.lang.AssertionError:
Failed to find cache context [cacheId=28897989,
readyTopVer=AffinityTopologyVersion [topVer=3, minorTopVer=3]]
[09:20:20] : [org.apache.ignite:ignite-core] [09:20:20,189][INFO
][exchange-worker-#94931%cache.CacheStopAndDestroySelfTest1%][GridCachePartitionExchangeManager]
Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=3, minorTopVer=3], evt=DISCOVERY_CUSTOM_EVT,
node=b6613820-be62-4d0b-9438-0d4eb5200002]
[09:20:20] : [org.apache.ignite:ignite-core] [09:20:20,189][INFO
][exchange-worker-#94938%cache.CacheStopAndDestroySelfTest0%][GridCachePartitionExchangeManager]
Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=3, minorTopVer=3], evt=DISCOVERY_CUSTOM_EVT,
node=b6613820-be62-4d0b-9438-0d4eb5200002]
{code}
Message gets sent when near cache still exists, but gets processed when near
cache has been already destroyed.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
