You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Vladimir Ershov (JIRA)" <ji...@apache.org> on 2016/01/22 13:45:39 UTC
[jira] [Commented] (IGNITE-1389)
GridCacheAbstractFullApiSelfTest.testWithSkipStore fails periodically on
different configuration
[ https://issues.apache.org/jira/browse/IGNITE-1389?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15112380#comment-15112380 ]
Vladimir Ershov commented on IGNITE-1389:
-----------------------------------------
JDK8. Local reproduction:
{noformat}
C:\java\jdk8\bin\java -ea -Dfile.encoding=windows-1251 -classpath "C:\Program Files (x86)\JetBrains\IntelliJ IDEA Community Edition 14.1.5\lib\idea_rt.jar;C:\Program Files (x86)\JetBrains\IntelliJ IDEA Community Edition 14.1.5\plugins\junit\lib\junit-rt.jar;C:\java\jdk8\jre\lib\charsets.jar;C:\java\jdk8\jre\lib\deploy.jar;C:\java\jdk8\jre\lib\javaws.jar;C:\java\jdk8\jre\lib\jce.jar;C:\java\jdk8\jre\lib\jfr.jar;C:\java\jdk8\jre\lib\jfxswt.jar;C:\java\jdk8\jre\lib\jsse.jar;C:\java\jdk8\jre\lib\management-agent.jar;C:\java\jdk8\jre\lib\plugin.jar;C:\java\jdk8\jre\lib\resources.jar;C:\java\jdk8\jre\lib\rt.jar;C:\java\jdk8\jre\lib\ext\access-bridge-64.jar;C:\java\jdk8\jre\lib\ext\cldrdata.jar;C:\java\jdk8\jre\lib\ext\dnsns.jar;C:\java\jdk8\jre\lib\ext\jaccess.jar;C:\java\jdk8\jre\lib\ext\jfxrt.jar;C:\java\jdk8\jre\lib\ext\localedata.jar;C:\java\jdk8\jre\lib\ext\nashorn.jar;C:\java\jdk8\jre\lib\ext\sunec.jar;C:\java\jdk8\jre\lib\ext\sunjce_provider.jar;C:\java\jdk8\jre\lib\ext\sunmscapi.jar;C:\java\jdk8\jre\lib\ext\sunpkcs11.jar;C:\java\jdk8\jre\lib\ext\zipfs.jar;C:\gridgain\incubator-ignite\modules\core\target\test-classes;C:\gridgain\incubator-ignite\modules\core\target\classes;C:\Users\agura\.m2\repository\javax\cache\cache-api\1.0.0\cache-api-1.0.0.jar;C:\Users\agura\.m2\repository\mx4j\mx4j-tools\3.0.1\mx4j-tools-3.0.1.jar;C:\Users\agura\.m2\repository\commons-dbcp\commons-dbcp\1.4\commons-dbcp-1.4.jar;C:\Users\agura\.m2\repository\commons-pool\commons-pool\1.5.4\commons-pool-1.5.4.jar;C:\Users\agura\.m2\repository\com\thoughtworks\xstream\xstream\1.4.8\xstream-1.4.8.jar;C:\Users\agura\.m2\repository\xmlpull\xmlpull\1.1.3.1\xmlpull-1.1.3.1.jar;C:\Users\agura\.m2\repository\xpp3\xpp3_min\1.1.4c\xpp3_min-1.1.4c.jar;C:\Users\agura\.m2\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;C:\Users\agura\.m2\repository\org\hsqldb\hsqldb\1.8.0.10\hsqldb-1.8.0.10.jar;C:\Users\agura\.m2\repository\com\h2database\h2\1.3.175\h2-1.3.175.jar;C:\Users\agura\.m2\repository\org\mockito\mockito-all\1.9.5\mockito-all-1.9.5.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-servlets\9.2.11.v20150529\jetty-servlets-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-continuation\9.2.11.v20150529\jetty-continuation-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-http\9.2.11.v20150529\jetty-http-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-util\9.2.11.v20150529\jetty-util-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-io\9.2.11.v20150529\jetty-io-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-webapp\9.2.11.v20150529\jetty-webapp-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-xml\9.2.11.v20150529\jetty-xml-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-servlet\9.2.11.v20150529\jetty-servlet-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-security\9.2.11.v20150529\jetty-security-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\org\eclipse\jetty\jetty-server\9.2.11.v20150529\jetty-server-9.2.11.v20150529.jar;C:\Users\agura\.m2\repository\javax\servlet\javax.servlet-api\3.1.0\javax.servlet-api-3.1.0.jar;C:\Users\agura\.m2\repository\com\esotericsoftware\kryo\kryo\2.20\kryo-2.20.jar;C:\Users\agura\.m2\repository\com\esotericsoftware\reflectasm\reflectasm\1.07\reflectasm-1.07-shaded.jar;C:\Users\agura\.m2\repository\org\ow2\asm\asm\4.0\asm-4.0.jar;C:\Users\agura\.m2\repository\com\esotericsoftware\minlog\minlog\1.2\minlog-1.2.jar;C:\Users\agura\.m2\repository\org\objenesis\objenesis\1.2\objenesis-1.2.jar;C:\Users\agura\.m2\repository\c3p0\c3p0\0.9.1\c3p0-0.9.1.jar;C:\Users\agura\.m2\repository\com\google\guava\guava\14.0.1\guava-14.0.1.jar;C:\Users\agura\.m2\repository\net\sf\json-lib\json-lib\2.4\json-lib-2.4-jdk15.jar;C:\Users\agura\.m2\repository\commons-beanutils\commons-beanutils\1.8.0\commons-beanutils-1.8.0.jar;C:\Users\agura\.m2\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;C:\Users\agura\.m2\repository\commons-lang\commons-lang\2.5\commons-lang-2.5.jar;C:\Users\agura\.m2\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;C:\Users\agura\.m2\repository\net\sf\ezmorph\ezmorph\1.0.6\ezmorph-1.0.6.jar;C:\Users\agura\.m2\repository\org\gridgain\ignite-shmem\1.0.0\ignite-shmem-1.0.0.jar;C:\Users\agura\.m2\repository\log4j\log4j\1.2.17\log4j-1.2.17.jar;C:\Users\agura\.m2\repository\org\springframework\spring-beans\4.1.0.RELEASE\spring-beans-4.1.0.RELEASE.jar;C:\Users\agura\.m2\repository\org\springframework\spring-core\4.1.0.RELEASE\spring-core-4.1.0.RELEASE.jar;C:\Users\agura\.m2\repository\org\springframework\spring-context\4.1.0.RELEASE\spring-context-4.1.0.RELEASE.jar;C:\Users\agura\.m2\repository\org\springframework\spring-aop\4.1.0.RELEASE\spring-aop-4.1.0.RELEASE.jar;C:\Users\agura\.m2\repository\aopalliance\aopalliance\1.0\aopalliance-1.0.jar;C:\Users\agura\.m2\repository\org\springframework\spring-expression\4.1.0.RELEASE\spring-expression-4.1.0.RELEASE.jar;C:\Users\agura\.m2\repository\commons-io\commons-io\2.4\commons-io-2.4.jar;C:\Users\agura\.m2\repository\org\apache\ignite\binary\test1\1.1\test1-1.1.jar;C:\Users\agura\.m2\repository\org\apache\ignite\binary\test2\1.1\test2-1.1.jar;C:\Users\agura\.m2\repository\junit\junit\4.11\junit-4.11.jar;C:\Users\agura\.m2\repository\org\hamcrest\hamcrest-core\1.3\hamcrest-core-1.3.jar;C:\gridgain\incubator-ignite\modules\apache-license-gen\target\classes;C:\java\jdk8\lib\tools.jar" com.intellij.rt.execution.junit.JUnitStarter -ideVersion5 -junit3 org.apache.ignite.internal.processors.cache.distributed.near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest,testWithSkipStore
Configured log4j from: C:\gridgain\incubator-ignite\modules\core\src\test\config\log4j-test.xml
[15:24:06,584][INFO ][main][root] >>> Starting test class: GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest <<<
[15:24:08,941][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0]
>>> __________ ________________
>>> / _/ ___/ |/ / _/_ __/ __/
>>> _/ // (7 7 // / / / / _/
>>> /___/\___/_/|_/___/ /_/ /___/
>>>
>>> ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575
>>> 2016 Copyright(C) Apache Software Foundation
>>>
>>> Ignite documentation: http://ignite.apache.org
[15:24:08,943][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Config URL: n/a
[15:24:08,943][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Daemon mode: off
[15:24:08,943][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] OS: Windows 7 6.1 amd64
[15:24:08,944][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] OS user: agura
[15:24:08,944][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Language runtime: Java Platform API Specification ver. 1.8
[15:24:08,945][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] VM information: Java(TM) SE Runtime Environment 1.8.0_66-b18 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.66-b18
[15:24:08,946][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] VM total memory: 1.8GB
[15:24:08,947][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Remote Management [restart: off, REST: off, JMX (remote: off)]
[15:24:08,947][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] IGNITE_HOME=C:\gridgain\incubator-ignite
[15:24:08,947][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] VM arguments: [-ea, -Dfile.encoding=windows-1251]
[15:24:08,948][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[15:24:08,948][WARN ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[15:24:09,009][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] 3-rd party licenses can be found at: C:\gridgain\incubator-ignite\libs\licenses
[15:24:09,010][WARN ][pub-#6%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiagnostic] Initial heap size is 128MB (should be no less than 512MB, use -Xms512m -Xmx512m).
[15:24:10,053][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Non-loopback local IPs: 192.168.1.102, fe80:0:0:0:0:5efe:c0a8:166%net5, fe80:0:0:0:5557:81a1:f7b1:9ea8%eth3
[15:24:10,054][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Enabled local MACs: 00000000000000E0, 5404A6174132
[15:24:10,386][INFO ][main][IgnitePluginProcessor] Configured plugins:
[15:24:10,386][INFO ][main][IgnitePluginProcessor] ^-- None
[15:24:10,386][INFO ][main][IgnitePluginProcessor]
[15:24:11,811][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP port [port=45010, locHost=/127.0.0.1]
[15:24:12,708][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[15:24:12,781][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Security status [authentication=off, tls/ssl=off]
[15:24:18,860][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port [port=47500, localHost=/127.0.0.1]
[15:24:22,297][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[15:24:22,421][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[15:24:23,103][INFO ][main][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[15:24:23,129][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[15:24:24,039][INFO ][exchange-worker-#44%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=1, minorTopVer=0], evt=NODE_JOINED, node=8047d395-6234-474e-a5e5-2e2befb00000]
[15:24:24,270][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] Performance suggestions for grid 'near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0' (fix if possible)
[15:24:24,270][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[15:24:24,271][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[15:24:24,271][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[15:24:24,271][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[15:24:24,271][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Use default binary marshaller (do not set 'marshaller' explicitly)
[15:24:24,272][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Disable near cache (set 'nearConfiguration' to null)
[15:24:24,272][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Decrease number of backups (set 'keyBackups' to 0)
[15:24:24,273][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[15:24:24,273][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Disable swap store (set 'swapEnabled' to false)
[15:24:24,273][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[15:24:24,274][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0]
[15:24:24,274][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[15:24:24,276][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0]
[15:24:24,280][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0]
>>> +-------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010
>>> +-------------------------------------------------------------------------------------+
>>> OS name: Windows 7 6.1 amd64
>>> CPU(s): 8
>>> Heap: 1.8GB
>>> VM name: 11928@work
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0
>>> Local node [ID=8047D395-6234-474E-A5E5-2E2BEFB00000, order=1, clientMode=false]
>>> Local node addresses: [127.0.0.1]
>>> Local ports: TCP:45010 TCP:47500
[15:24:24,291][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=1, servers=1, clients=0, CPUs=8, heap=1.8GB]
[15:24:24,303][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1]
>>> __________ ________________
>>> / _/ ___/ |/ / _/_ __/ __/
>>> _/ // (7 7 // / / / / _/
>>> /___/\___/_/|_/___/ /_/ /___/
>>>
>>> ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575
>>> 2016 Copyright(C) Apache Software Foundation
>>>
>>> Ignite documentation: http://ignite.apache.org
[15:24:24,304][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Config URL: n/a
[15:24:24,304][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Daemon mode: off
[15:24:24,304][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] OS: Windows 7 6.1 amd64
[15:24:24,304][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] OS user: agura
[15:24:24,305][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Language runtime: Java Platform API Specification ver. 1.8
[15:24:24,305][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] VM information: Java(TM) SE Runtime Environment 1.8.0_66-b18 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.66-b18
[15:24:24,305][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] VM total memory: 1.8GB
[15:24:24,306][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Remote Management [restart: off, REST: off, JMX (remote: off)]
[15:24:24,306][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] IGNITE_HOME=C:\gridgain\incubator-ignite
[15:24:24,307][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] VM arguments: [-ea, -Dfile.encoding=windows-1251]
[15:24:24,307][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[15:24:24,308][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] 3-rd party licenses can be found at: C:\gridgain\incubator-ignite\libs\licenses
[15:24:24,307][WARN ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[15:24:24,308][WARN ][pub-#50%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDiagnostic] Initial heap size is 128MB (should be no less than 512MB, use -Xms512m -Xmx512m).
[15:24:24,411][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Non-loopback local IPs: 192.168.1.102, fe80:0:0:0:0:5efe:c0a8:166%net5, fe80:0:0:0:5557:81a1:f7b1:9ea8%eth3
[15:24:24,412][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Enabled local MACs: 00000000000000E0, 5404A6174132
[15:24:24,412][INFO ][main][IgnitePluginProcessor] Configured plugins:
[15:24:24,412][INFO ][main][IgnitePluginProcessor] ^-- None
[15:24:24,412][INFO ][main][IgnitePluginProcessor]
[15:24:24,449][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP port [port=45011, locHost=/127.0.0.1]
[15:24:24,454][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[15:24:24,481][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Security status [authentication=off, tls/ssl=off]
[15:24:24,498][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port [port=47501, localHost=/127.0.0.1]
[15:24:24,908][INFO ][disco-event-worker-#41%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465464720, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]
[15:24:24,914][INFO ][disco-event-worker-#41%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=2, servers=2, clients=0, CPUs=8, heap=1.8GB]
[15:24:24,971][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[15:24:24,994][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[15:24:24,996][INFO ][main][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[15:24:24,997][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[15:24:26,096][INFO ][aff-#91%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode
[15:24:26,100][INFO ][aff-#91%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.configuration.DeploymentMode
[15:24:26,103][INFO ][aff-#91%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.lang.IgniteProductVersion
[15:24:26,253][INFO ][exchange-worker-#44%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=2, minorTopVer=0], evt=NODE_JOINED, node=7aec2170-339d-4ee7-932b-3f7a60b00001]
[15:24:26,287][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-sys-cache, waitList=[]]
[15:24:26,287][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-marshaller-sys-cache, waitList=[]]
[15:24:26,288][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-atomics-sys-cache, waitList=[ignite-sys-cache, ignite-marshaller-sys-cache]]
[15:24:26,288][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=null, waitList=[ignite-sys-cache, ignite-marshaller-sys-cache, ignite-atomics-sys-cache]]
[15:24:26,289][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Rebalancing required[top=AffinityTopologyVersion [topVer=2, minorTopVer=0], evt=NODE_JOINED, node=7aec2170-339d-4ee7-932b-3f7a60b00001]
[15:24:26,289][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Starting rebalancing [cache=ignite-marshaller-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=20, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], updateSeq=1]
[15:24:26,319][INFO ][sys-#66%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <ignite-sys-cache> Starting rebalancing [cache=ignite-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=100, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], updateSeq=1]
[15:24:26,381][INFO ][marshaller-cache-#95%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Completed (final) rebalancing [cache=ignite-marshaller-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], time=90 ms]
[15:24:26,385][INFO ][marshaller-cache-#95%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <ignite-sys-cache> Completed (final) rebalancing [cache=ignite-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], time=60 ms]
[15:24:26,386][INFO ][sys-#66%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Starting rebalancing [cache=ignite-atomics-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=500, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], updateSeq=1]
[15:24:26,403][INFO ][sys-#67%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Completed (final) rebalancing [cache=ignite-atomics-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], time=10 ms]
[15:24:26,404][INFO ][sys-#66%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <default> Starting rebalancing [cache=null, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=256, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], updateSeq=1]
[15:24:26,443][INFO ][sys-#68%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDhtPartitionDemander] <default> Completed (final) rebalancing [cache=null, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=2, minorTopVer=0], time=40 ms]
[15:24:26,460][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] Performance suggestions for grid 'near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1' (fix if possible)
[15:24:26,460][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[15:24:26,460][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[15:24:26,461][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[15:24:26,461][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[15:24:26,461][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Use default binary marshaller (do not set 'marshaller' explicitly)
[15:24:26,461][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Disable near cache (set 'nearConfiguration' to null)
[15:24:26,462][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Decrease number of backups (set 'keyBackups' to 0)
[15:24:26,462][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[15:24:26,462][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Disable swap store (set 'swapEnabled' to false)
[15:24:26,463][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[15:24:26,463][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1]
[15:24:26,463][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[15:24:26,463][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1]
[15:24:26,464][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1]
>>> +-------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010
>>> +-------------------------------------------------------------------------------------+
>>> OS name: Windows 7 6.1 amd64
>>> CPU(s): 8
>>> Heap: 1.8GB
>>> VM name: 11928@work
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1
>>> Local node [ID=7AEC2170-339D-4EE7-932B-3F7A60B00001, order=2, clientMode=false]
>>> Local node addresses: [127.0.0.1]
>>> Local ports: TCP:45011 TCP:47501
[15:24:26,465][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=2, servers=2, clients=0, CPUs=8, heap=1.8GB]
[15:24:26,472][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2]
>>> __________ ________________
>>> / _/ ___/ |/ / _/_ __/ __/
>>> _/ // (7 7 // / / / / _/
>>> /___/\___/_/|_/___/ /_/ /___/
>>>
>>> ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575
>>> 2016 Copyright(C) Apache Software Foundation
>>>
>>> Ignite documentation: http://ignite.apache.org
[15:24:26,473][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Config URL: n/a
[15:24:26,473][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Daemon mode: off
[15:24:26,473][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] OS: Windows 7 6.1 amd64
[15:24:26,473][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] OS user: agura
[15:24:26,474][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Language runtime: Java Platform API Specification ver. 1.8
[15:24:26,474][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] VM information: Java(TM) SE Runtime Environment 1.8.0_66-b18 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.66-b18
[15:24:26,474][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] VM total memory: 1.8GB
[15:24:26,474][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Remote Management [restart: off, REST: off, JMX (remote: off)]
[15:24:26,474][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] IGNITE_HOME=C:\gridgain\incubator-ignite
[15:24:26,475][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] VM arguments: [-ea, -Dfile.encoding=windows-1251]
[15:24:26,475][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[15:24:26,476][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] 3-rd party licenses can be found at: C:\gridgain\incubator-ignite\libs\licenses
[15:24:26,475][WARN ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[15:24:26,476][WARN ][pub-#100%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDiagnostic] Initial heap size is 128MB (should be no less than 512MB, use -Xms512m -Xmx512m).
[15:24:26,587][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Non-loopback local IPs: 192.168.1.102, fe80:0:0:0:0:5efe:c0a8:166%net5, fe80:0:0:0:5557:81a1:f7b1:9ea8%eth3
[15:24:26,587][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Enabled local MACs: 00000000000000E0, 5404A6174132
[15:24:26,588][INFO ][main][IgnitePluginProcessor] Configured plugins:
[15:24:26,588][INFO ][main][IgnitePluginProcessor] ^-- None
[15:24:26,588][INFO ][main][IgnitePluginProcessor]
[15:24:26,592][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP port [port=45012, locHost=/127.0.0.1]
[15:24:26,598][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[15:24:26,614][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Security status [authentication=off, tls/ssl=off]
[15:24:26,622][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port [port=47502, localHost=/127.0.0.1]
[15:24:26,649][INFO ][disco-event-worker-#41%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=915a6c4c-043a-4da1-8786-fe1b05700002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1453465466626, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]
[15:24:26,650][INFO ][disco-event-worker-#87%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=915a6c4c-043a-4da1-8786-fe1b05700002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1453465466626, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]
[15:24:26,650][INFO ][disco-event-worker-#41%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=8, heap=1.8GB]
[15:24:26,651][INFO ][disco-event-worker-#87%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=8, heap=1.8GB]
[15:24:26,933][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[15:24:26,935][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[15:24:26,938][INFO ][main][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[15:24:26,940][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[15:24:26,952][INFO ][aff-#141%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode
[15:24:26,954][INFO ][aff-#141%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.configuration.DeploymentMode
[15:24:26,954][INFO ][aff-#141%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.lang.IgniteProductVersion
[15:24:27,066][INFO ][exchange-worker-#44%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=3, minorTopVer=0], evt=NODE_JOINED, node=915a6c4c-043a-4da1-8786-fe1b05700002]
[15:24:27,071][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=3, minorTopVer=0], evt=NODE_JOINED, node=915a6c4c-043a-4da1-8786-fe1b05700002]
[15:24:27,075][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-sys-cache, waitList=[]]
[15:24:27,076][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-marshaller-sys-cache, waitList=[]]
[15:24:27,076][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-atomics-sys-cache, waitList=[ignite-sys-cache, ignite-marshaller-sys-cache]]
[15:24:27,076][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=null, waitList=[ignite-sys-cache, ignite-marshaller-sys-cache, ignite-atomics-sys-cache]]
[15:24:27,077][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridCachePartitionExchangeManager] Rebalancing required[top=AffinityTopologyVersion [topVer=3, minorTopVer=0], evt=NODE_JOINED, node=915a6c4c-043a-4da1-8786-fe1b05700002]
[15:24:27,078][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Starting rebalancing [cache=ignite-marshaller-sys-cache, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=12, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,081][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Starting rebalancing [cache=ignite-marshaller-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=8, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,082][INFO ][sys-#117%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-sys-cache> Starting rebalancing [cache=ignite-sys-cache, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=48, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,084][INFO ][sys-#117%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-sys-cache> Starting rebalancing [cache=ignite-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=52, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,086][INFO ][marshaller-cache-#145%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Completed rebalancing [cache=ignite-marshaller-sys-cache, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=10 ms]
[15:24:27,087][INFO ][marshaller-cache-#147%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Completed (final) rebalancing [cache=ignite-marshaller-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=10 ms]
[15:24:27,088][INFO ][marshaller-cache-#145%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-sys-cache> Completed rebalancing [cache=ignite-sys-cache, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=0 ms]
[15:24:27,090][INFO ][marshaller-cache-#147%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-sys-cache> Completed (final) rebalancing [cache=ignite-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=0 ms]
[15:24:27,091][INFO ][sys-#117%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Starting rebalancing [cache=ignite-atomics-sys-cache, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=187, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,092][INFO ][sys-#117%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Starting rebalancing [cache=ignite-atomics-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=169, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,097][INFO ][sys-#118%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Completed rebalancing [cache=ignite-atomics-sys-cache, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=0 ms]
[15:24:27,099][INFO ][sys-#119%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Completed (final) rebalancing [cache=ignite-atomics-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=0 ms]
[15:24:27,100][INFO ][sys-#117%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <default> Starting rebalancing [cache=null, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=84, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,101][INFO ][sys-#117%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <default> Starting rebalancing [cache=null, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=86, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], updateSeq=1]
[15:24:27,106][INFO ][sys-#120%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <default> Completed rebalancing [cache=null, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=10 ms]
[15:24:27,108][INFO ][sys-#121%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDhtPartitionDemander] <default> Completed (final) rebalancing [cache=null, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=3, minorTopVer=0], time=10 ms]
[15:24:27,129][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] Performance suggestions for grid 'near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2' (fix if possible)
[15:24:27,129][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[15:24:27,129][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[15:24:27,130][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[15:24:27,130][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[15:24:27,130][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Use default binary marshaller (do not set 'marshaller' explicitly)
[15:24:27,131][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Disable near cache (set 'nearConfiguration' to null)
[15:24:27,131][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Decrease number of backups (set 'keyBackups' to 0)
[15:24:27,132][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[15:24:27,132][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Disable swap store (set 'swapEnabled' to false)
[15:24:27,132][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[15:24:27,133][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2]
[15:24:27,133][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[15:24:27,133][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2]
[15:24:27,134][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2]
>>> +-------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010
>>> +-------------------------------------------------------------------------------------+
>>> OS name: Windows 7 6.1 amd64
>>> CPU(s): 8
>>> Heap: 1.8GB
>>> VM name: 11928@work
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2
>>> Local node [ID=915A6C4C-043A-4DA1-8786-FE1B05700002, order=3, clientMode=false]
>>> Local node addresses: [127.0.0.1]
>>> Local ports: TCP:45012 TCP:47502
[15:24:27,136][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=8, heap=1.8GB]
[15:24:27,167][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3]
>>> __________ ________________
>>> / _/ ___/ |/ / _/_ __/ __/
>>> _/ // (7 7 // / / / / _/
>>> /___/\___/_/|_/___/ /_/ /___/
>>>
>>> ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575
>>> 2016 Copyright(C) Apache Software Foundation
>>>
>>> Ignite documentation: http://ignite.apache.org
[15:24:27,168][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Config URL: n/a
[15:24:27,169][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Daemon mode: off
[15:24:27,169][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] OS: Windows 7 6.1 amd64
[15:24:27,169][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] OS user: agura
[15:24:27,170][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Language runtime: Java Platform API Specification ver. 1.8
[15:24:27,170][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] VM information: Java(TM) SE Runtime Environment 1.8.0_66-b18 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.66-b18
[15:24:27,170][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] VM total memory: 1.8GB
[15:24:27,171][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
[15:24:27,171][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] IGNITE_HOME=C:\gridgain\incubator-ignite
[15:24:27,171][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] VM arguments: [-ea, -Dfile.encoding=windows-1251]
[15:24:27,172][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[15:24:27,173][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] 3-rd party licenses can be found at: C:\gridgain\incubator-ignite\libs\licenses
[15:24:27,172][WARN ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[15:24:27,173][WARN ][pub-#154%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDiagnostic] Initial heap size is 128MB (should be no less than 512MB, use -Xms512m -Xmx512m).
[15:24:27,297][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Non-loopback local IPs: 192.168.1.102, fe80:0:0:0:0:5efe:c0a8:166%net5, fe80:0:0:0:5557:81a1:f7b1:9ea8%eth3
[15:24:27,298][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Enabled local MACs: 00000000000000E0, 5404A6174132
[15:24:27,299][INFO ][main][IgnitePluginProcessor] Configured plugins:
[15:24:27,299][INFO ][main][IgnitePluginProcessor] ^-- None
[15:24:27,299][INFO ][main][IgnitePluginProcessor]
[15:24:27,308][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP port [port=45013, locHost=/127.0.0.1]
[15:24:27,314][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[15:24:27,315][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Security status [authentication=off, tls/ssl=off]
[15:24:27,323][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port [port=47503, localHost=/127.0.0.1]
[15:24:27,360][INFO ][disco-event-worker-#41%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467321, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]
[15:24:27,361][INFO ][disco-event-worker-#41%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=8, heap=1.8GB]
[15:24:27,361][INFO ][disco-event-worker-#87%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]
[15:24:27,363][INFO ][disco-event-worker-#138%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]
[15:24:27,363][INFO ][disco-event-worker-#87%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=8, heap=1.8GB]
[15:24:27,364][INFO ][disco-event-worker-#138%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=8, heap=1.8GB]
[15:24:27,368][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[15:24:27,371][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[15:24:27,373][INFO ][main][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[15:24:27,377][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[15:24:27,397][INFO ][aff-#197%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode
[15:24:27,399][INFO ][aff-#197%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.configuration.DeploymentMode
[15:24:27,401][INFO ][aff-#197%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDeploymentLocalStore] Class locally deployed: class org.apache.ignite.lang.IgniteProductVersion
[15:24:27,482][INFO ][exchange-worker-#44%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=4, minorTopVer=0], evt=NODE_JOINED, node=79c829a9-1cd3-4c39-9475-64485ab00003]
[15:24:27,483][INFO ][exchange-worker-#140%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=4, minorTopVer=0], evt=NODE_JOINED, node=79c829a9-1cd3-4c39-9475-64485ab00003]
[15:24:27,488][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-sys-cache, waitList=[]]
[15:24:27,488][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-marshaller-sys-cache, waitList=[]]
[15:24:27,488][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=ignite-atomics-sys-cache, waitList=[ignite-sys-cache, ignite-marshaller-sys-cache]]
[15:24:27,489][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridCachePartitionExchangeManager] Cache rebalancing scheduled: [cache=null, waitList=[ignite-sys-cache, ignite-marshaller-sys-cache, ignite-atomics-sys-cache]]
[15:24:27,489][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridCachePartitionExchangeManager] Rebalancing required[top=AffinityTopologyVersion [topVer=4, minorTopVer=0], evt=NODE_JOINED, node=79c829a9-1cd3-4c39-9475-64485ab00003]
[15:24:27,490][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Starting rebalancing [cache=ignite-marshaller-sys-cache, mode=SYNC, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, partitionsCount=10, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,494][INFO ][exchange-worker-#89%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1%][GridCachePartitionExchangeManager] Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=4, minorTopVer=0], evt=NODE_JOINED, node=79c829a9-1cd3-4c39-9475-64485ab00003]
[15:24:27,494][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Starting rebalancing [cache=ignite-marshaller-sys-cache, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=6, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,498][INFO ][exchange-worker-#196%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Starting rebalancing [cache=ignite-marshaller-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=4, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,498][INFO ][marshaller-cache-#200%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Completed rebalancing [cache=ignite-marshaller-sys-cache, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=10 ms]
[15:24:27,500][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-sys-cache> Starting rebalancing [cache=ignite-sys-cache, mode=SYNC, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, partitionsCount=39, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,500][INFO ][marshaller-cache-#202%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Completed rebalancing [cache=ignite-marshaller-sys-cache, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=10 ms]
[15:24:27,501][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-sys-cache> Starting rebalancing [cache=ignite-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=27, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,503][INFO ][marshaller-cache-#205%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-marshaller-sys-cache> Completed (final) rebalancing [cache=ignite-marshaller-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=20 ms]
[15:24:27,504][INFO ][utility-#207%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-sys-cache> Completed rebalancing [cache=ignite-sys-cache, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=10 ms]
[15:24:27,503][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-sys-cache> Starting rebalancing [cache=ignite-sys-cache, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=34, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,506][INFO ][marshaller-cache-#205%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-sys-cache> Completed rebalancing [cache=ignite-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=10 ms]
[15:24:27,509][INFO ][utility-#214%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-sys-cache> Completed (final) rebalancing [cache=ignite-sys-cache, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=10 ms]
[15:24:27,513][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Starting rebalancing [cache=ignite-atomics-sys-cache, mode=SYNC, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, partitionsCount=74, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,514][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Starting rebalancing [cache=ignite-atomics-sys-cache, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=74, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,515][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Starting rebalancing [cache=ignite-atomics-sys-cache, mode=SYNC, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, partitionsCount=89, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,517][INFO ][sys-#174%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Completed rebalancing [cache=ignite-atomics-sys-cache, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=0 ms]
[15:24:27,519][INFO ][sys-#175%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Completed rebalancing [cache=ignite-atomics-sys-cache, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=0 ms]
[15:24:27,520][INFO ][sys-#176%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <ignite-atomics-sys-cache> Completed (final) rebalancing [cache=ignite-atomics-sys-cache, fromNode=8047d395-6234-474e-a5e5-2e2befb00000, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=0 ms]
[15:24:27,522][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <default> Starting rebalancing [cache=null, mode=SYNC, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, partitionsCount=64, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,522][INFO ][sys-#173%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <default> Starting rebalancing [cache=null, mode=SYNC, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, partitionsCount=64, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], updateSeq=1]
[15:24:27,526][INFO ][sys-#177%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <default> Completed rebalancing [cache=null, fromNode=915a6c4c-043a-4da1-8786-fe1b05700002, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=0 ms]
[15:24:27,527][INFO ][sys-#178%near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3%][GridDhtPartitionDemander] <default> Completed (final) rebalancing [cache=null, fromNode=7aec2170-339d-4ee7-932b-3f7a60b00001, topology=AffinityTopologyVersion [topVer=4, minorTopVer=0], time=0 ms]
[15:24:27,548][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] Performance suggestions for grid 'near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3' (fix if possible)
[15:24:27,548][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[15:24:27,548][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[15:24:27,549][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[15:24:27,549][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[15:24:27,550][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Use default binary marshaller (do not set 'marshaller' explicitly)
[15:24:27,550][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Disable near cache (set 'nearConfiguration' to null)
[15:24:27,550][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Decrease number of backups (set 'keyBackups' to 0)
[15:24:27,551][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[15:24:27,551][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Disable swap store (set 'swapEnabled' to false)
[15:24:27,552][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[15:24:27,552][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3]
[15:24:27,552][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[15:24:27,553][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3]
[15:24:27,553][INFO ][main][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3]
>>> +-------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010
>>> +-------------------------------------------------------------------------------------+
>>> OS name: Windows 7 6.1 amd64
>>> CPU(s): 8
>>> Heap: 1.8GB
>>> VM name: 11928@work
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3
>>> Local node [ID=79C829A9-1CD3-4C39-9475-64485AB00003, order=4, clientMode=false]
>>> Local node addresses: [127.0.0.1]
>>> Local ports: TCP:45013 TCP:47503
[15:24:27,555][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=8, heap=1.8GB]
[15:24:27,638][WARN ][main][root] Waiting for topology map update [grid=near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], topVer=4, nodeId8=7aec2170, msg=Node joined: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], type=NODE_JOINED, tstamp=1453465467361], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=79c829a9, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1453465467361, endTime=1453465467401, ignoreInterrupts=false, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, initFlag=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1453465467361, oldest=8047d395-6234-474e-a5e5-2e2befb00000, oldestOrder=1, evtLatch=0, remaining=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1453465467361, endTime=1453465467482, ignoreInterrupts=false, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], owners=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465467542, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], locNode=TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465467542, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]]
[15:24:28,248][WARN ][main][root] Waiting for topology map update [grid=near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], topVer=4, nodeId8=7aec2170, msg=Node joined: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], type=NODE_JOINED, tstamp=1453465467361], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=79c829a9, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1453465467361, endTime=1453465467401, ignoreInterrupts=false, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, initFlag=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1453465467361, oldest=8047d395-6234-474e-a5e5-2e2befb00000, oldestOrder=1, evtLatch=0, remaining=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1453465467361, endTime=1453465467482, ignoreInterrupts=false, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], owners=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465468232, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], locNode=TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465468232, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]]
[15:24:29,264][WARN ][main][root] Waiting for topology map update [grid=near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], topVer=4, nodeId8=7aec2170, msg=Node joined: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], type=NODE_JOINED, tstamp=1453465467361], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=79c829a9, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1453465467361, endTime=1453465467401, ignoreInterrupts=false, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, initFlag=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1453465467361, oldest=8047d395-6234-474e-a5e5-2e2befb00000, oldestOrder=1, evtLatch=0, remaining=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1453465467361, endTime=1453465467482, ignoreInterrupts=false, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], owners=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465469132, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], locNode=TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465469132, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]]
[15:24:29,467][WARN ][main][root] Finished waiting for topology map update [grid=near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1, p=0, duration=1825ms]
[15:24:29,469][WARN ][main][root] Waiting for topology map update [grid=near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], topVer=4, nodeId8=7aec2170, msg=Node joined: TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], type=NODE_JOINED, tstamp=1453465467361], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=79c829a9, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1453465467361, endTime=1453465467401, ignoreInterrupts=false, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, initFlag=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1453465467361, oldest=8047d395-6234-474e-a5e5-2e2befb00000, oldestOrder=1, evtLatch=0, remaining=[915a6c4c-043a-4da1-8786-fe1b05700002, 79c829a9-1cd3-4c39-9475-64485ab00003, 8047d395-6234-474e-a5e5-2e2befb00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1453465467361, endTime=1453465467482, ignoreInterrupts=false, state=DONE]], p=14, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], owners=[TcpDiscoveryNode [id=79c829a9-1cd3-4c39-9475-64485ab00003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1453465467331, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false], TcpDiscoveryNode [id=915a6c4c-043a-4da1-8786-fe1b05700002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1453465466626, loc=false, ver=1.5.1#20160122-sha1:243a8575, isClient=false]], locNode=TcpDiscoveryNode [id=7aec2170-339d-4ee7-932b-3f7a60b00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1453465469132, loc=true, ver=1.5.1#20160122-sha1:243a8575, isClient=false]]
[15:24:31,090][WARN ][main][root] Finished waiting for topology map update [grid=near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1, p=14, duration=1617ms]
[15:24:31,140][INFO ][main][root] Grid 0: 8047d395-6234-474e-a5e5-2e2befb00000
[15:24:31,140][INFO ][main][root] Grid 1: 7aec2170-339d-4ee7-932b-3f7a60b00001
[15:24:31,140][INFO ][main][root] Grid 2: 915a6c4c-043a-4da1-8786-fe1b05700002
[15:24:31,140][INFO ][main][root] Grid 3: 79c829a9-1cd3-4c39-9475-64485ab00003
[15:24:31,141][INFO ][main][root] >>> Starting test: testWithSkipStore <<<
[15:24:36,922][ERROR][main][root] Test failed.
junit.framework.AssertionFailedError: Expected: <null> but was: 249
at junit.framework.Assert.fail(Assert.java:57)
at junit.framework.Assert.assertTrue(Assert.java:22)
at junit.framework.Assert.assertNull(Assert.java:277)
at junit.framework.Assert.assertNull(Assert.java:268)
at junit.framework.TestCase.assertNull(TestCase.java:438)
at org.apache.ignite.internal.processors.cache.GridCacheAbstractFullApiSelfTest.testWithSkipStore(GridCacheAbstractFullApiSelfTest.java:4712)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at junit.framework.TestCase.runTest(TestCase.java:176)
at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1699)
at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:116)
at org.apache.ignite.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1637)
at java.lang.Thread.run(Thread.java:745)
[15:24:36,927][INFO ][main][root] >>> Stopping test: testWithSkipStore in 5127 ms <<<
[15:24:36,928][INFO ][main][root] Checking grid: 0
[15:24:37,475][INFO ][main][root] Size after [idx=0, size=0, keySize=0, primarySize=0, globalSize=0, globalPrimarySize=0, entrySet=CacheLocalEntries []]
[15:24:37,479][INFO ][main][root] Checking grid: 1
[15:24:37,512][INFO ][main][root] Size after [idx=1, size=0, keySize=0, primarySize=0, globalSize=0, globalPrimarySize=0, entrySet=CacheLocalEntries []]
[15:24:37,513][INFO ][main][root] Checking grid: 2
[15:24:37,521][INFO ][main][root] Size after [idx=2, size=0, keySize=0, primarySize=0, globalSize=0, globalPrimarySize=0, entrySet=CacheLocalEntries []]
[15:24:37,521][INFO ][main][root] Checking grid: 3
[15:24:37,531][INFO ][main][root] Size after [idx=3, size=0, keySize=0, primarySize=0, globalSize=0, globalPrimarySize=0, entrySet=CacheLocalEntries []]
junit.framework.AssertionFailedError: Expected: <null> but was: 249
at org.apache.ignite.internal.processors.cache.GridCacheAbstractFullApiSelfTest.testWithSkipStore(GridCacheAbstractFullApiSelfTest.java:4712)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1699)
at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:116)
at org.apache.ignite.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1637)
at java.lang.Thread.run(Thread.java:745)
[15:24:37,565][INFO ][Thread-2][G] Invoking shutdown hook...
[15:24:37,566][INFO ][Thread-3][G] Invoking shutdown hook...
[15:24:37,566][INFO ][Thread-4][G] Invoking shutdown hook...
[15:24:37,565][INFO ][Thread-1][G] Invoking shutdown hook...
[15:24:37,669][INFO ][Thread-1][GridCacheProcessor] Stopped cache: null
[15:24:37,671][INFO ][Thread-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[15:24:37,671][INFO ][Thread-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
[15:24:37,671][INFO ][Thread-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[15:24:37,674][INFO ][Thread-4][GridCacheProcessor] Stopped cache: null
[15:24:37,674][INFO ][Thread-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1453465465716, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@6d06d69c, clsLdrId=b4bca496251-8047d395-6234-474e-a5e5-2e2befb00000, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1, pendingUndeploy=false, undeployed=true, usage=0]
[15:24:37,674][INFO ][Thread-3][GridCacheProcessor] Stopped cache: null
[15:24:37,675][INFO ][Thread-4][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[15:24:37,677][INFO ][Thread-4][GridCacheProcessor] Stopped cache: ignite-sys-cache
[15:24:37,677][INFO ][Thread-4][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[15:24:37,678][INFO ][Thread-4][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1453465467391, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@6d06d69c, clsLdrId=8aeca496251-79c829a9-1cd3-4c39-9475-64485ab00003, userVer=0, loc=true, sampleClsName=org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode, pendingUndeploy=false, undeployed=true, usage=0]
[15:24:37,676][INFO ][Thread-3][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[15:24:37,679][INFO ][Thread-3][GridCacheProcessor] Stopped cache: ignite-sys-cache
[15:24:37,680][INFO ][Thread-3][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[15:24:37,680][INFO ][Thread-3][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1453465466951, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@6d06d69c, clsLdrId=30dca496251-915a6c4c-043a-4da1-8786-fe1b05700002, userVer=0, loc=true, sampleClsName=org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode, pendingUndeploy=false, undeployed=true, usage=0]
[15:24:37,683][INFO ][Thread-2][GridCacheProcessor] Stopped cache: null
[15:24:37,683][INFO ][Thread-2][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[15:24:37,684][INFO ][Thread-2][GridCacheProcessor] Stopped cache: ignite-sys-cache
[15:24:37,684][INFO ][Thread-2][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[15:24:37,685][INFO ][Thread-2][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1453465465976, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@6d06d69c, clsLdrId=d4bca496251-7aec2170-339d-4ee7-932b-3f7a60b00001, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[15:24:37,700][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.lang.IgniteProductVersion
[15:24:37,700][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.lang.IgniteProductVersion
[15:24:37,700][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.lang.IgniteProductVersion
[15:24:37,700][INFO ][Thread-1][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheAdapter$RemoveAllTask
[15:24:37,700][INFO ][Thread-3][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheAdapter$RemoveAllTask
[15:24:37,700][INFO ][Thread-2][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheAdapter$RemoveAllTask
[15:24:37,700][INFO ][Thread-4][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.GridDistributedCacheAdapter$RemoveAllTask
[15:24:37,701][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[15:24:37,701][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[15:24:37,701][INFO ][Thread-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap2
[15:24:37,702][INFO ][Thread-3][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAdapter$SizeTask
[15:24:37,702][INFO ][Thread-2][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAdapter$SizeTask
[15:24:37,702][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[15:24:37,703][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[15:24:37,703][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[15:24:37,703][INFO ][Thread-1][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAdapter$SizeTask
[15:24:37,704][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheEntryPredicateNoValue
[15:24:37,704][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheEntryPredicateNoValue
[15:24:37,703][INFO ][Thread-4][GridDeploymentLocalStore] Task locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAdapter$SizeTask
[15:24:37,705][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
[15:24:37,704][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
[15:24:37,704][INFO ][Thread-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
[15:24:37,706][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T6
[15:24:37,705][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T6
[15:24:37,705][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[15:24:37,706][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAbstractFullApiSelfTest$SetValueProcessor
[15:24:37,706][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAbstractFullApiSelfTest$SetValueProcessor
[15:24:37,706][INFO ][Thread-1][GridDeploymentLocalStore] Class locally undeployed: class java.lang.String
[15:24:37,707][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class java.net.InetAddress
[15:24:37,707][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class java.net.InetAddress
[15:24:37,706][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheEntryPredicateNoValue
[15:24:37,708][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap2
[15:24:37,708][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap2
[15:24:37,707][INFO ][Thread-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T6
[15:24:37,708][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.configuration.DeploymentMode
[15:24:37,708][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.configuration.DeploymentMode
[15:24:37,709][INFO ][Thread-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode
[15:24:37,708][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
[15:24:37,709][INFO ][Thread-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode
[15:24:37,708][INFO ][Thread-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.query.GridCacheDistributedQueryManager$7
[15:24:37,710][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheAbstractFullApiSelfTest$SetValueProcessor
[15:24:37,714][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class java.net.InetAddress
[15:24:37,714][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap2
[15:24:37,714][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.configuration.DeploymentMode
[15:24:37,714][INFO ][Thread-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.spi.discovery.tcp.internal.TcpDiscoveryNode
[15:24:37,767][INFO ][Thread-4][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3]
>>> +------------------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010 stopped OK
>>> +------------------------------------------------------------------------------------------------+
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest3
>>> Grid uptime: 00:00:10:221
[15:24:37,767][INFO ][Thread-2][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1]
>>> +------------------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010 stopped OK
>>> +------------------------------------------------------------------------------------------------+
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest1
>>> Grid uptime: 00:00:11:307
[15:24:37,767][INFO ][Thread-1][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0]
>>> +------------------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010 stopped OK
>>> +------------------------------------------------------------------------------------------------+
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest0
>>> Grid uptime: 00:00:13:504
[15:24:37,767][INFO ][Thread-3][GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2]
>>> +------------------------------------------------------------------------------------------------+
>>> Ignite ver. 1.5.1-final-SNAPSHOT#20160122-sha1:243a8575a77bd8986e7bc995e71d4a73a4d37010 stopped OK
>>> +------------------------------------------------------------------------------------------------+
>>> Grid name: near.GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest2
>>> Grid uptime: 00:00:10:642
Process finished with exit code -1
{noformat}
We should mute the test.
> GridCacheAbstractFullApiSelfTest.testWithSkipStore fails periodically on different configuration
> ------------------------------------------------------------------------------------------------
>
> Key: IGNITE-1389
> URL: https://issues.apache.org/jira/browse/IGNITE-1389
> Project: Ignite
> Issue Type: Bug
> Components: cache
> Affects Versions: ignite-1.4
> Reporter: Denis Magda
> Assignee: Denis Magda
>
> The test fails on different configurations in different places.
> As an example, this is a stack trace for {{GridCacheAtomicNearEnabledFairAffinityMultiNodeFullApiSelfTest.testWithSkipStore}} configuration
> {noformat}
> junit.framework.AssertionFailedError: expected:<-1> but was:<21>
> 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:86)
> at junit.framework.TestCase.assertEquals(TestCase.java:253)
> at org.apache.ignite.internal.processors.cache.GridCacheAbstractFullApiSelfTest.testWithSkipStore(GridCacheAbstractFullApiSelfTest.java:4534)
> at sun.reflect.GeneratedMethodAccessor108.invoke(Unknown Source)
> 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)
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)