You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Pavel Tupitsyn (JIRA)" <ji...@apache.org> on 2016/08/09 12:33:31 UTC

[jira] [Updated] (IGNITE-1600) [Test Failed] IgniteCacheEntryListenerTxTest.testEvents

     [ https://issues.apache.org/jira/browse/IGNITE-1600?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Pavel Tupitsyn updated IGNITE-1600:
-----------------------------------
    Fix Version/s:     (was: 1.7)
                   1.8

> [Test Failed] IgniteCacheEntryListenerTxTest.testEvents
> -------------------------------------------------------
>
>                 Key: IGNITE-1600
>                 URL: https://issues.apache.org/jira/browse/IGNITE-1600
>             Project: Ignite
>          Issue Type: Test
>    Affects Versions: 1.5.0.final
>            Reporter: Anton Vinogradov
>            Assignee: Nikolay Tikhonov
>            Priority: Blocker
>              Labels: Muted_test
>             Fix For: 1.8
>
>
> There is no guarantee that test failure will be produced each test run, please analyze logs in case of reproduction problem:
> junit.framework.AssertionFailedError: expected:<11> but was:<12>
>     at junit.framework.Assert.fail(Assert.java:57)
>     at junit.framework.Assert.failNotEquals(Assert.java:329)
>     at junit.framework.Assert.assertEquals(Assert.java:78)
>     at junit.framework.Assert.assertEquals(Assert.java:234)
>     at junit.framework.Assert.assertEquals(Assert.java:241)
>     at junit.framework.TestCase.assertEquals(TestCase.java:409)
>     at org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest.checkEvents(IgniteCacheEntryListenerAbstractTest.java:804)
>     at org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest.checkEvents(IgniteCacheEntryListenerAbstractTest.java:590)
>     at org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest.testEvents(IgniteCacheEntryListenerAbstractTest.java:459)
>     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>     at java.lang.reflect.Method.invoke(Method.java:606)
>     at junit.framework.TestCase.runTest(TestCase.java:176)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111)
>     at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603)
> ------- Stdout: -------
> [12:53:31,916][INFO ][main][root] >>> Starting test: testEvents <<<
> [12:53:31,949][INFO ][test-runner][root] Check create event [key=0]
> [12:53:31,951][INFO ][test-runner][root] Caught expected exception: Listener is already registered for configuration: javax.cache.configuration.MutableCacheEntryListenerConfiguration@23983b48
> [12:53:32,869][INFO ][test-runner][root] Remove listener.
> [12:53:33,374][INFO ][test-runner][root] Check update event [key=0]
> [12:53:33,375][INFO ][test-runner][root] Caught expected exception: Listener is already registered for configuration: javax.cache.configuration.MutableCacheEntryListenerConfiguration@44a48433
> [12:53:34,296][INFO ][main][root] >>> Stopping test: testEvents in 2380 ms <<<
> [12:53:34,296][INFO ][main][root] >>> Stopping test class: IgniteCacheEntryListenerTxTest <<<
> [12:53:34,296][INFO ][main][root] >>> Stopping grid [name=cache.IgniteCacheEntryListenerTxTest2, id=203e1517-5451-4f52-96ec-273e6eb88002]
> [12:53:34,300][INFO ][disco-event-worker-#1150%cache.IgniteCacheEntryListenerTxTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=203e1517-5451-4f52-96ec-273e6eb88002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=3, intOrder=3, lastExchangeTime=1443693189197, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [12:53:34,301][INFO ][disco-event-worker-#1150%cache.IgniteCacheEntryListenerTxTest0%][GridDiscoveryManager] Topology snapshot [ver=8, servers=2, clients=0, CPUs=8, heap=2.7GB]
> [12:53:34,301][INFO ][disco-event-worker-#1153%cache.IgniteCacheEntryListenerTxTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=203e1517-5451-4f52-96ec-273e6eb88002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=3, intOrder=3, lastExchangeTime=1443693189197, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [12:53:34,301][INFO ][disco-event-worker-#1153%cache.IgniteCacheEntryListenerTxTest1%][GridDiscoveryManager] Topology snapshot [ver=8, servers=2, clients=0, CPUs=8, heap=2.7GB]
> [12:53:34,303][INFO ][main][GridCacheProcessor] Stopped cache: null
> [12:53:34,303][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [12:53:34,304][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [12:53:34,304][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [12:53:34,304][INFO ][main][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443693189217, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=929d0d22051-203e1517-5451-4f52-96ec-273e6eb88002, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [12:53:34,304][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [12:53:34,304][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
> [12:53:34,304][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheEntryPredicateNoValue
> [12:53:34,304][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.IgniteExternalizableExpiryPolicy
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.typedef.T2
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest$EntrySetValueProcessor
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Object
> [12:53:34,305][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest$EntryToStringProcessor
> [12:53:34,309][INFO ][main][IgniteCacheEntryListenerTxTest2] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.IgniteCacheEntryListenerTxTest2
> >>> Grid uptime: 00:00:25:63
> [12:53:34,315][INFO ][main][root] >>> Stopping grid [name=cache.IgniteCacheEntryListenerTxTest0, id=00d21a85-3fa7-4ecd-bc4b-9e5f4295c000]
> [12:53:34,334][INFO ][disco-event-worker-#1153%cache.IgniteCacheEntryListenerTxTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=00d21a85-3fa7-4ecd-bc4b-9e5f4295c000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1443693188722, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [12:53:34,334][INFO ][disco-event-worker-#1153%cache.IgniteCacheEntryListenerTxTest1%][GridDiscoveryManager] Topology snapshot [ver=9, servers=1, clients=0, CPUs=8, heap=2.7GB]
> [12:53:34,346][INFO ][main][GridCacheProcessor] Stopped cache: null
> [12:53:34,346][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [12:53:34,346][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [12:53:34,346][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [12:53:34,346][INFO ][main][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443693188742, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=f64d0d22051-00d21a85-3fa7-4ecd-bc4b-9e5f4295c000, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap, pendingUndeploy=false, undeployed=true, usage=0]
> [12:53:34,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.typedef.T2
> [12:53:34,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
> [12:53:34,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
> [12:53:34,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheEntryPredicateNoValue
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest$EntrySetValueProcessor
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.IgniteExternalizableExpiryPolicy
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Object
> [12:53:34,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest$EntryToStringProcessor
> [12:53:34,348][INFO ][main][IgniteCacheEntryListenerTxTest0] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.IgniteCacheEntryListenerTxTest0
> >>> Grid uptime: 00:00:25:601
> [12:53:34,350][INFO ][main][root] >>> Stopping grid [name=cache.IgniteCacheEntryListenerTxTest1, id=10bbbc56-8ef1-4efd-946d-a7d574260001]
> [12:53:34,353][INFO ][main][GridCacheProcessor] Stopped cache: null
> [12:53:34,353][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [12:53:34,353][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [12:53:34,353][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [12:53:34,353][INFO ][main][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443693188742, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=174d0d22051-10bbbc56-8ef1-4efd-946d-a7d574260001, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [12:53:34,353][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheEntryPredicateNoValue
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.IgniteExternalizableExpiryPolicy
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.typedef.T2
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest$EntrySetValueProcessor
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Object
> [12:53:34,354][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest$EntryToStringProcessor
> [12:53:34,355][INFO ][main][IgniteCacheEntryListenerTxTest1] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.IgniteCacheEntryListenerTxTest1
> >>> Grid uptime: 00:00:25:594
> ------- Stderr: -------
> [12:53:34,295][ERROR][main][root] Test failed.
> junit.framework.AssertionFailedError: expected:<11> but was:<12>
>     at junit.framework.Assert.fail(Assert.java:57)
>     at junit.framework.Assert.failNotEquals(Assert.java:329)
>     at junit.framework.Assert.assertEquals(Assert.java:78)
>     at junit.framework.Assert.assertEquals(Assert.java:234)
>     at junit.framework.Assert.assertEquals(Assert.java:241)
>     at junit.framework.TestCase.assertEquals(TestCase.java:409)
>     at org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest.checkEvents(IgniteCacheEntryListenerAbstractTest.java:804)
>     at org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest.checkEvents(IgniteCacheEntryListenerAbstractTest.java:590)
>     at org.apache.ignite.internal.processors.cache.IgniteCacheEntryListenerAbstractTest.testEvents(IgniteCacheEntryListenerAbstractTest.java:459)
>     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>     at java.lang.reflect.Method.invoke(Method.java:606)
>     at junit.framework.TestCase.runTest(TestCase.java:176)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111)
>     at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603)
> [12:53:34,300][WARN ][tcp-disco-sock-reader-#729%cache.IgniteCacheEntryListenerTxTest2][TcpDiscoverySpi] Failed to read magic header (too few bytes received) [rmtAddr=/127.0.0.1:64732, locAddr=/127.0.0.1:47500]



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