[
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)
