You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@ignite.apache.org by "Yakov Zhdanov (JIRA)" <ji...@apache.org> on 2015/04/13 18:01:13 UTC

[jira] [Created] (IGNITE-740) org.apache.ignite.internal.processors.cache.GridCacheTcpClientDiscoveryMultiThreadedTest#testCacheWithServerNodesRestart fails

Yakov Zhdanov created IGNITE-740:
------------------------------------

             Summary: org.apache.ignite.internal.processors.cache.GridCacheTcpClientDiscoveryMultiThreadedTest#testCacheWithServerNodesRestart fails
                 Key: IGNITE-740
                 URL: https://issues.apache.org/jira/browse/IGNITE-740
             Project: Ignite
          Issue Type: Sub-task
            Reporter: Yakov Zhdanov
            Assignee: Valentin Kulichenko
            Priority: Blocker
             Fix For: sprint-3-p1


{code}
[19:03:21,451][INFO ][main][root] >>> Starting test class: GridCacheTcpClientDiscoveryMultiThreadedTest <<<
[19:03:21,452][INFO ][main][root] >>> Starting test: testCacheConcurrentlyWithMultipleClientNodes <<<
[19:03:21,594][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:21,595][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Config URL: n/a
[19:03:21,595][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Daemon mode: off
[19:03:21,595][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] OS: Mac OS X 10.9.5 x86_64
[19:03:21,595][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] OS user: yzhdanov
[19:03:21,596][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Language runtime: Java Platform API Specification ver. 1.7
[19:03:21,596][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:21,598][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] VM total memory: 2.9GB
[19:03:21,598][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:21,599][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:21,599][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:21,600][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[19:03:21,601][WARN ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:21,607][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:21,644][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:21,645][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:21,950][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
[19:03:21,951][INFO ][test-runner][IgnitePluginProcessor]   ^-- None
[19:03:21,951][INFO ][test-runner][IgnitePluginProcessor] 
[19:03:22,012][INFO ][test-runner][TcpCommunicationSpi] Successfully bound to TCP port [port=45010, locHost=/127.0.0.1]
[19:03:22,092][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:22,102][WARN ][test-runner][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:22,105][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Security status [authentication=off]
[19:03:22,283][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47500, localHost=/127.0.0.1]
[19:03:22,429][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:22,460][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:22,472][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:22,486][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:22,609][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:22,614][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:22,614][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:22,615][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:22,638][INFO ][preloader-demand-worker-#57%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=21 ms]
[19:03:22,638][INFO ][preloader-demand-worker-#52%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=21 ms]
[19:03:22,638][INFO ][preloader-demand-worker-#49%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=32 ms]
[19:03:22,638][INFO ][preloader-demand-worker-#63%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=21 ms]
[19:03:22,676][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest0' (fix if possible)
[19:03:22,676][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:22,676][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:22,677][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:22,677][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:22,678][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Disable near cache (set 'nearConfiguration' to null)
[19:03:22,678][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Decrease number of backups (set 'keyBackups' to 0)
[19:03:22,678][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
[19:03:22,679][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[19:03:22,679][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Disable swap store (set 'swapEnabled' to false)
[19:03:22,679][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0]   ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[19:03:22,680][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] 
[19:03:22,680][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:22,680][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] 
[19:03:22,681][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest0] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest0
>>> Local node [ID=F2899FA7-22FD-4AE3-9602-6D6158BB0A06, order=1]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45010 TCP:47500 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:22,683][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=1, nodes=1, CPUs=8, heap=3.0GB]
[19:03:22,691][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:22,692][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Config URL: n/a
[19:03:22,692][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Daemon mode: off
[19:03:22,692][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] OS: Mac OS X 10.9.5 x86_64
[19:03:22,692][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] OS user: yzhdanov
[19:03:22,693][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Language runtime: Java Platform API Specification ver. 1.7
[19:03:22,693][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:22,694][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] VM total memory: 2.9GB
[19:03:22,694][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:22,694][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:22,695][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:22,696][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[19:03:22,697][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:22,696][WARN ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:22,705][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:22,705][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:22,706][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
[19:03:22,706][INFO ][test-runner][IgnitePluginProcessor]   ^-- None
[19:03:22,707][INFO ][test-runner][IgnitePluginProcessor] 
[19:03:22,726][INFO ][test-runner][TcpCommunicationSpi] Successfully bound to TCP port [port=45011, locHost=/127.0.0.1]
[19:03:22,758][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:22,763][WARN ][test-runner][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:22,765][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Security status [authentication=off]
[19:03:22,793][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47501, localHost=/127.0.0.1]
[19:03:22,859][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=c2595bf4-9ec3-4092-a960-489bfdde7ef0, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=false]
[19:03:22,860][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=2, nodes=2, CPUs=8, heap=3.0GB]
[19:03:22,867][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:22,870][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:22,877][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:22,882][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:23,132][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:23,132][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:23,133][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:23,133][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:23,214][INFO ][preloader-demand-worker-#111%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=85 ms]
[19:03:23,223][INFO ][preloader-demand-worker-#127%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=86 ms]
[19:03:23,241][INFO ][preloader-demand-worker-#121%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=108 ms]
[19:03:23,256][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest1' (fix if possible)
[19:03:23,256][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:23,256][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:23,257][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:23,257][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:23,257][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Disable near cache (set 'nearConfiguration' to null)
[19:03:23,257][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Decrease number of backups (set 'keyBackups' to 0)
[19:03:23,257][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
[19:03:23,258][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[19:03:23,258][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Disable swap store (set 'swapEnabled' to false)
[19:03:23,258][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1]   ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[19:03:23,258][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] 
[19:03:23,258][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:23,259][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] 
[19:03:23,259][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest1] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest1
>>> Local node [ID=C2595BF4-9EC3-4092-A960-489BFDDE7EF0, order=2]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45011 TCP:47501 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:23,260][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=2, nodes=2, CPUs=8, heap=3.0GB]
[19:03:23,268][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:23,269][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Config URL: n/a
[19:03:23,269][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Daemon mode: off
[19:03:23,269][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] OS: Mac OS X 10.9.5 x86_64
[19:03:23,269][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] OS user: yzhdanov
[19:03:23,270][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Language runtime: Java Platform API Specification ver. 1.7
[19:03:23,270][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:23,270][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] VM total memory: 2.9GB
[19:03:23,271][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:23,271][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:23,271][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:23,272][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
[19:03:23,273][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:23,272][WARN ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:23,278][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:23,278][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:23,279][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
[19:03:23,279][INFO ][test-runner][IgnitePluginProcessor]   ^-- None
[19:03:23,279][INFO ][test-runner][IgnitePluginProcessor] 
[19:03:23,293][INFO ][test-runner][TcpCommunicationSpi] Successfully bound to TCP port [port=45012, locHost=/127.0.0.1]
[19:03:23,314][INFO ][preloader-demand-worker-#116%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=172 ms]
[19:03:23,316][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:23,319][WARN ][test-runner][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:23,321][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Security status [authentication=off]
[19:03:23,347][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47502, localHost=/127.0.0.1]
[19:03:23,415][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=394472b7-0e1d-4c86-b008-46397925433b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=false]
[19:03:23,416][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=3, nodes=3, CPUs=8, heap=3.0GB]
[19:03:23,416][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=394472b7-0e1d-4c86-b008-46397925433b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=false]
[19:03:23,417][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=3, nodes=3, CPUs=8, heap=3.0GB]
[19:03:23,424][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:23,428][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:23,432][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:23,434][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:23,659][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:23,659][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:23,660][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:23,660][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:23,710][INFO ][preloader-demand-worker-#178%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=48 ms]
[19:03:23,719][INFO ][preloader-demand-worker-#194%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=58 ms]
[19:03:23,722][INFO ][preloader-demand-worker-#188%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=58 ms]
[19:03:23,739][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest2' (fix if possible)
[19:03:23,739][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:23,739][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:23,739][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:23,739][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:23,740][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Disable near cache (set 'nearConfiguration' to null)
[19:03:23,740][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Decrease number of backups (set 'keyBackups' to 0)
[19:03:23,740][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
[19:03:23,740][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
[19:03:23,741][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Disable swap store (set 'swapEnabled' to false)
[19:03:23,741][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2]   ^-- Enable write-behind to persistent store (set 'writeBehindEnabled' to true)
[19:03:23,741][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] 
[19:03:23,742][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:23,742][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] 
[19:03:23,743][INFO ][test-runner][GridCacheTcpClientDiscoveryMultiThreadedTest2] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest2
>>> Local node [ID=394472B7-0E1D-4C86-B008-46397925433B, order=3]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45012 TCP:47502 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:23,744][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=3, nodes=3, CPUs=8, heap=3.0GB]
[19:03:23,744][INFO ][test-runner][root] Starting grids: 4
[19:03:23,765][INFO ][preloader-demand-worker-#183%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=100 ms]
[19:03:23,765][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:23,766][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Config URL: n/a
[19:03:23,766][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Daemon mode: off
[19:03:23,766][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] OS: Mac OS X 10.9.5 x86_64
[19:03:23,766][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:23,767][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:23,768][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Config URL: n/a
[19:03:23,768][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Daemon mode: off
[19:03:23,769][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] OS: Mac OS X 10.9.5 x86_64
[19:03:23,769][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] OS user: yzhdanov
[19:03:23,767][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] OS user: yzhdanov
[19:03:23,769][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Language runtime: Java Platform API Specification ver. 1.7
[19:03:23,770][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:23,769][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:23,768][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Config URL: n/a
[19:03:23,771][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Config URL: n/a
[19:03:23,771][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Daemon mode: off
[19:03:23,772][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] OS: Mac OS X 10.9.5 x86_64
[19:03:23,771][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM total memory: 2.9GB
[19:03:23,770][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Language runtime: Java Platform API Specification ver. 1.7
[19:03:23,773][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:23,772][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:23,772][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] OS user: yzhdanov
[19:03:23,771][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Daemon mode: off
[19:03:23,774][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Language runtime: Java Platform API Specification ver. 1.7
[19:03:23,774][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:23,773][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM total memory: 2.9GB
[19:03:23,775][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:23,774][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:23,774][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] OS: Mac OS X 10.9.5 x86_64
[19:03:23,776][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM total memory: 2.9GB
[19:03:23,776][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Configured caches ['default']
[19:03:23,775][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:23,777][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:23,776][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] OS user: yzhdanov
[19:03:23,778][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:23,778][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:23,777][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:23,779][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:23,777][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:23,778][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Language runtime: Java Platform API Specification ver. 1.7
[19:03:23,781][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:23,780][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Configured caches ['default']
[19:03:23,780][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:23,781][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM total memory: 2.9GB
[19:03:23,782][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:23,782][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Configured caches ['default']
[19:03:23,783][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:23,783][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:23,784][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:23,784][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:23,785][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:23,785][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Configured caches ['default']
[19:03:23,784][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:23,786][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor] Configured plugins:
[19:03:23,781][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:23,783][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:23,786][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:23,787][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor]   ^-- None
[19:03:23,786][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:23,786][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:23,787][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor] 
[19:03:23,788][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:23,789][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor] Configured plugins:
[19:03:23,789][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor]   ^-- None
[19:03:23,789][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor] 
[19:03:23,790][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:23,790][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:23,791][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:23,791][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:23,791][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor] Configured plugins:
[19:03:23,792][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor]   ^-- None
[19:03:23,792][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor] 
[19:03:23,792][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor] Configured plugins:
[19:03:23,793][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor]   ^-- None
[19:03:23,793][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor] 
[19:03:23,801][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][TcpCommunicationSpi] Successfully bound to TCP port [port=45013, locHost=/127.0.0.1]
[19:03:23,802][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][TcpCommunicationSpi] Successfully bound to TCP port [port=45014, locHost=/127.0.0.1]
[19:03:23,805][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][TcpCommunicationSpi] Successfully bound to TCP port [port=45015, locHost=/127.0.0.1]
[19:03:23,807][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][TcpCommunicationSpi] Successfully bound to TCP port [port=45016, locHost=/127.0.0.1]
[19:03:23,824][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:23,827][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:23,828][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:23,829][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:23,830][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Security status [authentication=off]
[19:03:23,831][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:23,835][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Security status [authentication=off]
[19:03:23,836][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Security status [authentication=off]
[19:03:23,832][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:23,834][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:23,836][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:23,838][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Security status [authentication=off]
[19:03:23,924][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b860aeb-0cd5-405b-b079-e4366fdb7cee, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:23,925][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=4, nodes=4, CPUs=8, heap=3.0GB]
[19:03:23,935][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:23,947][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:23,954][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b860aeb-0cd5-405b-b079-e4366fdb7cee, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:23,955][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=4, nodes=4, CPUs=8, heap=3.0GB]
[19:03:23,957][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:23,960][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:23,962][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=5, nodes=5, CPUs=8, heap=3.0GB]
[19:03:23,964][INFO ][disco-event-worker-#359%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:23,965][INFO ][disco-event-worker-#359%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Topology snapshot [ver=5, nodes=5, CPUs=8, heap=3.0GB]
[19:03:23,973][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:23,974][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=5, nodes=5, CPUs=8, heap=3.0GB]
[19:03:23,980][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:23,990][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:23,999][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:24,002][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,004][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=6, nodes=6, CPUs=8, heap=3.0GB]
[19:03:24,005][INFO ][disco-event-worker-#359%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,006][INFO ][disco-event-worker-#362%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,006][INFO ][disco-event-worker-#359%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Topology snapshot [ver=6, nodes=6, CPUs=8, heap=3.0GB]
[19:03:24,007][INFO ][disco-event-worker-#362%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Topology snapshot [ver=6, nodes=6, CPUs=8, heap=3.0GB]
[19:03:24,010][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,011][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=6, nodes=6, CPUs=8, heap=3.0GB]
[19:03:24,016][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b860aeb-0cd5-405b-b079-e4366fdb7cee, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,017][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=4, nodes=4, CPUs=8, heap=3.0GB]
[19:03:24,019][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,020][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:24,021][INFO ][disco-event-worker-#359%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,022][INFO ][disco-event-worker-#362%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,022][INFO ][disco-event-worker-#359%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:24,023][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,023][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,023][INFO ][disco-event-worker-#362%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:24,025][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:24,024][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=5, nodes=5, CPUs=8, heap=3.0GB]
[19:03:24,028][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,029][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=6, nodes=6, CPUs=8, heap=3.0GB]
[19:03:24,032][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,034][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:24,049][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
[19:03:24,049][INFO ][disco-event-worker-#365%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:24,051][INFO ][disco-event-worker-#365%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:24,064][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
[19:03:24,057][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:24,066][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:24,071][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:24,076][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:24,184][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:24,188][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:33,975][WARN ][grid-timeout-worker-#328%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=6b860aeb, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=4, locNodeId=6b860aeb-0cd5-405b-b079-e4366fdb7cee]
[19:03:33,978][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:33,978][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:33,979][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:33,980][INFO ][preloader-demand-worker-#374%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:03:33,981][INFO ][preloader-demand-worker-#378%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:03:33,981][INFO ][preloader-demand-worker-#384%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
[19:03:33,992][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest4' (fix if possible)
[19:03:33,993][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:33,993][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:33,993][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:33,993][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:33,994][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 
[19:03:33,994][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:33,994][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 
[19:03:33,995][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest4
>>> Local node [ID=6B860AEB-0CD5-405B-B079-E4366FDB7CEE, order=4]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45014 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:33,996][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:34,019][WARN ][grid-timeout-worker-#327%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=5, minorTopVer=0], nodeId=6fd8766a, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=5, locNodeId=6fd8766a-27dd-40b5-a2d8-22496b233ce7]
[19:03:34,023][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:34,023][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:34,023][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:34,025][INFO ][preloader-demand-worker-#389%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:03:34,026][INFO ][preloader-demand-worker-#393%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:03:34,027][INFO ][preloader-demand-worker-#399%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
[19:03:34,036][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest5' (fix if possible)
[19:03:34,036][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:34,037][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:34,037][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:34,037][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:34,037][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 
[19:03:34,037][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:34,038][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 
[19:03:34,038][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest5
>>> Local node [ID=6FD8766A-27DD-40B5-A2D8-22496B233CE7, order=5]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45013 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:34,039][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:34,195][WARN ][grid-timeout-worker-#330%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=6, minorTopVer=0], nodeId=5ccab839, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=6, locNodeId=5ccab839-4be7-40c9-92bf-d87d627d7d4e]
[19:03:34,199][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:34,200][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:34,206][WARN ][grid-timeout-worker-#329%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=0], nodeId=747f2667, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=7, locNodeId=747f2667-fa78-48a1-99e5-ef012c33189d]
[19:03:34,200][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:34,201][INFO ][preloader-demand-worker-#404%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:03:34,202][INFO ][preloader-demand-worker-#408%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:03:34,203][INFO ][preloader-demand-worker-#414%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
[19:03:34,210][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:34,210][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:34,210][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:34,211][INFO ][preloader-demand-worker-#419%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:03:34,212][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest6' (fix if possible)
[19:03:34,213][INFO ][preloader-demand-worker-#423%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:03:34,213][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:34,214][INFO ][preloader-demand-worker-#429%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
[19:03:34,214][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:34,214][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:34,214][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:34,215][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 
[19:03:34,215][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:34,215][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 
[19:03:34,216][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest6
>>> Local node [ID=5CCAB839-4BE7-40C9-92BF-D87D627D7D4E, order=6]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45015 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:34,217][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:34,226][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest3' (fix if possible)
[19:03:34,226][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:34,226][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:34,227][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:34,227][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:34,227][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 
[19:03:34,227][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:34,227][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 
[19:03:34,228][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest3
>>> Local node [ID=747F2667-FA78-48A1-99E5-EF012C33189D, order=7]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45016 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:34,229][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDiscoveryManager] Topology snapshot [ver=7, nodes=7, CPUs=8, heap=3.0GB]
[19:03:34,348][INFO ][exchange-worker-#370%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:34,358][INFO ][ignite-#248%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:34,360][INFO ][preloader-demand-worker-#434%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
[19:03:44,340][WARN ][grid-timeout-worker-#327%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=1], nodeId=747f2667, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=5, locNodeId=6fd8766a-27dd-40b5-a2d8-22496b233ce7]
[19:03:44,340][WARN ][grid-timeout-worker-#328%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=1], nodeId=747f2667, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=4, locNodeId=6b860aeb-0cd5-405b-b079-e4366fdb7cee]
[19:03:44,346][INFO ][exchange-worker-#361%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:44,356][INFO ][ignite-#320%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:44,352][WARN ][grid-timeout-worker-#330%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=1], nodeId=747f2667, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=6, locNodeId=5ccab839-4be7-40c9-92bf-d87d627d7d4e]
[19:03:44,357][INFO ][preloader-demand-worker-#439%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
[19:03:44,366][INFO ][exchange-worker-#364%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:44,378][INFO ][ignite-#301%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:44,380][INFO ][preloader-demand-worker-#444%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
[19:03:54,370][WARN ][grid-timeout-worker-#328%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=3], nodeId=6fd8766a, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=4, locNodeId=6b860aeb-0cd5-405b-b079-e4366fdb7cee]
[19:03:54,374][WARN ][grid-timeout-worker-#329%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=3], nodeId=6fd8766a, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=7, locNodeId=747f2667-fa78-48a1-99e5-ef012c33189d]
[19:03:54,383][WARN ][grid-timeout-worker-#330%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=3], nodeId=6fd8766a, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=6, locNodeId=5ccab839-4be7-40c9-92bf-d87d627d7d4e]
[19:03:54,388][WARN ][grid-timeout-worker-#327%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=4], nodeId=5ccab839, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=5, locNodeId=6fd8766a-27dd-40b5-a2d8-22496b233ce7]
[19:03:54,390][INFO ][exchange-worker-#369%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:03:54,399][INFO ][ignite-#271%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:03:54,400][INFO ][preloader-demand-worker-#449%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
[19:03:55,161][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest5, id=6fd8766a-27dd-40b5-a2d8-22496b233ce7]
[19:03:55,161][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest3, id=747f2667-fa78-48a1-99e5-ef012c33189d]
[19:03:55,172][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest4, id=6b860aeb-0cd5-405b-b079-e4366fdb7cee]
[19:03:55,172][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest6, id=5ccab839-4be7-40c9-92bf-d87d627d7d4e]
[19:03:55,177][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,179][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=9, nodes=6, CPUs=8, heap=3.0GB]
[19:03:55,182][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,183][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=11, nodes=3, CPUs=8, heap=3.0GB]
[19:03:55,183][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,184][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6fd8766a-27dd-40b5-a2d8-22496b233ce7, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=5, intOrder=5, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,184][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=11, nodes=3, CPUs=8, heap=3.0GB]
[19:03:55,185][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=8, nodes=6, CPUs=8, heap=3.0GB]
[19:03:55,185][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6b860aeb-0cd5-405b-b079-e4366fdb7cee, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,186][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,186][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=747f2667-fa78-48a1-99e5-ef012c33189d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=7, intOrder=7, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,188][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=10, nodes=4, CPUs=8, heap=3.0GB]
[19:03:55,188][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,190][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6b860aeb-0cd5-405b-b079-e4366fdb7cee, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,189][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6b860aeb-0cd5-405b-b079-e4366fdb7cee, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,191][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=11, nodes=3, CPUs=8, heap=3.0GB]
[19:03:55,192][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,192][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=5ccab839-4be7-40c9-92bf-d87d627d7d4e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=6, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,195][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: null
[19:03:55,196][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:03:55,196][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:03:55,196][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:03:55,197][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: null
[19:03:55,197][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:03:55,198][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:03:55,198][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:03:55,199][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937414016, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=d327e43bc41-6fd8766a-27dd-40b5-a2d8-22496b233ce7, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:03:55,200][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: null
[19:03:55,200][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:03:55,201][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:03:55,201][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: null
[19:03:55,202][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:03:55,202][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:03:55,202][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937413973, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=6327e43bc41-6b860aeb-0cd5-405b-b079-e4366fdb7cee, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:03:55,203][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:03:55,203][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:03:55,203][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:03:55,204][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:03:55,204][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:03:55,205][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:03:55,206][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937414206, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=a427e43bc41-747f2667-fa78-48a1-99e5-ef012c33189d, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:03:55,205][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937414195, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=5427e43bc41-5ccab839-4be7-40c9-92bf-d87d627d7d4e, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:03:55,207][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:03:55,208][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:03:55,206][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:03:55,208][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:03:55,207][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:03:55,209][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:03:55,210][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:03:55,211][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:03:55,217][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest5
>>> Grid uptime: 00:00:21:180


[19:03:55,219][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest3
>>> Grid uptime: 00:00:21:01


[19:03:55,220][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest6
>>> Grid uptime: 00:00:21:11


[19:03:55,226][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest4
>>> Grid uptime: 00:00:21:233


[19:03:55,229][INFO ][test-runner][root] Starting grids: 4
[19:03:55,238][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:55,238][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Config URL: n/a
[19:03:55,239][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Daemon mode: off
[19:03:55,239][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] OS: Mac OS X 10.9.5 x86_64
[19:03:55,239][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] OS user: yzhdanov
[19:03:55,240][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Language runtime: Java Platform API Specification ver. 1.7
[19:03:55,240][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:55,240][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM total memory: 2.9GB
[19:03:55,241][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:55,241][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:55,241][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:55,243][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Configured caches ['default']
[19:03:55,244][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:55,245][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:55,246][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Config URL: n/a
[19:03:55,246][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Daemon mode: off
[19:03:55,247][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] OS: Mac OS X 10.9.5 x86_64
[19:03:55,247][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] OS user: yzhdanov
[19:03:55,247][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Language runtime: Java Platfo[19:03:55,243][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
rm API Specification ver. 1.7
[19:03:55,248][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:55,248][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM total memory: 2.9GB
[19:03:55,249][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:55,249][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:55,249][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:55,250][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:55,250][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:55,250][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:55,251][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Configured caches ['default']
[19:03:55,251][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor] Configured plugins:
[19:03:55,251][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:03:55,251][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Config URL: n/a
[19:03:55,253][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Daemon mode: off
[19:03:55,253][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Config URL: n/a
[19:03:55,252][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:55,252][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor]   ^-- None
[19:03:55,254][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor] 
[19:03:55,254][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Daemon mode: off
[19:03:55,255][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-[19:03:55,251][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
2][GridCacheTcpClientDiscoveryMultiThreadedTest4] OS: Mac OS X 10.9.5 x86_64
[19:03:55,253][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] OS: Mac OS X 10.9.5 x86_64
[19:03:55,255][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] OS user: yzhdanov
[19:03:55,255][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] OS user: yzhdanov
[19:03:55,256][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Language runtime: Java Platform API Specification ver. 1.7
[19:03:55,256][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Language runtime: Java Platform API Specification ver. 1.7
[19:03:55,257][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:55,256][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:03:55,257][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM total memory: 2.9GB
[19:03:55,257][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:55,258][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:55,258][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:55,258][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:55,257][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM total memory: 2.9GB
[19:03:55,259][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:55,259][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor] Configured plugins:
[19:03:55,259][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Configured caches ['default']
[19:03:55,259][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:03:55,260][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:03:55,260][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor]   ^-- None
[19:03:55,261][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:55,261][INFO ][grid-starter-testCacheConcurrentlyWithMultipl[19:03:55,260][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
eClientNodes-1][TcpCommunicationSpi] Successfully bound to TCP port [port=45013, locHost=/127.0.0.1]
[19:03:55,261][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:03:55,261][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor] 
[19:03:55,262][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Configured caches ['default']
[19:03:55,263][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:03:55,263][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:03:55,266][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:55,267][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:55,267][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor] Configured plugins:
[19:03:55,267][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor]   ^-- None
[19:03:55,267][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor] 
[19:03:55,269][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:03:55,269][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:03:55,270][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor] Configured plugins:
[19:03:55,270][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor]   ^-- None
[19:03:55,270][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor] 
[19:03:55,270][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][TcpCommunicationSpi] Successfully bound to TCP port [port=45014, locHost=/127.0.0.1]
[19:03:55,275][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][TcpCommunicationSpi] Successfully bound to TCP port [port=45015, locHost=/127.0.0.1]
[19:03:55,279][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][TcpCommunicationSpi] Successfully bound to TCP port [port=45016, locHost=/127.0.0.1]
[19:03:55,280][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Security status [authentication=off]
[19:03:55,277][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:55,279][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:55,289][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:55,292][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:55,293][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:55,294][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Security status [authentication=off]
[19:03:55,296][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Security status [authentication=off]
[19:03:55,295][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:55,296][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:03:55,299][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:03:55,300][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Security status [authentication=off]
[19:03:55,338][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,339][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=12, nodes=4, CPUs=8, heap=3.0GB]
[19:03:55,340][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,341][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=13, nodes=5, CPUs=8, heap=3.0GB]
[19:03:55,344][INFO ][disco-event-worker-#611%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,350][INFO ][disco-event-worker-#611%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Topology snapshot [ver=13, nodes=5, CPUs=8, heap=3.0GB]
[19:03:55,351][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,351][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,353][INFO ][disco-event-worker-#611%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,352][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=14, nodes=6, CPUs=8, heap=3.0GB]
[19:03:55,354][INFO ][disco-event-worker-#611%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Topology snapshot [ver=14, nodes=6, CPUs=8, heap=3.0GB]
[19:03:55,354][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:55,353][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=13, nodes=5, CPUs=8, heap=3.0GB]
[19:03:55,356][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,357][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=14, nodes=6, CPUs=8, heap=3.0GB]
[19:03:55,358][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,358][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:55,359][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,358][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,361][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:55,360][INFO ][disco-event-worker-#611%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,358][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isCli[19:03:55,367][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
ent=true]
[19:03:55,364][INFO ][disco-event-worker-#611%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,361][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=13, nodes=5, CPUs=8, heap=3.0GB]
[19:03:55,367][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,367][INFO ][disco-event-worker-#613%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,369][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,369][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,376][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:55,377][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,373][INFO ][disco-event-worker-#614%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,373][INFO ][disco-event-worker-#613%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,379][INFO ][disco-event-worker-#614%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,378][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,383][INFO ][disco-event-worker-#614%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,384][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:03:55,373][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
[19:03:55,373][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
[19:03:55,389][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:55,391][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:55,391][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:03:55,400][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:55,405][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:55,405][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:03:55,410][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:55,417][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:03:55,433][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:55,434][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:55,434][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:55,436][INFO ][preloader-demand-worker-#626%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:03:55,439][INFO ][preloader-demand-worker-#630%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:03:55,441][INFO ][preloader-demand-worker-#636%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=11 ms]
[19:03:55,451][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest6' (fix if possible)
[19:03:55,452][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:55,452][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:55,452][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:55,453][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:55,453][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 
[19:03:55,453][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:55,454][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 
[19:03:55,454][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest6
>>> Local node [ID=50CFB09C-A733-433A-B611-B61BBDC024B4, order=13]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45014 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:55,455][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:03:55,592][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:03:55,594][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:03:55,594][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:03:55,596][INFO ][preloader-demand-worker-#641%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:03:55,597][INFO ][preloader-demand-worker-#645%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=10 ms]
[19:03:55,598][INFO ][preloader-demand-worker-#651%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=10 ms]
[19:03:55,608][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest5' (fix if possible)
[19:03:55,609][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:03:55,609][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:03:55,609][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:03:55,610][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:03:55,610][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 
[19:03:55,610][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:03:55,610][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 
[19:03:55,611][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest5
>>> Local node [ID=032F8CBF-F9AA-4040-8618-C7FE06E149B2, order=12]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45015 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:03:55,612][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:04:05,419][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:04:05,419][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:04:05,419][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:04:05,416][WARN ][grid-timeout-worker-#592%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=0], nodeId=b12ead0f, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=15, locNodeId=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5]
[19:04:05,421][INFO ][preloader-demand-worker-#656%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:04:05,422][INFO ][preloader-demand-worker-#660%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:04:05,422][INFO ][preloader-demand-worker-#666%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
[19:04:05,426][WARN ][grid-timeout-worker-#579%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=14, minorTopVer=0], nodeId=6675eeb7, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=14, locNodeId=6675eeb7-3232-44cd-8b7c-04fcc52b9762]
[19:04:05,427][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest4' (fix if possible)
[19:04:05,427][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:04:05,428][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:04:05,428][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:04:05,428][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:04:05,428][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 
[19:04:05,428][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:04:05,429][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 
[19:04:05,429][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
[19:04:05,430][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
[19:04:05,430][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
[19:04:05,430][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest4
>>> Local node [ID=B12EAD0F-6E8C-4CFB-99FD-4A1281648BD5, order=15]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45016 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:04:05,431][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:04:05,432][INFO ][preloader-demand-worker-#671%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
[19:04:05,433][INFO ][preloader-demand-worker-#675%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
[19:04:05,434][INFO ][preloader-demand-worker-#681%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
[19:04:05,441][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Performance suggestions for grid 'cache.GridCacheTcpClientDiscoveryMultiThreadedTest3' (fix if possible)
[19:04:05,441][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[19:04:05,442][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
[19:04:05,442][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
[19:04:05,442][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
[19:04:05,442][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 
[19:04:05,443][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
[19:04:05,443][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 
[19:04:05,443][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>> +---------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV
>>> +---------------------------------+
>>> OS name: Mac OS X 10.9.5 x86_64
>>> CPU(s): 8
>>> Heap: 3.0GB
>>> VM name: 2800@mbp.local
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest3
>>> Local node [ID=6675EEB7-3232-44CD-8B7C-04FCC52B9762, order=14]
>>> Local node addresses: [/127.0.0.1]
>>> Local ports: TCP:45013 
>>> Ignite documentation: http://ignite.incubator.apache.org

[19:04:05,444][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridDiscoveryManager] Topology snapshot [ver=15, nodes=7, CPUs=8, heap=3.0GB]
[19:04:05,451][WARN ][grid-timeout-worker-#585%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=14, minorTopVer=0], nodeId=6675eeb7, evt=NODE_JOINED], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=13, locNodeId=50cfb09c-a733-433a-b611-b61bbdc024b4]
[19:04:05,569][INFO ][exchange-worker-#621%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:04:05,580][INFO ][ignite-#471%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:04:05,581][INFO ][preloader-demand-worker-#686%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=11 ms]
[19:04:15,579][WARN ][grid-timeout-worker-#585%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=1], nodeId=6675eeb7, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=13, locNodeId=50cfb09c-a733-433a-b611-b61bbdc024b4]
[19:04:15,580][WARN ][grid-timeout-worker-#586%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=1], nodeId=6675eeb7, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=12, locNodeId=032f8cbf-f9aa-4040-8618-c7fe06e149b2]
[19:04:15,580][WARN ][grid-timeout-worker-#592%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=1], nodeId=6675eeb7, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=15, locNodeId=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5]
[19:04:15,586][INFO ][exchange-worker-#620%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:04:15,591][WARN ][grid-timeout-worker-#579%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=2], nodeId=b12ead0f, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=14, locNodeId=6675eeb7-3232-44cd-8b7c-04fcc52b9762]
[19:04:15,596][INFO ][ignite-#565%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:04:15,597][INFO ][preloader-demand-worker-#691%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=11 ms]
[19:04:15,603][INFO ][exchange-worker-#616%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:04:15,615][INFO ][ignite-#550%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:04:15,617][INFO ][preloader-demand-worker-#696%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
[19:04:25,606][WARN ][grid-timeout-worker-#592%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=3], nodeId=032f8cbf, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=15, locNodeId=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5]
[19:04:25,614][WARN ][grid-timeout-worker-#585%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=3], nodeId=032f8cbf, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=13, locNodeId=50cfb09c-a733-433a-b611-b61bbdc024b4]
[19:04:25,617][WARN ][grid-timeout-worker-#579%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=3], nodeId=032f8cbf, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=14, locNodeId=6675eeb7-3232-44cd-8b7c-04fcc52b9762]
[19:04:25,621][INFO ][exchange-worker-#622%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
[19:04:25,625][WARN ][grid-timeout-worker-#586%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDhtPartitionsExchangeFuture] Retrying preload partition exchange due to timeout [done=false, dummy=false, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=4], nodeId=50cfb09c, evt=DISCOVERY_CUSTOM_EVT], rcvdIds=[], rmtIds=[394472b7, f2899fa7, c2595bf4], remaining=[394472b7, f2899fa7, c2595bf4], init=true, initFut=true, ready=true, replied=false, added=true, oldest=f2899fa7, oldestOrder=1, evtLatch=0, locNodeOrder=12, locNodeId=032f8cbf-f9aa-4040-8618-c7fe06e149b2]
[19:04:25,630][INFO ][ignite-#515%sys-cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
[19:04:25,635][INFO ][preloader-demand-worker-#701%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
[19:04:26,058][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest5, id=032f8cbf-f9aa-4040-8618-c7fe06e149b2]
[19:04:26,060][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest3, id=6675eeb7-3232-44cd-8b7c-04fcc52b9762]
[19:04:26,067][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest6, id=50cfb09c-a733-433a-b611-b61bbdc024b4]
[19:04:26,069][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest4, id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5]
[19:04:26,073][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,076][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=17, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,075][INFO ][disco-event-worker-#615%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,077][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,077][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,080][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: null
[19:04:26,076][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,081][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,082][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=17, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,080][INFO ][disco-event-worker-#614%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=032f8cbf-f9aa-4040-8618-c7fe06e149b2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=12, intOrder=8, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,085][INFO ][disco-event-worker-#614%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=17, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,079][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=17, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,077][INFO ][disco-event-worker-#615%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Topology snapshot [ver=17, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,084][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,082][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,093][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,093][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: null
[19:04:26,092][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6675eeb7-3232-44cd-8b7c-04fcc52b9762, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=14, intOrder=10, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,094][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937435586, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=8489e43bc41-032f8cbf-f9aa-4040-8618-c7fe06e149b2, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,095][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:04:26,096][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,094][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,097][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,096][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,095][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=17, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,094][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,100][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=19, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,097][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,102][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=19, nodes=3, CPUs=8, heap=3.0GB]
[19:04:26,102][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=50cfb09c-a733-433a-b611-b61bbdc024b4, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=13, intOrder=9, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,104][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,103][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,103][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,106][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=19, nodes=3, CPUs=8, heap=3.0GB]
[19:04:26,106][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: null
[19:04:26,105][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: null
[19:04:26,105][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest5
>>> Grid uptime: 00:00:30:494


[19:04:26,104][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=19, nodes=3, CPUs=8, heap=3.0GB]
[19:04:26,107][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,109][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=15, intOrder=11, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,107][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,110][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,106][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937445426, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=5499e43bc41-6675eeb7-3232-44cd-8b7c-04fcc52b9762, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,111][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:04:26,111][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,110][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,109][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,113][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,112][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,114][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937445415, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=1499e43bc41-b12ead0f-6e8c-4cfb-99fd-4a1281648bd5, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,113][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937435429, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=1389e43bc41-50cfb09c-a733-433a-b611-b61bbdc024b4, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,115][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:04:26,116][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:04:26,118][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,119][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,119][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,120][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,122][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest3
>>> Grid uptime: 00:00:20:676


[19:04:26,126][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest4
>>> Grid uptime: 00:00:20:697


[19:04:26,126][INFO ][cache.GridCacheTcpClientDiscoveryMultiThreadedTest-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest6
>>> Grid uptime: 00:00:30:673


[19:04:26,129][INFO ][test-runner][root] Starting grids: 4
[19:04:26,138][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:04:26,139][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Config URL: n/a
[19:04:26,139][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Daemon mode: off
[19:04:26,139][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] OS: Mac OS X 10.9.5 x86_64
[19:04:26,140][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] OS user: yzhdanov
[19:04:26,140][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Language runtime: Java Platform API Specification ver. 1.7
[19:04:26,140][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:04:26,141][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM total memory: 2.9GB
[19:04:26,141][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:04:26,142][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:04:26,143][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:04:26,142][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:04:26,143][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Configured caches ['default']
[19:04:26,144][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:04:26,148][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:04:26,149][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Config URL: n/a
[19:04:26,149][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:04:26,150][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:04:26,149][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Daemon mode: off
[19:04:26,150][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] OS: Mac OS X 10.9.5 x86_64
[19:04:26,150][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] OS user: yzhdanov
[19:04:26,151][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Language runtime: Java Platform API Specification ver. 1.7
[19:04:26,151][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:04:26,151][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor] Configured plugins:
[19:04:26,152][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:04:26,152][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Config URL: n/a
[19:04:26,153][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Daemon mode: off
[19:04:26,153][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] OS: Mac OS X 10.9.5 x86_64
[19:04:26,153][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] OS user: yzhdanov
[19:04:26,152][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM total memory: 2.9GB
[19:04:26,154][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:04:26,154][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Language runtime: Java Platform API Specification ver. 1.7
[19:04:26,152][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor]   ^-- None
[19:04:26,155][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:04:26,154][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:04:26,155][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:04:26,154][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>>    __________  ________________  
>>>   /  _/ ___/ |/ /  _/_  __/ __/  
>>>  _/ // (7 7    // /  / / / _/    
>>> /___/\___/_/|_/___/ /_/ /___/   
>>> 
>>> ver. 1.0.0#19700101-sha1:DEV
>>> 2015 Copyright(C) Apache Software Foundation

[19:04:26,156][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Configured caches ['default']
[19:04:26,155][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM total memory: 2.9GB
[19:04:26,155][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][IgnitePluginProcessor] 
[19:04:26,157][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:04:26,157][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:04:26,156][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Config URL: n/a
[19:04:26,158][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:04:26,158][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Daemon mode: off
[19:04:26,159][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] OS: Mac OS X 10.9.5 x86_64
[19:04:26,159][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:04:26,159][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] OS user: yzhdanov
[19:04:26,160][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Configured caches ['default']
[19:04:26,160][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Language runtime: Java Platform API Specification ver. 1.7
[19:04:26,157][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:04:26,161][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:04:26,161][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM information: Java(TM) SE Runtime Environment 1.7.0_40-b43 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.0-b56
[19:04:26,162][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:04:26,162][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:04:26,161][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM total memory: 2.9GB
[19:04:26,161][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][TcpCommunicationSpi] Successfully bound to TCP port [port=45013, locHost=/127.0.0.1]
[19:04:26,163][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor] Configured plugins:
[19:04:26,163][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor]   ^-- None
[19:04:26,163][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][IgnitePluginProcessor] 
[19:04:26,163][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Remote Management [restart: off, REST: off, JMX (remote: off)]
[19:04:26,162][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:04:26,164][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] IGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite
[19:04:26,164][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] VM arguments: [-ea, -Xmx3g, -Xms3g, -DIGNITE_HOME=/Users/yzhdanov/projects/incubator-ignite, -DIGNITE_QUIET=false, -Didea.launcher.port=7533, -Didea.launcher.bin.path=/Applications/IntelliJ IDEA 13 CE.app/Contents/bin, -Dfile.encoding=UTF-8]
[19:04:26,165][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Configured caches ['default']
[19:04:26,166][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 3-rd party licenses can be found at: /Users/yzhdanov/projects/incubator-ignite/libs/licenses
[19:04:26,166][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[19:04:26,168][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:04:26,169][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:04:26,168][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][TcpCommunicationSpi] Successfully bound to TCP port [port=45014, locHost=/127.0.0.1]
[19:04:26,169][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Non-loopback local IPs: 192.168.1.188, 192.168.3.4, fe80:0:0:0:1240:f3ff:fe9c:4be4%4, fe80:0:0:0:3e07:54ff:fe21:d15d%11
[19:04:26,170][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Enabled local MACs: 1040F39C4BE4, 3C075421D15D
[19:04:26,169][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor] Configured plugins:
[19:04:26,170][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor]   ^-- None
[19:04:26,170][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][IgnitePluginProcessor] 
[19:04:26,170][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor] Configured plugins:
[19:04:26,171][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor]   ^-- None
[19:04:26,171][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][IgnitePluginProcessor] 
[19:04:26,174][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Security status [authentication=off]
[19:04:26,171][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:04:26,173][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:04:26,175][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][TcpCommunicationSpi] Successfully bound to TCP port [port=45015, locHost=/127.0.0.1]
[19:04:26,175][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][TcpCommunicationSpi] Successfully bound to TCP port [port=45016, locHost=/127.0.0.1]
[19:04:26,179][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:04:26,181][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:04:26,182][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Security status [authentication=off]
[19:04:26,184][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:04:26,184][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[19:04:26,186][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:04:26,186][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridIndexingManager] Indexing is disabled (to enable please configure GridIndexingSpi).
[19:04:26,187][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Security status [authentication=off]
[19:04:26,187][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Security status [authentication=off]
[19:04:26,215][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=ae433b89-4284-4de1-b072-d75fd31a524c, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=20, intOrder=12, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,215][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=20, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,220][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:04:26,220][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=ae433b89-4284-4de1-b072-d75fd31a524c, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=20, intOrder=12, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,221][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=20, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,222][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,223][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,223][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,225][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=22, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,223][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=21, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,224][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=21, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,226][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,226][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,226][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,227][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=22, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,227][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=22, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,228][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=22, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,229][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
[19:04:26,229][ERROR][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] Got exception while starting (will rollback startup routine).
java.lang.NullPointerException
	at org.apache.ignite.internal.MarshallerContextImpl.onMarshallerCacheStarted(MarshallerContextImpl.java:59)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:742)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at [19:04:26,229][INFO ][disco-event-worker-#867%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=falseorg.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,231][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,231][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,231][INFO ][disco-event-worker-#865%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,230][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,230][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:04:26,230][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=ae433b89-4284-4de1-b072-d75fd31a524c, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=20, intOrder=12, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,242][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=23, nodes=7, CPUs=8, heap=3.0GB]
[19:04:26,241][INFO ][disco-event-worker-#865%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][GridDiscoveryManager] Topology snapshot [ver=23, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,241][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=23, nodes=7, CPUs=8, heap=3.0GB]
[19:04:26,240][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:04:26,240][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=23, nodes=7, CPUs=8, heap=3.0GB]
[19:04:26,237][INFO ][disco-event-worker-#867%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDiscoveryManager] Topology snapshot [ver=23, nodes=7, CPUs=8, heap=3.0GB]
[19:04:26,243][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=23, nodes=7, CPUs=8, heap=3.0GB]
[19:04:26,246][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,247][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,246][INFO ][disco-event-worker-#867%cache.GridCacheTcpClientDiscoveryMultiTh[19:04:26,241][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
readedTest3%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,248][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,248][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,248][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
[19:04:26,251][INFO ][disco-event-worker-#869%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,247][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=24, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,252][INFO ][disco-event-worker-#869%cache.GridCacheTcpClientDiscoveryMultiThreadedTest5%][GridDiscoveryManager] Topology snapshot [ver=24, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,251][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=24, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,250][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=24, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,249][INFO ][disco-event-worker-#867%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDiscoveryManager] Topology snapshot [ver=24, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,248][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,254][INFO ][disco-event-worker-#867%cache.GridCacheTcpClientDiscoveryMultiThreadedTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,254][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,255][INFO ][disco-event-worker-#863%cache.GridCacheTcpClientDiscoveryMultiThreadedTest6%][GridDiscoveryManager] Topology snapshot [ver=24, nodes=6, CPUs=8, heap=3.0GB]
[19:04:26,257][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=3991151d-787f-4176-b5c0-8cdacc6940f2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=22, intOrder=14, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,259][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:04:26,255][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:04:26,255][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
[19:04:26,258][ERROR][grid-nio-worker-0-#851%cache.GridCacheTcpClientDiscoveryMultiThreadedTest4%][TcpCommunicationSpi] Caught unhandled exception in NIO worker thread (restart the node).
java.lang.AssertionError
	at org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi$6.writer(TcpCommunicationSpi.java:1385)
	at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processWrite0(GridNioServer.java:1026)
	at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processWrite(GridNioServer.java:832)
[19:04:26,262][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
[19:04:26,263][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeys(GridNioServer.java:1330)
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1269)
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1156)
	at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:108)
	at java.lang.Thread.run(Thread.java:724)
Failure in thread: Thread [id=894, name=grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2]
class org.apache.ignite.IgniteCheckedException: null
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:828)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.NullPointerException
	at org.apache.ignite.internal.MarshallerContextImpl.onMarshallerCacheStarted(MarshallerContextImpl.java:59)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:742)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	... 9 more
[19:04:26,274][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] Grid startup routine has been interrupted (will rollback).
[19:04:26,274][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] Grid startup routine has been interrupted (will rollback).
[19:04:26,274][WARN ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] Grid startup routine has been interrupted (will rollback).
Failure in thread: Thread [id=896, name=grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4]
class org.apache.ignite.internal.IgniteInterruptedCheckedException: Got interrupted while waiting for future to complete.
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:152)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:138)
	at org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager.onKernalStart0(GridCachePartitionExchangeManager.java:281)
	at org.apache.ignite.internal.processors.cache.GridCacheSharedManagerAdapter.onKernalStart(GridCacheSharedManagerAdapter.java:97)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:752)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.InterruptedException
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1038)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1326)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:164)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:147)
	... 14 more
Failure in thread: Thread [id=895, name=grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3]
Failure in thread: Thread [id=893, name=grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1]
class org.apache.ignite.internal.IgniteInterruptedCheckedException: Got interrupted while waiting for future to complete.
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:152)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:138)
	at org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager.onKernalStart0(GridCachePartitionExchangeManager.java:281)
	at org.apache.ignite.internal.processors.cache.GridCacheSharedManagerAdapter.onKernalStart(GridCacheSharedManagerAdapter.java:97)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:752)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.InterruptedException
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1038)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1326)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:164)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:147)
	... 14 more
class org.apache.ignite.internal.IgniteInterruptedCheckedException: Got interrupted while waiting for future to complete.
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:152)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:138)
	at org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager.onKernalStart0(GridCachePartitionExchangeManager.java:281)
	at org.apache.ignite.internal.processors.cache.GridCacheSharedManagerAdapter.onKernalStart(GridCacheSharedManagerAdapter.java:97)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:752)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.InterruptedException
[19:04:26,267][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-2][GridCacheTcpClientDiscoveryMultiThreadedTest4] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest4
>>> Grid uptime: 00:00:00:104


[19:04:26,282][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=ae433b89-4284-4de1-b072-d75fd31a524c, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=20, intOrder=12, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,282][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=26, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,283][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=ae433b89-4284-4de1-b072-d75fd31a524c, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=20, intOrder=12, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,283][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,284][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=ae433b89-4284-4de1-b072-d75fd31a524c, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=20, intOrder=12, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,284][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=26, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,285][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=26, nodes=5, CPUs=8, heap=3.0GB]
[19:04:26,284][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=26, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,286][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,289][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=26, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,290][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=00dba6ec-dab5-4812-902c-abde322f00c2, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=23, intOrder=15, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,291][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=26, nodes=4, CPUs=8, heap=3.0GB]
[19:04:26,291][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,292][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,292][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,292][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=27, nodes=3, CPUs=8, heap=3.0GB]
[19:04:26,295][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,294][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=27, nodes=3, CPUs=8, heap=3.0GB]
[19:04:26,294][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=6b6f2a15-e849-4f8b-a260-ecd9c3ec1c99, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=21, intOrder=13, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=true]
[19:04:26,296][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,297][INFO ][disco-event-worker-#172%cache.GridCacheTcpClientDiscoveryMultiThreadedTest2%][GridDiscoveryManager] Topology snapshot [ver=27, nodes=3, CPUs=8, heap=3.0GB]
[19:04:26,296][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,297][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,298][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,300][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,300][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,300][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,305][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-4][GridCacheTcpClientDiscoveryMultiThreadedTest6] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest6
>>> Grid uptime: 00:00:00:155


[19:04:26,305][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-3][GridCacheTcpClientDiscoveryMultiThreadedTest5] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest5
>>> Grid uptime: 00:00:00:155


[19:04:26,311][INFO ][grid-starter-testCacheConcurrentlyWithMultipleClientNodes-1][GridCacheTcpClientDiscoveryMultiThreadedTest3] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest3
>>> Grid uptime: 00:00:00:177


[19:04:26,325][INFO ][main][root] >>> Stopping test: testCacheConcurrentlyWithMultipleClientNodes in 64872 ms <<<
[19:04:26,325][INFO ][main][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest2, id=394472b7-0e1d-4c86-b008-46397925433b]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1038)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1326)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:164)
	at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:147)
	... 14 more
[19:04:26,323][ERROR][main][root] Test failed.
class org.apache.ignite.IgniteCheckedException: null
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:828)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.NullPointerException
	at org.apache.ignite.internal.MarshallerContextImpl.onMarshallerCacheStarted(MarshallerContextImpl.java:59)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:742)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	... 9 more
[19:04:26,338][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=394472b7-0e1d-4c86-b008-46397925433b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=false]
[19:04:26,339][INFO ][disco-event-worker-#41%cache.GridCacheTcpClientDiscoveryMultiThreadedTest0%][GridDiscoveryManager] Topology snapshot [ver=28, nodes=2, CPUs=8, heap=3.0GB]
[19:04:26,340][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=394472b7-0e1d-4c86-b008-46397925433b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=false]
[19:04:26,342][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=28, nodes=2, CPUs=8, heap=3.0GB]
[19:04:26,344][INFO ][main][GridCacheProcessor] Stopped cache: null
[19:04:26,344][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,344][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,345][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,345][INFO ][main][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937403645, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=58b6e43bc41-394472b7-0e1d-4c86-b008-46397925433b, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:04:26,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
[19:04:26,346][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
[19:04:26,347][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,350][INFO ][main][GridCacheTcpClientDiscoveryMultiThreadedTest2] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest2
>>> Grid uptime: 00:01:02:606


[19:04:26,352][INFO ][main][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest0, id=f2899fa7-22fd-4ae3-9602-6d6158bb0a06]
[19:04:26,413][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=f2899fa7-22fd-4ae3-9602-6d6158bb0a06, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, loc=false, ver=1.0.0#19700101-sha1:00000000, isClient=false]
[19:04:26,423][WARN ][exchange-worker-#107%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][TcpCommunicationSpi] Failed to connect to a remote node (make sure that destination node is alive and operating system firewall is disabled on local and remote hosts) [addrs=[/127.0.0.1:45010]]
[19:04:26,414][INFO ][disco-event-worker-#105%cache.GridCacheTcpClientDiscoveryMultiThreadedTest1%][GridDiscoveryManager] Topology snapshot [ver=29, nodes=1, CPUs=8, heap=3.0GB]
[19:04:26,415][INFO ][main][GridCacheProcessor] Stopped cache: null
[19:04:26,415][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,415][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,416][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,417][INFO ][main][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937403078, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=f576e43bc41-f2899fa7-22fd-4ae3-9602-6d6158bb0a06, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,417][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
[19:04:26,418][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,418][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
[19:04:26,418][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,418][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
[19:04:26,421][INFO ][main][GridCacheTcpClientDiscoveryMultiThreadedTest0] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest0
>>> Grid uptime: 00:01:03:754


[19:04:26,423][INFO ][main][root] >>> Stopping grid [name=cache.GridCacheTcpClientDiscoveryMultiThreadedTest1, id=c2595bf4-9ec3-4092-a960-489bfdde7ef0]
[19:04:26,431][INFO ][main][GridCacheProcessor] Stopped cache: null
[19:04:26,431][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
[19:04:26,431][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
[19:04:26,431][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
[19:04:26,432][INFO ][main][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1428937403111, depMode=SHARED, clsLdr=sun.misc.Launcher$AppClassLoader@361bb8ea, clsLdrId=1676e43bc41-c2595bf4-9ec3-4092-a960-489bfdde7ef0, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
[19:04:26,433][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
[19:04:26,433][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
[19:04:26,433][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
[19:04:26,433][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
[19:04:26,433][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
[19:04:26,434][INFO ][main][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
[19:04:26,436][INFO ][main][GridCacheTcpClientDiscoveryMultiThreadedTest1] 

>>> +--------------------------------------------+
>>> Ignite ver. 1.0.0#19700101-sha1:DEV stopped OK
>>> +--------------------------------------------+
>>> Grid name: cache.GridCacheTcpClientDiscoveryMultiThreadedTest1
>>> Grid uptime: 00:01:03:180



class org.apache.ignite.IgniteCheckedException: null
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:828)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:1444)
	at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1312)
	at org.apache.ignite.internal.IgnitionEx.start0(IgnitionEx.java:855)
	at org.apache.ignite.internal.IgnitionEx.start(IgnitionEx.java:481)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:663)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:648)
	at org.apache.ignite.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:625)
	at org.apache.ignite.testframework.junits.GridAbstractTest$1.call(GridAbstractTest.java:569)
	at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.NullPointerException
	at org.apache.ignite.internal.MarshallerContextImpl.onMarshallerCacheStarted(MarshallerContextImpl.java:59)
	at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:742)
	at org.apache.ignite.internal.IgniteKernal.start(IgniteKernal.java:800)
	... 9 more

{code}



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