You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Anton Vinogradov (JIRA)" <ji...@apache.org> on 2015/10/01 13:48:26 UTC

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

Anton Vinogradov created IGNITE-1600:
----------------------------------------

             Summary: [Test Failed] IgniteCacheEntryListenerTxTest.testEvents
                 Key: IGNITE-1600
                 URL: https://issues.apache.org/jira/browse/IGNITE-1600
             Project: Ignite
          Issue Type: Test
    Affects Versions: ignite-1.5
            Reporter: Anton Vinogradov
            Priority: Blocker
             Fix For: ignite-1.5


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)