ignite-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Vladimir Ozerov (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (IGNITE-1741) [Test Failed] CacheAffinityCallSelfTest.testAffinityCallNoServerNode hangs on TC sometimes
Date Mon, 10 Jul 2017 07:00:03 GMT

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

Vladimir Ozerov updated IGNITE-1741:
------------------------------------
    Fix Version/s:     (was: 2.1)
                   2.2

> [Test Failed] CacheAffinityCallSelfTest.testAffinityCallNoServerNode hangs on TC sometimes
> ------------------------------------------------------------------------------------------
>
>                 Key: IGNITE-1741
>                 URL: https://issues.apache.org/jira/browse/IGNITE-1741
>             Project: Ignite
>          Issue Type: Test
>            Reporter: Artem Shutak
>            Priority: Critical
>              Labels: Muted_test
>             Fix For: 2.2
>
>
> Test CacheAffinityCallSelfTest.testAffinityCallNoServerNode hangs on TC sometimes.
> From thread dump I see {{affinityCall}} hangs instead of throwing  ClusterTopologyException.
> {noformat}
> java.util.concurrent.TimeoutException: Test has been timed out [test=testAffinityCallNoServerNode, timeout=300000]
>     at org.apache.ignite.testframework.junits.GridAbstractTest.runTest(GridAbstractTest.java:1630)
>     at junit.framework.TestCase.runBare(TestCase.java:141)
>     at junit.framework.TestResult$1.protect(TestResult.java:122)
>     at junit.framework.TestResult.runProtected(TestResult.java:142)
>     at junit.framework.TestResult.run(TestResult.java:125)
>     at junit.framework.TestCase.run(TestCase.java:129)
>     at junit.framework.TestSuite.runTest(TestSuite.java:255)
>     at junit.framework.TestSuite.run(TestSuite.java:250)
>     at junit.framework.TestSuite.runTest(TestSuite.java:255)
>     at junit.framework.TestSuite.run(TestSuite.java:250)
>     at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:84)
>     at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
>     at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
>     at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
>     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>     at java.lang.reflect.Method.invoke(Method.java:606)
>     at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:208)
>     at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:156)
>     at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:82)
>     at org.apache.maven.plugin.surefire.InPluginVMSurefireStarter.runSuitesInProcess(InPluginVMSurefireStarter.java:82)
>     at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:951)
>     at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:831)
>     at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:729)
>     at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:101)
>     at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:209)
>     at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:153)
>     at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:145)
>     at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:84)
>     at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:59)
>     at org.apache.maven.lifecycle.internal.LifecycleStarter.singleThreadedBuild(LifecycleStarter.java:183)
>     at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:161)
>     at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:320)
>     at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:156)
>     at org.apache.maven.cli.MavenCli.execute(MavenCli.java:537)
>     at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:196)
>     at org.apache.maven.cli.MavenCli.main(MavenCli.java:141)
>     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>     at java.lang.reflect.Method.invoke(Method.java:606)
>     at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:290)
>     at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:230)
>     at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:409)
>     at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:352)
> ------- Stdout: -------
> [00:44:00,635][INFO ][main][root] >>> Starting test class: CacheAffinityCallSelfTest <<<
> [00:44:00,638][INFO ][main][root] >>> Starting test: testAffinityCallNoServerNode <<<
> [00:44:00,638][INFO ][test-runner][root] Starting grids: 5
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Config URL: n/a
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Daemon mode: off
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] OS: Linux 3.13.0-65-generic amd64
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] OS user: teamcity
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Language runtime: Java Platform API Specification ver. 1.7
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] VM total memory: 2.7GB
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] IGNITE_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.24.1.2, -Dagent.home.dir=/usr/local/BuildAgent, -Dagent.name=ip_192.168.2.24, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/BuildAgent/work, -Dbuild.number=3825, -Dbuild.vcs.number=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.1=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dclassworlds.conf=/usr/local/BuildAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/BuildAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/BuildAgent/temp/buildTmp, -Dmaven.home=/usr/local/BuildAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=724, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=553198, -Dteamcity.auth.password=HyhA0UFlfBmEZMo0iwR8txiMBNsSiF5d, -Dteamcity.auth.userId=TeamCityBuildId=553198, -Dteamcity.build.changedFiles.file=/usr/local/BuildAgent/temp/buildTmp/changedFiles841970307114981409.txt, -Dteamcity.build.checkoutDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.build.id=553198, -Dteamcity.build.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.build8620170127122988525.properties, -Dteamcity.build.tempDir=/usr/local/BuildAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache, -Dteamcity.buildType.id=Ignite_DataGrid, -Dteamcity.configuration.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.config9185952440533343273.properties, -Dteamcity.maven.watcher.home=/usr/local/BuildAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.runner1796536891430219731.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/BuildAgent/temp/buildTmp/testsToRunFirst4024402862108205725.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/BuildAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintGCTimeStamps]
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'myCache']
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [00:44:00,642][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Config URL: n/a
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Daemon mode: off
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] OS: Linux 3.13.0-65-generic amd64
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] OS user: teamcity
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Language runtime: Java Platform API Specification ver. 1.7
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] 3-rd party licenses can be found at: /usr/local/BuildAgent/work/871ff4a46e450b13/libs/licenses
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] VM total memory: 2.7GB
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] IGNITE_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.24.1.2, -Dagent.home.dir=/usr/local/BuildAgent, -Dagent.name=ip_192.168.2.24, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/BuildAgent/work, -Dbuild.number=3825, -Dbuild.vcs.number=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.1=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dclassworlds.conf=/usr/local/BuildAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/BuildAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/BuildAgent/temp/buildTmp, -Dmaven.home=/usr/local/BuildAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=724, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=553198, -Dteamcity.auth.password=HyhA0UFlfBmEZMo0iwR8txiMBNsSiF5d, -Dteamcity.auth.userId=TeamCityBuildId=553198, -Dteamcity.build.changedFiles.file=/usr/local/BuildAgent/temp/buildTmp/changedFiles841970307114981409.txt, -Dteamcity.build.checkoutDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.build.id=553198, -Dteamcity.build.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.build8620170127122988525.properties, -Dteamcity.build.tempDir=/usr/local/BuildAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache, -Dteamcity.buildType.id=Ignite_DataGrid, -Dteamcity.configuration.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.config9185952440533343273.properties, -Dteamcity.maven.watcher.home=/usr/local/BuildAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.runner1796536891430219731.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/BuildAgent/temp/buildTmp/testsToRunFirst4024402862108205725.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/BuildAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintGCTimeStamps]
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'myCache']
> [00:44:00,643][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] 3-rd party licenses can be found at: /usr/local/BuildAgent/work/871ff4a46e450b13/libs/licenses
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Config URL: n/a
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Daemon mode: off
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] OS: Linux 3.13.0-65-generic amd64
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] OS user: teamcity
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Config URL: n/a
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Daemon mode: off
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] OS: Linux 3.13.0-65-generic amd64
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Language runtime: Java Platform API Specification ver. 1.7
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [00:44:00,644][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] OS user: teamcity
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] VM total memory: 2.7GB
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Language runtime: Java Platform API Specification ver. 1.7
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] IGNITE_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] VM total memory: 2.7GB
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.24.1.2, -Dagent.home.dir=/usr/local/BuildAgent, -Dagent.name=ip_192.168.2.24, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/BuildAgent/work, -Dbuild.number=3825, -Dbuild.vcs.number=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.1=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dclassworlds.conf=/usr/local/BuildAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/BuildAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/BuildAgent/temp/buildTmp, -Dmaven.home=/usr/local/BuildAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=724, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=553198, -Dteamcity.auth.password=HyhA0UFlfBmEZMo0iwR8txiMBNsSiF5d, -Dteamcity.auth.userId=TeamCityBuildId=553198, -Dteamcity.build.changedFiles.file=/usr/local/BuildAgent/temp/buildTmp/changedFiles841970307114981409.txt, -Dteamcity.build.checkoutDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.build.id=553198, -Dteamcity.build.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.build8620170127122988525.properties, -Dteamcity.build.tempDir=/usr/local/BuildAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache, -Dteamcity.buildType.id=Ignite_DataGrid, -Dteamcity.configuration.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.config9185952440533343273.properties, -Dteamcity.maven.watcher.home=/usr/local/BuildAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.runner1796536891430219731.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/BuildAgent/temp/buildTmp/testsToRunFirst4024402862108205725.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/BuildAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintGCTimeStamps]
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] IGNITE_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'myCache']
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Non-loopback local IPs: 192.168.2.24, fe80:0:0:0:96de:80ff:feba:5432%2
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Enabled local MACs: 94DE80BA5432
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.24.1.2, -Dagent.home.dir=/usr/local/BuildAgent, -Dagent.name=ip_192.168.2.24, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/BuildAgent/work, -Dbuild.number=3825, -Dbuild.vcs.number=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.1=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dclassworlds.conf=/usr/local/BuildAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/BuildAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/BuildAgent/temp/buildTmp, -Dmaven.home=/usr/local/BuildAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=724, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=553198, -Dteamcity.auth.password=HyhA0UFlfBmEZMo0iwR8txiMBNsSiF5d, -Dteamcity.auth.userId=TeamCityBuildId=553198, -Dteamcity.build.changedFiles.file=/usr/local/BuildAgent/temp/buildTmp/changedFiles841970307114981409.txt, -Dteamcity.build.checkoutDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.build.id=553198, -Dteamcity.build.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.build8620170127122988525.properties, -Dteamcity.build.tempDir=/usr/local/BuildAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache, -Dteamcity.buildType.id=Ignite_DataGrid, -Dteamcity.configuration.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.config9185952440533343273.properties, -Dteamcity.maven.watcher.home=/usr/local/BuildAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.runner1796536891430219731.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/BuildAgent/temp/buildTmp/testsToRunFirst4024402862108205725.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/BuildAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintGCTimeStamps]
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'myCache']
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-1][IgnitePluginProcessor] Configured plugins:
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-1][IgnitePluginProcessor]   ^-- None
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-1][IgnitePluginProcessor] 
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] 3-rd party licenses can be found at: /usr/local/BuildAgent/work/871ff4a46e450b13/libs/licenses
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Non-loopback local IPs: 192.168.2.24, fe80:0:0:0:96de:80ff:feba:5432%2
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Enabled local MACs: 94DE80BA5432
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-4][IgnitePluginProcessor] Configured plugins:
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-4][IgnitePluginProcessor]   ^-- None
> [00:44:00,645][INFO ][grid-starter-testAffinityCallNoServerNode-4][IgnitePluginProcessor] 
> [00:44:00,646][INFO ][grid-starter-testAffinityCallNoServerNode-1][TcpCommunicationSpi] IPC shared memory server endpoint started [port=48100, tokDir=/usr/local/BuildAgent/temp/buildTmp/ignite/work/ipc/shmem/006f53ca-0cd4-4e6c-95cc-87f45a777000-27089]
> [00:44:00,646][INFO ][grid-starter-testAffinityCallNoServerNode-1][TcpCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48100, locHost=/127.0.0.1]
> [00:44:00,646][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] 3-rd party licenses can be found at: /usr/local/BuildAgent/work/871ff4a46e450b13/libs/licenses
> [00:44:00,646][INFO ][grid-starter-testAffinityCallNoServerNode-1][TcpCommunicationSpi] Successfully bound to TCP port [port=45400, locHost=/127.0.0.1]
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Non-loopback local IPs: 192.168.2.24, fe80:0:0:0:96de:80ff:feba:5432%2
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Enabled local MACs: 94DE80BA5432
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-4][TcpCommunicationSpi] IPC shared memory server endpoint started [port=48101, tokDir=/usr/local/BuildAgent/temp/buildTmp/ignite/work/ipc/shmem/30898011-3481-4fc9-a41d-961a9ee5d003-27089]
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-4][TcpCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48101, locHost=/127.0.0.1]
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-3][IgnitePluginProcessor] Configured plugins:
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-3][IgnitePluginProcessor]   ^-- None
> [00:44:00,647][INFO ][grid-starter-testAffinityCallNoServerNode-3][IgnitePluginProcessor] 
> [00:44:00,649][INFO ][grid-starter-testAffinityCallNoServerNode-3][TcpCommunicationSpi] IPC shared memory server endpoint started [port=48102, tokDir=/usr/local/BuildAgent/temp/buildTmp/ignite/work/ipc/shmem/20b06beb-2d40-4efb-8b86-432167e5f002-27089]
> [00:44:00,649][INFO ][grid-starter-testAffinityCallNoServerNode-3][TcpCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48102, locHost=/127.0.0.1]
> [00:44:00,650][INFO ][grid-starter-testAffinityCallNoServerNode-3][TcpCommunicationSpi] Successfully bound to TCP port [port=45402, locHost=/127.0.0.1]
> [00:44:00,650][INFO ][grid-starter-testAffinityCallNoServerNode-4][TcpCommunicationSpi] Successfully bound to TCP port [port=45401, locHost=/127.0.0.1]
> [00:44:00,651][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Security status [authentication=off, tls/ssl=off]
> [00:44:00,651][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Non-loopback local IPs: 192.168.2.24, fe80:0:0:0:96de:80ff:feba:5432%2
> [00:44:00,651][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Enabled local MACs: 94DE80BA5432
> [00:44:00,651][INFO ][grid-starter-testAffinityCallNoServerNode-5][IgnitePluginProcessor] Configured plugins:
> [00:44:00,651][INFO ][grid-starter-testAffinityCallNoServerNode-5][IgnitePluginProcessor]   ^-- None
> [00:44:00,651][INFO ][grid-starter-testAffinityCallNoServerNode-5][IgnitePluginProcessor] 
> [00:44:00,652][INFO ][grid-starter-testAffinityCallNoServerNode-5][TcpCommunicationSpi] IPC shared memory server endpoint started [port=48103, tokDir=/usr/local/BuildAgent/temp/buildTmp/ignite/work/ipc/shmem/40389643-57fb-43e2-a3ce-b88236309004-27089]
> [00:44:00,652][INFO ][grid-starter-testAffinityCallNoServerNode-5][TcpCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48103, locHost=/127.0.0.1]
> [00:44:00,652][INFO ][grid-starter-testAffinityCallNoServerNode-5][TcpCommunicationSpi] Successfully bound to TCP port [port=45403, locHost=/127.0.0.1]
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Security status [authentication=off, tls/ssl=off]
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Security status [authentication=off, tls/ssl=off]
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Config URL: n/a
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Daemon mode: off
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] OS: Linux 3.13.0-65-generic amd64
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] OS user: teamcity
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Language runtime: Java Platform API Specification ver. 1.7
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] VM total memory: 2.7GB
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] IGNITE_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.24.1.2, -Dagent.home.dir=/usr/local/BuildAgent, -Dagent.name=ip_192.168.2.24, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/BuildAgent/work, -Dbuild.number=3825, -Dbuild.vcs.number=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.1=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=bc6bf5fcd2c6fd996c3f3d2b1731afed26338091, -Dclassworlds.conf=/usr/local/BuildAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/BuildAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/BuildAgent/temp/buildTmp, -Dmaven.home=/usr/local/BuildAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=724, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=553198, -Dteamcity.auth.password=HyhA0UFlfBmEZMo0iwR8txiMBNsSiF5d, -Dteamcity.auth.userId=TeamCityBuildId=553198, -Dteamcity.build.changedFiles.file=/usr/local/BuildAgent/temp/buildTmp/changedFiles841970307114981409.txt, -Dteamcity.build.checkoutDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.build.id=553198, -Dteamcity.build.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.build8620170127122988525.properties, -Dteamcity.build.tempDir=/usr/local/BuildAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/BuildAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache, -Dteamcity.buildType.id=Ignite_DataGrid, -Dteamcity.configuration.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.config9185952440533343273.properties, -Dteamcity.maven.watcher.home=/usr/local/BuildAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/BuildAgent/temp/buildTmp/teamcity.runner1796536891430219731.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/BuildAgent/temp/buildTmp/testsToRunFirst4024402862108205725.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/BuildAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/BuildAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintGCTimeStamps]
> [00:44:00,653][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'myCache']
> [00:44:00,654][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] 3-rd party licenses can be found at: /usr/local/BuildAgent/work/871ff4a46e450b13/libs/licenses
> [00:44:00,654][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Security status [authentication=off, tls/ssl=off]
> [00:44:00,654][INFO ][grid-starter-testAffinityCallNoServerNode-1][TcpDiscoverySpi] Successfully bound to TCP port [port=47500, localHost=/127.0.0.1]
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Non-loopback local IPs: 192.168.2.24, fe80:0:0:0:96de:80ff:feba:5432%2
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Enabled local MACs: 94DE80BA5432
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-2][IgnitePluginProcessor] Configured plugins:
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-2][IgnitePluginProcessor]   ^-- None
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-2][IgnitePluginProcessor] 
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-3][TcpDiscoverySpi] Successfully bound to TCP port [port=47501, localHost=/127.0.0.1]
> [00:44:00,656][INFO ][grid-starter-testAffinityCallNoServerNode-5][TcpDiscoverySpi] Successfully bound to TCP port [port=47502, localHost=/127.0.0.1]
> [00:44:00,657][INFO ][grid-starter-testAffinityCallNoServerNode-4][TcpDiscoverySpi] Successfully bound to TCP port [port=47503, localHost=/127.0.0.1]
> [00:44:00,657][INFO ][grid-starter-testAffinityCallNoServerNode-2][TcpCommunicationSpi] IPC shared memory server endpoint started [port=48104, tokDir=/usr/local/BuildAgent/temp/buildTmp/ignite/work/ipc/shmem/100ab86a-4846-4cdd-bcf5-ad30e1af0001-27089]
> [00:44:00,657][INFO ][grid-starter-testAffinityCallNoServerNode-2][TcpCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48104, locHost=/127.0.0.1]
> [00:44:00,657][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [00:44:00,658][INFO ][grid-starter-testAffinityCallNoServerNode-2][TcpCommunicationSpi] Successfully bound to TCP port [port=45404, locHost=/127.0.0.1]
> [00:44:00,658][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [00:44:00,659][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Security status [authentication=off, tls/ssl=off]
> [00:44:00,660][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridCacheProcessor] Started cache [name=myCache, mode=PARTITIONED]
> [00:44:00,662][INFO ][grid-starter-testAffinityCallNoServerNode-2][TcpDiscoverySpi] Successfully bound to TCP port [port=47504, localHost=/127.0.0.1]
> [00:44:00,665][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=40389643-57fb-43e2-a3ce-b88236309004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=2, intOrder=2, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,665][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=2, servers=1, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,667][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,667][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3, servers=2, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,668][INFO ][grid-starter-testAffinityCallNoServerNode-5][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [00:44:00,668][INFO ][grid-starter-testAffinityCallNoServerNode-5][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [00:44:00,668][INFO ][grid-starter-testAffinityCallNoServerNode-5][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [00:44:00,669][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,669][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=3, servers=2, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,669][INFO ][grid-starter-testAffinityCallNoServerNode-5][GridCacheProcessor] Started cache [name=myCache, mode=PARTITIONED]
> [00:44:00,671][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,672][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,672][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,672][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,672][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,672][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [00:44:00,672][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,673][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [00:44:00,674][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [00:44:00,675][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridCacheProcessor] Started cache [name=myCache, mode=PARTITIONED]
> [00:44:00,675][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [00:44:00,676][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,676][INFO ][disco-event-worker-#24494%cache.CacheAffinityCallSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,677][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,677][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,677][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [00:44:00,677][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,677][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,678][INFO ][disco-event-worker-#24517%cache.CacheAffinityCallSelfTest3%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:00,678][INFO ][disco-event-worker-#24517%cache.CacheAffinityCallSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,678][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [00:44:00,680][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [00:44:00,680][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [00:44:00,680][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [00:44:00,680][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [00:44:00,680][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridDhtPreloader] <myCache> Starting rebalancing in ASYNC mode: myCache
> [00:44:00,680][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridCacheProcessor] Started cache [name=myCache, mode=PARTITIONED]
> [00:44:00,681][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [00:44:00,682][INFO ][preloader-demand-worker-#24534%cache.CacheAffinityCallSelfTest0%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [00:44:00,682][INFO ][preloader-demand-worker-#24526%cache.CacheAffinityCallSelfTest0%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [00:44:00,682][INFO ][preloader-demand-worker-#24530%cache.CacheAffinityCallSelfTest0%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
> [00:44:00,682][INFO ][preloader-demand-worker-#24539%cache.CacheAffinityCallSelfTest0%][GridDhtPreloader] <myCache> Completed rebalancing in ASYNC mode [cache=myCache, time=0 ms]
> [00:44:00,683][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [00:44:00,685][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridCacheProcessor] Started cache [name=myCache, mode=PARTITIONED]
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Performance suggestions for grid 'cache.CacheAffinityCallSelfTest0' (fix if possible)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Disable near cache (set 'nearConfiguration' to null)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Decrease number of backups (set 'keyBackups' to 0)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] 
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [00:44:00,688][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] 
> [00:44:00,689][INFO ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] 
> >>> +------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> +------------------------------------------+
> >>> OS name: Linux 3.13.0-65-generic amd64
> >>> CPU(s): 8
> >>> Heap: 2.7GB
> >>> VM name: 27089@teamcity24
> >>> Grid name: cache.CacheAffinityCallSelfTest0
> >>> Local node [ID=006F53CA-0CD4-4E6C-95CC-87F45A777000, order=1, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:45400 TCP:47500 TCP:48100 
> [00:44:00,689][INFO ][grid-starter-testAffinityCallNoServerNode-1][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,805][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [00:44:00,805][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [00:44:00,805][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [00:44:00,805][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridDhtPreloader] <myCache> Starting rebalancing in ASYNC mode: myCache
> [00:44:00,811][INFO ][preloader-demand-worker-#24555%cache.CacheAffinityCallSelfTest2%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=11 ms]
> [00:44:00,814][INFO ][preloader-demand-worker-#24560%cache.CacheAffinityCallSelfTest2%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=11 ms]
> [00:44:00,814][INFO ][preloader-demand-worker-#24564%cache.CacheAffinityCallSelfTest2%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=11 ms]
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Performance suggestions for grid 'cache.CacheAffinityCallSelfTest4' (fix if possible)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Enable client mode for TcpDiscoverySpi (set TcpDiscoverySpi.forceServerMode to false)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Disable near cache (set 'nearConfiguration' to null)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Decrease number of backups (set 'keyBackups' to 0)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] 
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] 
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] 
> >>> +------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> +------------------------------------------+
> >>> OS name: Linux 3.13.0-65-generic amd64
> >>> CPU(s): 8
> >>> Heap: 2.7GB
> >>> VM name: 27089@teamcity24
> >>> Grid name: cache.CacheAffinityCallSelfTest4
> >>> Local node [ID=40389643-57FB-43E2-A3CE-B88236309004, order=2, clientMode=true]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:45403 TCP:47502 TCP:48103 
> [00:44:00,818][INFO ][grid-starter-testAffinityCallNoServerNode-5][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Performance suggestions for grid 'cache.CacheAffinityCallSelfTest2' (fix if possible)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Disable near cache (set 'nearConfiguration' to null)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Decrease number of backups (set 'keyBackups' to 0)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] 
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] 
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] 
> >>> +------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> +------------------------------------------+
> >>> OS name: Linux 3.13.0-65-generic amd64
> >>> CPU(s): 8
> >>> Heap: 2.7GB
> >>> VM name: 27089@teamcity24
> >>> Grid name: cache.CacheAffinityCallSelfTest2
> >>> Local node [ID=20B06BEB-2D40-4EFB-8B86-432167E5F002, order=3, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:45402 TCP:47501 TCP:48102 
> [00:44:00,823][INFO ][grid-starter-testAffinityCallNoServerNode-3][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,825][INFO ][preloader-demand-worker-#24568%cache.CacheAffinityCallSelfTest2%][GridDhtPreloader] <myCache> Completed rebalancing in ASYNC mode [cache=myCache, time=21 ms]
> [00:44:00,840][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [00:44:00,840][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [00:44:00,840][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [00:44:00,840][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridDhtPreloader] <myCache> Starting rebalancing in ASYNC mode: myCache
> [00:44:00,840][INFO ][preloader-demand-worker-#24575%cache.CacheAffinityCallSelfTest3%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [00:44:00,841][INFO ][preloader-demand-worker-#24579%cache.CacheAffinityCallSelfTest3%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
> [00:44:00,841][INFO ][preloader-demand-worker-#24583%cache.CacheAffinityCallSelfTest3%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [00:44:00,841][INFO ][preloader-demand-worker-#24587%cache.CacheAffinityCallSelfTest3%][GridDhtPreloader] <myCache> Completed rebalancing in ASYNC mode [cache=myCache, time=0 ms]
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Performance suggestions for grid 'cache.CacheAffinityCallSelfTest3' (fix if possible)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Disable near cache (set 'nearConfiguration' to null)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Decrease number of backups (set 'keyBackups' to 0)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] 
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] 
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] 
> >>> +------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> +------------------------------------------+
> >>> OS name: Linux 3.13.0-65-generic amd64
> >>> CPU(s): 8
> >>> Heap: 2.7GB
> >>> VM name: 27089@teamcity24
> >>> Grid name: cache.CacheAffinityCallSelfTest3
> >>> Local node [ID=30898011-3481-4FC9-A41D-961A9EE5D003, order=4, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:45401 TCP:47503 TCP:48101 
> [00:44:00,846][INFO ][grid-starter-testAffinityCallNoServerNode-4][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:00,880][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [00:44:00,880][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [00:44:00,880][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [00:44:00,880][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridDhtPreloader] <myCache> Starting rebalancing in ASYNC mode: myCache
> [00:44:00,882][INFO ][preloader-demand-worker-#24599%cache.CacheAffinityCallSelfTest1%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [00:44:00,884][INFO ][preloader-demand-worker-#24610%cache.CacheAffinityCallSelfTest1%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=10 ms]
> [00:44:00,885][INFO ][preloader-demand-worker-#24604%cache.CacheAffinityCallSelfTest1%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=10 ms]
> [00:44:00,885][INFO ][preloader-demand-worker-#24617%cache.CacheAffinityCallSelfTest1%][GridDhtPreloader] <myCache> Completed rebalancing in ASYNC mode [cache=myCache, time=10 ms]
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Performance suggestions for grid 'cache.CacheAffinityCallSelfTest1' (fix if possible)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Disable near cache (set 'nearConfiguration' to null)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Decrease number of backups (set 'keyBackups' to 0)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to ATOMIC)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1]   ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC)
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] 
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] 
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] 
> >>> +------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> +------------------------------------------+
> >>> OS name: Linux 3.13.0-65-generic amd64
> >>> CPU(s): 8
> >>> Heap: 2.7GB
> >>> VM name: 27089@teamcity24
> >>> Grid name: cache.CacheAffinityCallSelfTest1
> >>> Local node [ID=100AB86A-4846-4CDD-BCF5-AD30E1AF0001, order=5, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:45404 TCP:47504 TCP:48104 
> [00:44:00,891][INFO ][grid-starter-testAffinityCallNoServerNode-2][GridDiscoveryManager] Topology snapshot [ver=5, servers=4, clients=1, CPUs=8, heap=2.7GB]
> [00:44:03,917][INFO ][async-runner-1][root] >>> Stopping grid [name=cache.CacheAffinityCallSelfTest0, id=006f53ca-0cd4-4e6c-95cc-87f45a777000]
> [00:44:03,920][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:03,921][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:03,921][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:03,921][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:03,921][INFO ][disco-event-worker-#24517%cache.CacheAffinityCallSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:03,921][INFO ][disco-event-worker-#24517%cache.CacheAffinityCallSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:03,921][INFO ][disco-event-worker-#24520%cache.CacheAffinityCallSelfTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:03,921][INFO ][disco-event-worker-#24520%cache.CacheAffinityCallSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=8, heap=2.7GB]
> [00:44:03,929][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: myCache
> [00:44:03,929][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [00:44:03,929][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [00:44:03,929][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [00:44:03,929][INFO ][async-runner-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1445118240800, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=bae1b597051-006f53ca-0cd4-4e6c-95cc-87f45a777000, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap, pendingUndeploy=false, undeployed=true, usage=0]
> [00:44:03,929][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCacheIoManager$1$1$1
> [00:44:03,929][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [00:44:03,929][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [00:44:03,929][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [00:44:03,929][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.portable.CacheObjectPortableProcessorImpl$MetaDataPredicate
> [00:44:03,935][INFO ][async-runner-1][CacheAffinityCallSelfTest0] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.CacheAffinityCallSelfTest0
> >>> Grid uptime: 00:00:03:245
> [00:44:03,936][INFO ][async-runner-1][root] >>> Stopping grid [name=cache.CacheAffinityCallSelfTest1, id=100ab86a-4846-4cdd-bcf5-ad30e1af0001]
> [00:44:04,009][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:04,009][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=7, servers=2, clients=1, CPUs=8, heap=2.7GB]
> [00:44:04,009][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:04,009][INFO ][disco-event-worker-#24515%cache.CacheAffinityCallSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=7, servers=2, clients=1, CPUs=8, heap=2.7GB]
> [00:44:04,010][INFO ][disco-event-worker-#24517%cache.CacheAffinityCallSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:04,010][INFO ][disco-event-worker-#24517%cache.CacheAffinityCallSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=7, servers=2, clients=1, CPUs=8, heap=2.7GB]
> [00:44:04,010][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: myCache
> [00:44:04,010][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [00:44:04,010][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [00:44:04,010][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [00:44:04,010][INFO ][async-runner-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1445118240872, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=7332b597051-100ab86a-4846-4cdd-bcf5-ad30e1af0001, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [00:44:04,010][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [00:44:04,010][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [00:44:04,010][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [00:44:04,010][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [00:44:04,013][INFO ][async-runner-1][CacheAffinityCallSelfTest1] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.CacheAffinityCallSelfTest1
> >>> Grid uptime: 00:00:03:120
> [00:44:04,013][INFO ][async-runner-1][root] >>> Stopping grid [name=cache.CacheAffinityCallSelfTest2, id=20b06beb-2d40-4efb-8b86-432167e5f002]
> [00:44:06,027][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:06,027][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: myCache
> [00:44:06,027][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [00:44:06,027][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=8, servers=1, clients=1, CPUs=8, heap=2.7GB]
> [00:44:06,027][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [00:44:06,028][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [00:44:06,028][INFO ][async-runner-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1445118240800, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=8ce1b597051-20b06beb-2d40-4efb-8b86-432167e5f002, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [00:44:06,028][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [00:44:06,028][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [00:44:06,028][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [00:44:06,028][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [00:44:06,031][INFO ][async-runner-1][CacheAffinityCallSelfTest2] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.CacheAffinityCallSelfTest2
> >>> Grid uptime: 00:00:05:202
> [00:44:06,032][INFO ][async-runner-1][root] >>> Stopping grid [name=cache.CacheAffinityCallSelfTest3, id=30898011-3481-4fc9-a41d-961a9ee5d003]
> [00:44:06,037][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [00:44:06,037][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: myCache
> [00:44:06,037][INFO ][disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%][GridDiscoveryManager] Topology snapshot [ver=9, servers=0, clients=1, CPUs=8, heap=2.7GB]
> [00:44:06,037][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [00:44:06,037][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [00:44:06,037][INFO ][async-runner-1][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [00:44:06,037][INFO ][async-runner-1][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1445118240831, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=d412b597051-30898011-3481-4fc9-a41d-961a9ee5d003, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [00:44:06,037][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [00:44:06,037][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheAffinityCallSelfTest$CheckCallable
> [00:44:06,037][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [00:44:06,037][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [00:44:06,037][INFO ][async-runner-1][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [00:44:06,040][INFO ][async-runner-1][CacheAffinityCallSelfTest3] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.CacheAffinityCallSelfTest3
> >>> Grid uptime: 00:00:05:193
> [00:49:00] (wrn) No deadlocked threads detected.
> [00:49:00] (wrn) Thread dump at 2015/10/18 00:49:00 MSK
> Thread [name="ignite-#24632%pub-cache.CacheAffinityCallSelfTest4%", id=30298, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24631%pub-cache.CacheAffinityCallSelfTest4%", id=30297, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="tcp-disco-sock-reader-#3601%cache.CacheAffinityCallSelfTest4", id=30285, state=RUNNABLE, blockCnt=2, waitCnt=0]
>         at java.net.SocketInputStream.socketRead0(Native Method)
>         at java.net.SocketInputStream.read(SocketInputStream.java:152)
>         at java.net.SocketInputStream.read(SocketInputStream.java:122)
>         at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
>         at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
>         at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
>         - locked java.io.BufferedInputStream@6fd6426
>         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
>         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2310)
>         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2323)
>         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
>         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
>         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
>         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:39)
>         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal(JdkMarshaller.java:100)
>         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:4785)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="ignite-#24627%pub-cache.CacheAffinityCallSelfTest4%", id=30284, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24623%pub-cache.CacheAffinityCallSelfTest4%", id=30277, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="tcp-disco-sock-reader-#3600%cache.CacheAffinityCallSelfTest4", id=30276, state=RUNNABLE, blockCnt=3, waitCnt=0]
>         at java.net.SocketInputStream.socketRead0(Native Method)
>         at java.net.SocketInputStream.read(SocketInputStream.java:152)
>         at java.net.SocketInputStream.read(SocketInputStream.java:122)
>         at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
>         at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
>         at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
>         - locked java.io.BufferedInputStream@15eb4326
>         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
>         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2310)
>         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2323)
>         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
>         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
>         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
>         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:39)
>         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal(JdkMarshaller.java:100)
>         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:4785)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="grid-time-coordinator-#24622%cache.CacheAffinityCallSelfTest4%", id=30275, state=TIMED_WAITING, blockCnt=5, waitCnt=10]
>     Lock [object=o.a.i.i.processors.clock.GridClockSyncProcessor$TimeCoordinator@8857f08, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at o.a.i.i.processors.clock.GridClockSyncProcessor$TimeCoordinator.body(GridClockSyncProcessor.java:386)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="srvc-deploy-#24559%cache.CacheAffinityCallSelfTest4%", id=30205, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7456ea96, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24553%utility-cache.CacheAffinityCallSelfTest4%", id=30199, state=WAITING, blockCnt=0, waitCnt=1]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@76435985, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ttl-cleanup-worker-myCache-#24550%cache.CacheAffinityCallSelfTest4%", id=30196, state=TIMED_WAITING, blockCnt=0, waitCnt=600]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.i.util.IgniteUtils.sleep(IgniteUtils.java:7251)
>         at o.a.i.i.processors.cache.GridCacheTtlManager$CleanupWorker.body(GridCacheTtlManager.java:147)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="exchange-worker-#24522%cache.CacheAffinityCallSelfTest4%", id=30161, state=WAITING, blockCnt=0, waitCnt=9]
>     Lock [object=o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture@3faa676b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:834)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:994)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1303)
>         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:157)
>         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:115)
>         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body(GridCachePartitionExchangeManager.java:1154)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-time-server-reader-#24514%cache.CacheAffinityCallSelfTest4%", id=30146, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at java.net.PlainDatagramSocketImpl.receive0(Native Method)
>         - locked java.net.PlainDatagramSocketImpl@4c6bc274
>         at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:146)
>         - locked java.net.PlainDatagramSocketImpl@4c6bc274
>         at java.net.DatagramSocket.receive(DatagramSocket.java:817)
>         - locked java.net.DatagramPacket@3285b6dc
>         - locked java.net.DatagramSocket@68a83f6d
>         at o.a.i.i.processors.clock.GridClockServer$ReadWorker.body(GridClockServer.java:199)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%", id=30145, state=WAITING, blockCnt=3, waitCnt=238]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7ddc42c8, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body0(GridDiscoveryManager.java:2007)
>         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body(GridDiscoveryManager.java:1989)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="tcp-disco-ip-finder-cleaner-#3592%cache.CacheAffinityCallSelfTest4", id=30144, state=TIMED_WAITING, blockCnt=0, waitCnt=5]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.spi.discovery.tcp.ServerImpl$IpFinderCleaner.body(ServerImpl.java:1634)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-disco-sock-reader-#3585%cache.CacheAffinityCallSelfTest4", id=30135, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at java.net.SocketInputStream.socketRead0(Native Method)
>         at java.net.SocketInputStream.read(SocketInputStream.java:152)
>         at java.net.SocketInputStream.read(SocketInputStream.java:122)
>         at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
>         at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
>         at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
>         - locked java.io.BufferedInputStream@473a5cd5
>         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
>         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2310)
>         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2323)
>         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
>         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
>         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
>         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:39)
>         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal(JdkMarshaller.java:100)
>         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:4785)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-disco-srvr-#3576%cache.CacheAffinityCallSelfTest4", id=30119, state=RUNNABLE, blockCnt=1, waitCnt=0]
>         at java.net.PlainSocketImpl.socketAccept(Native Method)
>         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
>         at java.net.ServerSocket.implAccept(ServerSocket.java:530)
>         at java.net.ServerSocket.accept(ServerSocket.java:498)
>         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServer.body(ServerImpl.java:4492)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-disco-msg-worker-#3574%cache.CacheAffinityCallSelfTest4", id=30116, state=TIMED_WAITING, blockCnt=10, waitCnt=29822]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@48a4c87b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
>         at java.util.concurrent.LinkedBlockingDeque.pollFirst(LinkedBlockingDeque.java:519)
>         at java.util.concurrent.LinkedBlockingDeque.poll(LinkedBlockingDeque.java:682)
>         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorkerAdapter.body(ServerImpl.java:5377)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="grid-data-loader-flusher-#24502%cache.CacheAffinityCallSelfTest4%", id=30113, state=WAITING, blockCnt=0, waitCnt=1]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@3a66287f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.DelayQueue.take(DelayQueue.java:209)
>         at o.a.i.i.processors.datastreamer.DataStreamProcessor$2.body(DataStreamProcessor.java:92)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-sharedfs-timeout-worker-#3568%cache.CacheAffinityCallSelfTest4", id=30096, state=TIMED_WAITING, blockCnt=0, waitCnt=60]
>     Lock [object=java.lang.Object@8ecc7cd, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at o.a.i.spi.checkpoint.sharedfs.SharedFsTimeoutTask.body(SharedFsTimeoutTask.java:99)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-comm-worker-#3567%cache.CacheAffinityCallSelfTest4", id=30095, state=TIMED_WAITING, blockCnt=0, waitCnt=10]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7131684e, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
>         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
>         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$CommunicationWorker.body(TcpCommunicationSpi.java:3004)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="grid-nio-worker-3-#24488%cache.CacheAffinityCallSelfTest4%", id=30093, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@62b712f0
>         - locked java.util.Collections$UnmodifiableSet@613c7d31
>         - locked sun.nio.ch.EPollSelectorImpl@4a3f4dd6
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-nio-worker-2-#24487%cache.CacheAffinityCallSelfTest4%", id=30092, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@20f42af
>         - locked java.util.Collections$UnmodifiableSet@37e2f037
>         - locked sun.nio.ch.EPollSelectorImpl@1408c78b
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-nio-worker-1-#24486%cache.CacheAffinityCallSelfTest4%", id=30091, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@4c6480f3
>         - locked java.util.Collections$UnmodifiableSet@5a4df8b3
>         - locked sun.nio.ch.EPollSelectorImpl@3502de16
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-nio-worker-0-#24485%cache.CacheAffinityCallSelfTest4%", id=30090, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@4f42918a
>         - locked java.util.Collections$UnmodifiableSet@1b6123ad
>         - locked sun.nio.ch.EPollSelectorImpl@66d40e6
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="nio-acceptor-#24484%cache.CacheAffinityCallSelfTest4%", id=30089, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@6ebfbf55
>         - locked java.util.Collections$UnmodifiableSet@6eaa9e40
>         - locked sun.nio.ch.EPollSelectorImpl@37bb483
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:1761)
>         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:1733)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="shmem-communication-acceptor-#24489%cache.CacheAffinityCallSelfTest4%", id=30094, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at java.net.PlainSocketImpl.socketAccept(Native Method)
>         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
>         at java.net.ServerSocket.implAccept(ServerSocket.java:530)
>         at java.net.ServerSocket.accept(ServerSocket.java:498)
>         at o.a.i.i.util.ipc.shmem.IpcSharedMemoryServerEndpoint.accept(IpcSharedMemoryServerEndpoint.java:221)
>         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$ShmemAcceptWorker.body(TcpCommunicationSpi.java:2867)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ipc-shmem-gc-#24483%cache.CacheAffinityCallSelfTest4%", id=30088, state=TIMED_WAITING, blockCnt=0, waitCnt=30]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.i.util.ipc.shmem.IpcSharedMemoryServerEndpoint$GcWorker.body(IpcSharedMemoryServerEndpoint.java:554)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-timeout-worker-#24480%cache.CacheAffinityCallSelfTest4%", id=30084, state=TIMED_WAITING, blockCnt=3, waitCnt=161]
>     Lock [object=java.lang.Object@2e88063e, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at o.a.i.i.processors.timeout.GridTimeoutProcessor$TimeoutWorker.body(GridTimeoutProcessor.java:184)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24450%pub-cache.CacheAffinityCallSelfTest4%", id=30049, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24449%pub-cache.CacheAffinityCallSelfTest4%", id=30048, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24446%pub-cache.CacheAffinityCallSelfTest4%", id=30046, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24445%pub-cache.CacheAffinityCallSelfTest4%", id=30043, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24435%pub-cache.CacheAffinityCallSelfTest4%", id=30039, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24430%pub-cache.CacheAffinityCallSelfTest4%", id=30031, state=WAITING, blockCnt=1, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24422%sys-cache.CacheAffinityCallSelfTest4%", id=30021, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24419%sys-cache.CacheAffinityCallSelfTest4%", id=30019, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24417%sys-cache.CacheAffinityCallSelfTest4%", id=30017, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24415%sys-cache.CacheAffinityCallSelfTest4%", id=30015, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24413%sys-cache.CacheAffinityCallSelfTest4%", id=30013, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24411%sys-cache.CacheAffinityCallSelfTest4%", id=30011, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24409%sys-cache.CacheAffinityCallSelfTest4%", id=30009, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24407%sys-cache.CacheAffinityCallSelfTest4%", id=30007, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24405%sys-cache.CacheAffinityCallSelfTest4%", id=30005, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24403%sys-cache.CacheAffinityCallSelfTest4%", id=30003, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24401%sys-cache.CacheAffinityCallSelfTest4%", id=30001, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24400%sys-cache.CacheAffinityCallSelfTest4%", id=29999, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24399%sys-cache.CacheAffinityCallSelfTest4%", id=29998, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24398%sys-cache.CacheAffinityCallSelfTest4%", id=29997, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24396%sys-cache.CacheAffinityCallSelfTest4%", id=29996, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24394%sys-cache.CacheAffinityCallSelfTest4%", id=29994, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="test-runner", id=29904, state=WAITING, blockCnt=2, waitCnt=21]
>     Lock [object=o.a.i.i.processors.affinity.GridAffinityAssignmentCache$AffinityReadyFuture@3d6acdfd, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:834)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:994)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1303)
>         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:157)
>         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:115)
>         at o.a.i.i.processors.affinity.GridAffinityAssignmentCache.awaitTopologyVersion(GridAffinityAssignmentCache.java:463)
>         at o.a.i.i.processors.affinity.GridAffinityAssignmentCache.cachedAffinity(GridAffinityAssignmentCache.java:421)
>         at o.a.i.i.processors.affinity.GridAffinityAssignmentCache.assignments(GridAffinityAssignmentCache.java:304)
>         at o.a.i.i.processors.cache.GridCacheAffinityManager.assignments(GridCacheAffinityManager.java:159)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.affinityCache(GridAffinityProcessor.java:332)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.keysToNodes(GridAffinityProcessor.java:287)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.keysToNodes(GridAffinityProcessor.java:272)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.mapKeyToNode(GridAffinityProcessor.java:167)
>         at o.a.i.i.processors.closure.GridClosureProcessor.affinityCall(GridClosureProcessor.java:444)
>         at o.a.i.i.IgniteComputeImpl.affinityCall(IgniteComputeImpl.java:130)
>         at o.a.i.i.processors.cache.CacheAffinityCallSelfTest.testAffinityCallNoServerNode(CacheAffinityCallSelfTest.java:156)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:606)
>         at junit.framework.TestCase.runTest(TestCase.java:176)
>         at o.a.i.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1658)
>         at o.a.i.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:112)
>         at o.a.i.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1596)
> Thread [name="ignite-clock-for-tests", id=20, state=TIMED_WAITING, blockCnt=0, waitCnt=81163]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.i.util.GridTestClockTimer.run(GridTestClockTimer.java:41)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-5", id=18, state=WAITING, blockCnt=0, waitCnt=5]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-4", id=13, state=WAITING, blockCnt=0, waitCnt=4]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-3", id=12, state=WAITING, blockCnt=0, waitCnt=5]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-2", id=11, state=WAITING, blockCnt=0, waitCnt=4]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-1", id=10, state=WAITING, blockCnt=0, waitCnt=4]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="Signal Dispatcher", id=4, state=RUNNABLE, blockCnt=0, waitCnt=0]
> Thread [name="Finalizer", id=3, state=WAITING, blockCnt=1053, waitCnt=145]
>     Lock [object=java.lang.ref.ReferenceQueue$Lock@dc961c5, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
>         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
>         at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)
> Thread [name="Reference Handler", id=2, state=WAITING, blockCnt=163, waitCnt=153]
>     Lock [object=java.lang.ref.Reference$Lock@2031d395, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at java.lang.Object.wait(Object.java:503)
>         at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
> Thread [name="main", id=1, state=RUNNABLE, blockCnt=2482, waitCnt=3484]
>         at sun.management.ThreadImpl.dumpThreads0(Native Method)
>         at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:446)
>         at o.a.i.i.util.IgniteUtils.dumpThreads(IgniteUtils.java:1196)
>         at o.a.i.testframework.junits.GridAbstractTest.runTest(GridAbstractTest.java:1622)
>         at junit.framework.TestCase.runBare(TestCase.java:141)
>         at junit.framework.TestResult$1.protect(TestResult.java:122)
>         at junit.framework.TestResult.runProtected(TestResult.java:142)
>         at junit.framework.TestResult.run(TestResult.java:125)
>         at junit.framework.TestCase.run(TestCase.java:129)
>         at junit.framework.TestSuite.runTest(TestSuite.java:255)
>         at junit.framework.TestSuite.run(TestSuite.java:250)
>         at junit.framework.TestSuite.runTest(TestSuite.java:255)
>         at junit.framework.TestSuite.run(TestSuite.java:250)
>         at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:84)
>         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
>         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
>         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:606)
>         at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:208)
>         at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:156)
>         at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:82)
>         at org.apache.maven.plugin.surefire.InPluginVMSurefireStarter.runSuitesInProcess(InPluginVMSurefireStarter.java:82)
>         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:951)
>         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:831)
>         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:729)
>         at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:101)
>         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:209)
>         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:153)
>         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:145)
>         at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:84)
>         at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:59)
>         at org.apache.maven.lifecycle.internal.LifecycleStarter.singleThreadedBuild(LifecycleStarter.java:183)
>         at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:161)
>         at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:320)
>         at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:156)
>         at org.apache.maven.cli.MavenCli.execute(MavenCli.java:537)
>         at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:196)
>         at org.apache.maven.cli.MavenCli.main(MavenCli.java:141)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:606)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:290)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:230)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:409)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:352)
>     Locked synchronizers:
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@2739ebb6
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4ba20dd8
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@7c3b06b2
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@734cfd6
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1c38ee4c
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@5da4ce0d
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@6ed3e782
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1a0440a6
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@d305fa2
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@62d68b71
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@735cda68
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@55071d03
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4601db91
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@23f6307f
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@77e9f44a
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@20a563c7
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4a39ae8f
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@10f3545b
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@57272b0e
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4c7bd0d5
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@54cb772d
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@79fd56d8
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@6750bd79
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@65c787ec
> [00:49:00,849][INFO ][test-runner][root] >>> Stopping grid [name=cache.CacheAffinityCallSelfTest4, id=40389643-57fb-43e2-a3ce-b88236309004]
> [00:49:00,853][INFO ][test-runner][GridCacheProcessor] Stopped cache: myCache
> [00:49:00,853][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [00:49:00,853][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [00:49:00,853][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [00:49:00,853][INFO ][test-runner][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1445118240800, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=1be1b597051-40389643-57fb-43e2-a3ce-b88236309004, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [00:49:00,853][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [00:49:00,853][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.CacheAffinityCallSelfTest$CheckCallable
> [00:49:00,853][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [00:49:00,853][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [00:49:00,853][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.portable.CacheObjectPortableProcessorImpl$MetaDataPredicate
> [00:49:00,855][INFO ][test-runner][CacheAffinityCallSelfTest4] 
> >>> +-----------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK
> >>> +-----------------------------------------------------+
> >>> Grid name: cache.CacheAffinityCallSelfTest4
> >>> Grid uptime: 00:05:00:36
> [00:49:00,856][INFO ][main][root] >>> Stopping test: testAffinityCallNoServerNode in 300218 ms <<<
> ------- Stderr: -------
> [00:44:00,642][WARN ][grid-starter-testAffinityCallNoServerNode-1][CacheAffinityCallSelfTest0] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [00:44:00,643][WARN ][grid-starter-testAffinityCallNoServerNode-4][CacheAffinityCallSelfTest3] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [00:44:00,645][WARN ][grid-starter-testAffinityCallNoServerNode-3][CacheAffinityCallSelfTest2] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [00:44:00,645][WARN ][grid-starter-testAffinityCallNoServerNode-5][CacheAffinityCallSelfTest4] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [00:44:00,650][WARN ][grid-starter-testAffinityCallNoServerNode-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [00:44:00,651][WARN ][grid-starter-testAffinityCallNoServerNode-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [00:44:00,652][WARN ][grid-starter-testAffinityCallNoServerNode-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [00:44:00,652][WARN ][grid-starter-testAffinityCallNoServerNode-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [00:44:00,653][WARN ][grid-starter-testAffinityCallNoServerNode-4][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [00:44:00,653][WARN ][grid-starter-testAffinityCallNoServerNode-4][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [00:44:00,653][WARN ][grid-starter-testAffinityCallNoServerNode-2][CacheAffinityCallSelfTest1] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [00:44:00,654][WARN ][grid-starter-testAffinityCallNoServerNode-5][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [00:44:00,654][WARN ][grid-starter-testAffinityCallNoServerNode-5][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [00:44:00,659][WARN ][grid-starter-testAffinityCallNoServerNode-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [00:44:00,659][WARN ][grid-starter-testAffinityCallNoServerNode-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [00:44:00,892][WARN ][test-runner][root] Waiting for topology map update [grid=cache.CacheAffinityCallSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=5, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240882, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=5, nodeId8=100ab86a, msg=null, type=NODE_JOINED, tstamp=1445118240677], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=5, minorTopVer=0], nodeId=100ab86a, evt=NODE_JOINED], init=true, ready=true, replied=false, added=false, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118240687, endTime=1445118240687, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@1c1183b0, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1445118240687, oldest=006f53ca-0cd4-4e6c-95cc-87f45a777000, oldestOrder=1, evtLatch=0, remaining=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 30898011-3481-4fc9-a41d-961a9ee5d003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=5, minorTopVer=0], startTime=1445118240687, endTime=1445118240872, ignoreInterrupts=false, lsnr=null, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240882, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [00:44:02,901][WARN ][test-runner][root] Waiting for topology map update [grid=cache.CacheAffinityCallSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=5, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118242709, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=5, nodeId8=100ab86a, msg=null, type=NODE_JOINED, tstamp=1445118240677], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=5, minorTopVer=0], nodeId=100ab86a, evt=NODE_JOINED], init=true, ready=true, replied=false, added=false, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118240687, endTime=1445118240687, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@1c1183b0, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1445118240687, oldest=006f53ca-0cd4-4e6c-95cc-87f45a777000, oldestOrder=1, evtLatch=0, remaining=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 30898011-3481-4fc9-a41d-961a9ee5d003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=5, minorTopVer=0], startTime=1445118240687, endTime=1445118240872, ignoreInterrupts=false, lsnr=null, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240666, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118242709, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [00:44:03,907][WARN ][test-runner][root] Finished waiting for topology map update [grid=cache.CacheAffinityCallSelfTest1, p=0, duration=3015ms]
> [00:44:04,015][WARN ][grid-time-coordinator-#24622%cache.CacheAffinityCallSelfTest4%][TcpCommunicationSpi] Connect timed out (consider increasing 'failureDetectionTimeout' configuration property) [addr=/127.0.0.1:45404, failureDetectionTimeout=10000]
> [00:44:04,015][WARN ][grid-time-coordinator-#24622%cache.CacheAffinityCallSelfTest4%][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:45404]]
> [00:44:06,021][ERROR][async-runner-1][GridDhtPartitionsExchangeFuture] Failed to send full partition map to node (will retry after timeout) [node=40389643-57fb-43e2-a3ce-b88236309004, exchangeId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=6, minorTopVer=0], nodeId=006f53ca, evt=NODE_LEFT], timeout=1000]
> class org.apache.ignite.IgniteCheckedException: Failed to send message (node may have left the grid or TCP connection cannot be established due to firewall issues) [node=TcpDiscoveryNode [id=40389643-57fb-43e2-a3ce-b88236309004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=2, intOrder=2, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topic=TOPIC_CACHE, msg=GridDhtPartitionsFullMessage [parts={-2100569601=GridDhtPartitionFullMap [nodeId=20b06beb-2d40-4efb-8b86-432167e5f002, nodeOrder=3, updateSeq=116, size=3], 689859866=GridDhtPartitionFullMap [nodeId=20b06beb-2d40-4efb-8b86-432167e5f002, nodeOrder=3, updateSeq=275, size=3], 1482644790=GridDhtPartitionFullMap [nodeId=20b06beb-2d40-4efb-8b86-432167e5f002, nodeOrder=3, updateSeq=532, size=3], 1325947219=GridDhtPartitionFullMap [nodeId=20b06beb-2d40-4efb-8b86-432167e5f002, nodeOrder=3, updateSeq=36, size=3]}, topVer=AffinityTopologyVersion [topVer=6, minorTopVer=0], partCnt=4, super=GridDhtPartitionsAbstractMessage [exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=6, minorTopVer=0], nodeId=006f53ca, evt=NODE_LEFT], lastVer=GridCacheVersion [topVer=56598245, nodeOrderDrId=2, globalTime=1445118243920, order=1445118240658], super=GridCacheMessage [msgId=141459, depInfo=null, err=null, skipPrepare=false]]], policy=2]
>     at org.apache.ignite.internal.managers.communication.GridIoManager.send(GridIoManager.java:1071)
>     at org.apache.ignite.internal.managers.communication.GridIoManager.send(GridIoManager.java:1553)
>     at org.apache.ignite.internal.managers.communication.GridIoManager.send(GridIoManager.java:1344)
>     at org.apache.ignite.internal.processors.cache.GridCacheIoManager.safeSend(GridCacheIoManager.java:636)
>     at org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.sendAllPartitions(GridDhtPartitionsExchangeFuture.java:1003)
>     at org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.sendAllPartitions(GridDhtPartitionsExchangeFuture.java:1231)
>     at org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.onReceive(GridDhtPartitionsExchangeFuture.java:1164)
>     at org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$6.apply(GridCachePartitionExchangeManager.java:953)
>     at org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$6.apply(GridCachePartitionExchangeManager.java:950)
>     at org.apache.ignite.internal.util.future.GridFutureAdapter.notifyListener(GridFutureAdapter.java:262)
>     at org.apache.ignite.internal.util.future.GridFutureAdapter.notifyListeners(GridFutureAdapter.java:250)
>     at org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:380)
>     at org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:346)
>     at org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.onDone(GridDhtPartitionsExchangeFuture.java:1065)
>     at org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.onDone(GridDhtPartitionsExchangeFuture.java:87)
>     at org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:334)
>     at org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager.onKernalStop0(GridCachePartitionExchangeManager.java:389)
>     at org.apache.ignite.internal.processors.cache.GridCacheSharedManagerAdapter.onKernalStop(GridCacheSharedManagerAdapter.java:113)
>     at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStop(GridCacheProcessor.java:946)
>     at org.apache.ignite.internal.IgniteKernal.stop0(IgniteKernal.java:1820)
>     at org.apache.ignite.internal.IgniteKernal.stop(IgniteKernal.java:1766)
>     at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop0(IgnitionEx.java:2133)
>     at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop(IgnitionEx.java:2096)
>     at org.apache.ignite.internal.IgnitionEx.stop(IgnitionEx.java:314)
>     at org.apache.ignite.Ignition.stop(Ignition.java:223)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:1078)
>     at org.apache.ignite.internal.processors.cache.CacheAffinityCallSelfTest.access$100(CacheAffinityCallSelfTest.java:48)
>     at org.apache.ignite.internal.processors.cache.CacheAffinityCallSelfTest$2.call(CacheAffinityCallSelfTest.java:148)
>     at org.apache.ignite.testframework.GridTestUtils$5.run(GridTestUtils.java:670)
>     at org.apache.ignite.testframework.GridTestUtils$7.call(GridTestUtils.java:966)
>     at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
> Caused by: class org.apache.ignite.spi.IgniteSpiException: Node is stopping.
>     at org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi.reserveClient(TcpCommunicationSpi.java:1966)
>     at org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi.sendMessage0(TcpCommunicationSpi.java:1914)
>     at org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi.sendMessage(TcpCommunicationSpi.java:1880)
>     at org.apache.ignite.internal.managers.communication.GridIoManager.send(GridIoManager.java:1066)
>     ... 30 more
> [00:49:00,638][ERROR][main][root] Test has been timed out and will be interrupted (threads dump will be taken before interruption) [test=testAffinityCallNoServerNode, timeout=300000]
> [00:49:00,638][WARN ][main][CacheAffinityCallSelfTest4] Dumping debug info for node [id=40389643-57fb-43e2-a3ce-b88236309004, name=cache.CacheAffinityCallSelfTest4, order=2, client=true]
> [00:49:00,640][WARN ][main][GridCachePartitionExchangeManager] Ready affinity version: AffinityTopologyVersion [topVer=6, minorTopVer=0]
> [00:49:00,640][WARN ][main][GridCachePartitionExchangeManager] Last exchange future: GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=7, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118244002], rcvdIds=GridConcurrentHashSet [elements=[20b06beb-2d40-4efb-8b86-432167e5f002, 30898011-3481-4fc9-a41d-961a9ee5d003]], rmtIds=[30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=0], nodeId=100ab86a, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118244002, endTime=1445118246035, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118246023, oldest=30898011-3481-4fc9-a41d-961a9ee5d003, oldestOrder=4, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=0, res=null, startTime=1445118244002, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]]
> [00:49:00,640][WARN ][main][GridCachePartitionExchangeManager] Pending exchange futures:
> [00:49:00,642][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=8, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118246023], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=null, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=8, minorTopVer=0], nodeId=20b06beb, evt=NODE_LEFT], init=false, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246023, endTime=0, ignoreInterrupts=false, lsnr=o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture$5@58f8928b, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=0, oldest=null, oldestOrder=null, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246023, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]]
> [00:49:00,642][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=9, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118246035], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=null, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=9, minorTopVer=0], nodeId=30898011, evt=NODE_LEFT], init=false, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246035, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=0, oldest=null, oldestOrder=null, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246035, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]]
> [00:49:00,642][WARN ][main][GridCachePartitionExchangeManager] Last 10 exchange futures (total: 8):
> [00:49:00,643][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=9, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118246035], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=null, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=9, minorTopVer=0], nodeId=30898011, evt=NODE_LEFT], init=false, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246035, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=0, oldest=null, oldestOrder=null, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246035, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]]
> [00:49:00,643][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=8, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118246023], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=null, exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=8, minorTopVer=0], nodeId=20b06beb, evt=NODE_LEFT], init=false, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246023, endTime=0, ignoreInterrupts=false, lsnr=o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture$5@58f8928b, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=0, oldest=null, oldestOrder=null, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=0, res=null, startTime=1445118246023, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]]
> [00:49:00,644][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=7, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118244002], rcvdIds=GridConcurrentHashSet [elements=[20b06beb-2d40-4efb-8b86-432167e5f002, 30898011-3481-4fc9-a41d-961a9ee5d003]], rmtIds=[30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=7, minorTopVer=0], nodeId=100ab86a, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118244002, endTime=1445118246035, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118246023, oldest=30898011-3481-4fc9-a41d-961a9ee5d003, oldestOrder=4, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=0, res=null, startTime=1445118244002, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]]
> [00:49:00,644][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=6, nodeId8=40389643, msg=Node left: TcpDiscoveryNode [id=006f53ca-0cd4-4e6c-95cc-87f45a777000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_LEFT, tstamp=1445118243912], rcvdIds=GridConcurrentHashSet [elements=[20b06beb-2d40-4efb-8b86-432167e5f002, 100ab86a-4846-4cdd-bcf5-ad30e1af0001]], rmtIds=[20b06beb-2d40-4efb-8b86-432167e5f002, 100ab86a-4846-4cdd-bcf5-ad30e1af0001, 30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=6, minorTopVer=0], nodeId=006f53ca, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118243922, endTime=1445118243922, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118243922, oldest=30898011-3481-4fc9-a41d-961a9ee5d003, oldestOrder=4, evtLatch=0, remaining=[30898011-3481-4fc9-a41d-961a9ee5d003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=6, minorTopVer=0], startTime=1445118243922, endTime=1445118246023, ignoreInterrupts=false, lsnr=null, state=DONE]]
> [00:49:00,645][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=5, nodeId8=40389643, msg=Node joined: TcpDiscoveryNode [id=100ab86a-4846-4cdd-bcf5-ad30e1af0001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=5, intOrder=5, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1445118240677], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 100ab86a-4846-4cdd-bcf5-ad30e1af0001, 30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=5, minorTopVer=0], nodeId=100ab86a, evt=NODE_JOINED], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118240677, endTime=1445118240842, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118240831, oldest=006f53ca-0cd4-4e6c-95cc-87f45a777000, oldestOrder=1, evtLatch=0, remaining=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 100ab86a-4846-4cdd-bcf5-ad30e1af0001, 30898011-3481-4fc9-a41d-961a9ee5d003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=5, minorTopVer=0], startTime=1445118240677, endTime=1445118240872, ignoreInterrupts=false, lsnr=null, state=DONE]]
> [00:49:00,645][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=40389643, msg=Node joined: TcpDiscoveryNode [id=30898011-3481-4fc9-a41d-961a9ee5d003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1445118240666], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 30898011-3481-4fc9-a41d-961a9ee5d003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=30898011, evt=NODE_JOINED], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118240666, endTime=1445118240811, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118240811, oldest=006f53ca-0cd4-4e6c-95cc-87f45a777000, oldestOrder=1, evtLatch=0, remaining=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000, 30898011-3481-4fc9-a41d-961a9ee5d003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1445118240666, endTime=1445118240831, ignoreInterrupts=false, lsnr=null, state=DONE]]
> [00:49:00,646][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=3, nodeId8=40389643, msg=Node joined: TcpDiscoveryNode [id=20b06beb-2d40-4efb-8b86-432167e5f002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=3, intOrder=3, lastExchangeTime=1445118240656, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1445118240666], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=20b06beb, evt=NODE_JOINED], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118240666, endTime=1445118240800, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118240800, oldest=006f53ca-0cd4-4e6c-95cc-87f45a777000, oldestOrder=1, evtLatch=0, remaining=[20b06beb-2d40-4efb-8b86-432167e5f002, 006f53ca-0cd4-4e6c-95cc-87f45a777000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1445118240666, endTime=1445118240811, ignoreInterrupts=false, lsnr=null, state=DONE]]
> [00:49:00,646][WARN ][main][GridCachePartitionExchangeManager] >>> GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=40389643-57fb-43e2-a3ce-b88236309004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=2, intOrder=2, lastExchangeTime=1445118246035, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=2, nodeId8=40389643, msg=null, type=NODE_JOINED, tstamp=1445118240666], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[006f53ca-0cd4-4e6c-95cc-87f45a777000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], nodeId=40389643, evt=NODE_JOINED], init=true, ready=false, replied=false, added=false, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1445118240677, endTime=1445118240677, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=true, clientOnlyExchange=true, initTs=1445118240677, oldest=006f53ca-0cd4-4e6c-95cc-87f45a777000, oldestOrder=1, evtLatch=0, remaining=[006f53ca-0cd4-4e6c-95cc-87f45a777000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=2, minorTopVer=0], startTime=1445118240677, endTime=1445118240800, ignoreInterrupts=false, lsnr=null, state=DONE]]
> [00:49:00,646][WARN ][main][GridCachePartitionExchangeManager] Pending transactions:
> [00:49:00,647][WARN ][main][GridCachePartitionExchangeManager] Pending explicit locks:
> [00:49:00,647][WARN ][main][GridCachePartitionExchangeManager] Pending cache futures:
> [00:49:00,647][WARN ][main][GridCachePartitionExchangeManager] Pending atomic cache futures:
> [00:49:00,762][WARN ][main][root] No deadlocked threads detected.
> [00:49:00,849][WARN ][main][root] Thread dump at 2015/10/18 00:49:00 MSK
> Thread [name="ignite-#24632%pub-cache.CacheAffinityCallSelfTest4%", id=30298, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24631%pub-cache.CacheAffinityCallSelfTest4%", id=30297, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="tcp-disco-sock-reader-#3601%cache.CacheAffinityCallSelfTest4", id=30285, state=RUNNABLE, blockCnt=2, waitCnt=0]
>         at java.net.SocketInputStream.socketRead0(Native Method)
>         at java.net.SocketInputStream.read(SocketInputStream.java:152)
>         at java.net.SocketInputStream.read(SocketInputStream.java:122)
>         at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
>         at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
>         at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
>         - locked java.io.BufferedInputStream@6fd6426
>         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
>         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2310)
>         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2323)
>         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
>         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
>         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
>         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:39)
>         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal(JdkMarshaller.java:100)
>         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:4785)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="ignite-#24627%pub-cache.CacheAffinityCallSelfTest4%", id=30284, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24623%pub-cache.CacheAffinityCallSelfTest4%", id=30277, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="tcp-disco-sock-reader-#3600%cache.CacheAffinityCallSelfTest4", id=30276, state=RUNNABLE, blockCnt=3, waitCnt=0]
>         at java.net.SocketInputStream.socketRead0(Native Method)
>         at java.net.SocketInputStream.read(SocketInputStream.java:152)
>         at java.net.SocketInputStream.read(SocketInputStream.java:122)
>         at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
>         at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
>         at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
>         - locked java.io.BufferedInputStream@15eb4326
>         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
>         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2310)
>         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2323)
>         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
>         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
>         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
>         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:39)
>         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal(JdkMarshaller.java:100)
>         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:4785)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="grid-time-coordinator-#24622%cache.CacheAffinityCallSelfTest4%", id=30275, state=TIMED_WAITING, blockCnt=5, waitCnt=10]
>     Lock [object=o.a.i.i.processors.clock.GridClockSyncProcessor$TimeCoordinator@8857f08, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at o.a.i.i.processors.clock.GridClockSyncProcessor$TimeCoordinator.body(GridClockSyncProcessor.java:386)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="srvc-deploy-#24559%cache.CacheAffinityCallSelfTest4%", id=30205, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7456ea96, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24553%utility-cache.CacheAffinityCallSelfTest4%", id=30199, state=WAITING, blockCnt=0, waitCnt=1]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@76435985, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ttl-cleanup-worker-myCache-#24550%cache.CacheAffinityCallSelfTest4%", id=30196, state=TIMED_WAITING, blockCnt=0, waitCnt=600]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.i.util.IgniteUtils.sleep(IgniteUtils.java:7251)
>         at o.a.i.i.processors.cache.GridCacheTtlManager$CleanupWorker.body(GridCacheTtlManager.java:147)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="exchange-worker-#24522%cache.CacheAffinityCallSelfTest4%", id=30161, state=WAITING, blockCnt=0, waitCnt=9]
>     Lock [object=o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture@3faa676b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:834)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:994)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1303)
>         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:157)
>         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:115)
>         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body(GridCachePartitionExchangeManager.java:1154)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-time-server-reader-#24514%cache.CacheAffinityCallSelfTest4%", id=30146, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at java.net.PlainDatagramSocketImpl.receive0(Native Method)
>         - locked java.net.PlainDatagramSocketImpl@4c6bc274
>         at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:146)
>         - locked java.net.PlainDatagramSocketImpl@4c6bc274
>         at java.net.DatagramSocket.receive(DatagramSocket.java:817)
>         - locked java.net.DatagramPacket@3285b6dc
>         - locked java.net.DatagramSocket@68a83f6d
>         at o.a.i.i.processors.clock.GridClockServer$ReadWorker.body(GridClockServer.java:199)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="disco-event-worker-#24513%cache.CacheAffinityCallSelfTest4%", id=30145, state=WAITING, blockCnt=3, waitCnt=238]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7ddc42c8, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body0(GridDiscoveryManager.java:2007)
>         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body(GridDiscoveryManager.java:1989)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="tcp-disco-ip-finder-cleaner-#3592%cache.CacheAffinityCallSelfTest4", id=30144, state=TIMED_WAITING, blockCnt=0, waitCnt=6]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.spi.discovery.tcp.ServerImpl$IpFinderCleaner.body(ServerImpl.java:1634)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-disco-sock-reader-#3585%cache.CacheAffinityCallSelfTest4", id=30135, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at java.net.SocketInputStream.socketRead0(Native Method)
>         at java.net.SocketInputStream.read(SocketInputStream.java:152)
>         at java.net.SocketInputStream.read(SocketInputStream.java:122)
>         at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
>         at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
>         at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
>         - locked java.io.BufferedInputStream@473a5cd5
>         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
>         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2310)
>         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2323)
>         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
>         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
>         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
>         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:39)
>         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal(JdkMarshaller.java:100)
>         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:4785)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-disco-srvr-#3576%cache.CacheAffinityCallSelfTest4", id=30119, state=RUNNABLE, blockCnt=1, waitCnt=0]
>         at java.net.PlainSocketImpl.socketAccept(Native Method)
>         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
>         at java.net.ServerSocket.implAccept(ServerSocket.java:530)
>         at java.net.ServerSocket.accept(ServerSocket.java:498)
>         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServer.body(ServerImpl.java:4492)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-disco-msg-worker-#3574%cache.CacheAffinityCallSelfTest4", id=30116, state=TIMED_WAITING, blockCnt=10, waitCnt=29823]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@48a4c87b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
>         at java.util.concurrent.LinkedBlockingDeque.pollFirst(LinkedBlockingDeque.java:519)
>         at java.util.concurrent.LinkedBlockingDeque.poll(LinkedBlockingDeque.java:682)
>         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorkerAdapter.body(ServerImpl.java:5377)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="grid-data-loader-flusher-#24502%cache.CacheAffinityCallSelfTest4%", id=30113, state=WAITING, blockCnt=0, waitCnt=1]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@3a66287f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.DelayQueue.take(DelayQueue.java:209)
>         at o.a.i.i.processors.datastreamer.DataStreamProcessor$2.body(DataStreamProcessor.java:92)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-sharedfs-timeout-worker-#3568%cache.CacheAffinityCallSelfTest4", id=30096, state=TIMED_WAITING, blockCnt=0, waitCnt=61]
>     Lock [object=java.lang.Object@8ecc7cd, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at o.a.i.spi.checkpoint.sharedfs.SharedFsTimeoutTask.body(SharedFsTimeoutTask.java:99)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="tcp-comm-worker-#3567%cache.CacheAffinityCallSelfTest4", id=30095, state=TIMED_WAITING, blockCnt=0, waitCnt=11]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7131684e, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
>         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
>         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$CommunicationWorker.body(TcpCommunicationSpi.java:3004)
>         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> Thread [name="grid-nio-worker-3-#24488%cache.CacheAffinityCallSelfTest4%", id=30093, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@62b712f0
>         - locked java.util.Collections$UnmodifiableSet@613c7d31
>         - locked sun.nio.ch.EPollSelectorImpl@4a3f4dd6
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-nio-worker-2-#24487%cache.CacheAffinityCallSelfTest4%", id=30092, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@20f42af
>         - locked java.util.Collections$UnmodifiableSet@37e2f037
>         - locked sun.nio.ch.EPollSelectorImpl@1408c78b
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-nio-worker-1-#24486%cache.CacheAffinityCallSelfTest4%", id=30091, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@4c6480f3
>         - locked java.util.Collections$UnmodifiableSet@5a4df8b3
>         - locked sun.nio.ch.EPollSelectorImpl@3502de16
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-nio-worker-0-#24485%cache.CacheAffinityCallSelfTest4%", id=30090, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@4f42918a
>         - locked java.util.Collections$UnmodifiableSet@1b6123ad
>         - locked sun.nio.ch.EPollSelectorImpl@66d40e6
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1377)
>         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1263)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="nio-acceptor-#24484%cache.CacheAffinityCallSelfTest4%", id=30089, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
>         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
>         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
>         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
>         - locked sun.nio.ch.Util$2@6ebfbf55
>         - locked java.util.Collections$UnmodifiableSet@6eaa9e40
>         - locked sun.nio.ch.EPollSelectorImpl@37bb483
>         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
>         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:1761)
>         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:1733)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="shmem-communication-acceptor-#24489%cache.CacheAffinityCallSelfTest4%", id=30094, state=RUNNABLE, blockCnt=0, waitCnt=0]
>         at java.net.PlainSocketImpl.socketAccept(Native Method)
>         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
>         at java.net.ServerSocket.implAccept(ServerSocket.java:530)
>         at java.net.ServerSocket.accept(ServerSocket.java:498)
>         at o.a.i.i.util.ipc.shmem.IpcSharedMemoryServerEndpoint.accept(IpcSharedMemoryServerEndpoint.java:221)
>         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$ShmemAcceptWorker.body(TcpCommunicationSpi.java:2867)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ipc-shmem-gc-#24483%cache.CacheAffinityCallSelfTest4%", id=30088, state=RUNNABLE, blockCnt=0, waitCnt=30]
>         at java.io.UnixFileSystem.list(Native Method)
>         at java.io.File.list(File.java:1116)
>         at java.io.File.listFiles(File.java:1201)
>         at o.a.i.i.util.ipc.shmem.IpcSharedMemoryServerEndpoint$GcWorker.processTokenDirectory(IpcSharedMemoryServerEndpoint.java:635)
>         at o.a.i.i.util.ipc.shmem.IpcSharedMemoryServerEndpoint$GcWorker.cleanupResources(IpcSharedMemoryServerEndpoint.java:611)
>         at o.a.i.i.util.ipc.shmem.IpcSharedMemoryServerEndpoint$GcWorker.body(IpcSharedMemoryServerEndpoint.java:563)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="grid-timeout-worker-#24480%cache.CacheAffinityCallSelfTest4%", id=30084, state=TIMED_WAITING, blockCnt=3, waitCnt=161]
>     Lock [object=java.lang.Object@2e88063e, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at o.a.i.i.processors.timeout.GridTimeoutProcessor$TimeoutWorker.body(GridTimeoutProcessor.java:184)
>         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24450%pub-cache.CacheAffinityCallSelfTest4%", id=30049, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24449%pub-cache.CacheAffinityCallSelfTest4%", id=30048, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24446%pub-cache.CacheAffinityCallSelfTest4%", id=30046, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24445%pub-cache.CacheAffinityCallSelfTest4%", id=30043, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24435%pub-cache.CacheAffinityCallSelfTest4%", id=30039, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24430%pub-cache.CacheAffinityCallSelfTest4%", id=30031, state=WAITING, blockCnt=1, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@11c0b542, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24422%sys-cache.CacheAffinityCallSelfTest4%", id=30021, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24419%sys-cache.CacheAffinityCallSelfTest4%", id=30019, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24417%sys-cache.CacheAffinityCallSelfTest4%", id=30017, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24415%sys-cache.CacheAffinityCallSelfTest4%", id=30015, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24413%sys-cache.CacheAffinityCallSelfTest4%", id=30013, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24411%sys-cache.CacheAffinityCallSelfTest4%", id=30011, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24409%sys-cache.CacheAffinityCallSelfTest4%", id=30009, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24407%sys-cache.CacheAffinityCallSelfTest4%", id=30007, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24405%sys-cache.CacheAffinityCallSelfTest4%", id=30005, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24403%sys-cache.CacheAffinityCallSelfTest4%", id=30003, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24401%sys-cache.CacheAffinityCallSelfTest4%", id=30001, state=WAITING, blockCnt=0, waitCnt=2]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24400%sys-cache.CacheAffinityCallSelfTest4%", id=29999, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24399%sys-cache.CacheAffinityCallSelfTest4%", id=29998, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24398%sys-cache.CacheAffinityCallSelfTest4%", id=29997, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24396%sys-cache.CacheAffinityCallSelfTest4%", id=29996, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="ignite-#24394%sys-cache.CacheAffinityCallSelfTest4%", id=29994, state=WAITING, blockCnt=0, waitCnt=3]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6451e84f, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="test-runner", id=29904, state=WAITING, blockCnt=2, waitCnt=21]
>     Lock [object=o.a.i.i.processors.affinity.GridAffinityAssignmentCache$AffinityReadyFuture@3d6acdfd, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:834)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:994)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1303)
>         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:157)
>         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:115)
>         at o.a.i.i.processors.affinity.GridAffinityAssignmentCache.awaitTopologyVersion(GridAffinityAssignmentCache.java:463)
>         at o.a.i.i.processors.affinity.GridAffinityAssignmentCache.cachedAffinity(GridAffinityAssignmentCache.java:421)
>         at o.a.i.i.processors.affinity.GridAffinityAssignmentCache.assignments(GridAffinityAssignmentCache.java:304)
>         at o.a.i.i.processors.cache.GridCacheAffinityManager.assignments(GridCacheAffinityManager.java:159)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.affinityCache(GridAffinityProcessor.java:332)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.keysToNodes(GridAffinityProcessor.java:287)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.keysToNodes(GridAffinityProcessor.java:272)
>         at o.a.i.i.processors.affinity.GridAffinityProcessor.mapKeyToNode(GridAffinityProcessor.java:167)
>         at o.a.i.i.processors.closure.GridClosureProcessor.affinityCall(GridClosureProcessor.java:444)
>         at o.a.i.i.IgniteComputeImpl.affinityCall(IgniteComputeImpl.java:130)
>         at o.a.i.i.processors.cache.CacheAffinityCallSelfTest.testAffinityCallNoServerNode(CacheAffinityCallSelfTest.java:156)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:606)
>         at junit.framework.TestCase.runTest(TestCase.java:176)
>         at o.a.i.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1658)
>         at o.a.i.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:112)
>         at o.a.i.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1596)
> Thread [name="ignite-clock-for-tests", id=20, state=TIMED_WAITING, blockCnt=0, waitCnt=81164]
>         at java.lang.Thread.sleep(Native Method)
>         at o.a.i.i.util.GridTestClockTimer.run(GridTestClockTimer.java:41)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-5", id=18, state=WAITING, blockCnt=0, waitCnt=5]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-4", id=13, state=WAITING, blockCnt=0, waitCnt=4]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-3", id=12, state=WAITING, blockCnt=0, waitCnt=5]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-2", id=11, state=WAITING, blockCnt=0, waitCnt=4]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="resolver-1", id=10, state=WAITING, blockCnt=0, waitCnt=4]
>     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2ef9bc8b, ownerName=null, ownerId=-1]
>         at sun.misc.Unsafe.park(Native Method)
>         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
>         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
>         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
>         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>         at java.lang.Thread.run(Thread.java:745)
> Thread [name="Signal Dispatcher", id=4, state=RUNNABLE, blockCnt=0, waitCnt=0]
> Thread [name="Finalizer", id=3, state=WAITING, blockCnt=1053, waitCnt=145]
>     Lock [object=java.lang.ref.ReferenceQueue$Lock@dc961c5, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
>         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
>         at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)
> Thread [name="Reference Handler", id=2, state=WAITING, blockCnt=163, waitCnt=153]
>     Lock [object=java.lang.ref.Reference$Lock@2031d395, ownerName=null, ownerId=-1]
>         at java.lang.Object.wait(Native Method)
>         at java.lang.Object.wait(Object.java:503)
>         at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
> Thread [name="main", id=1, state=RUNNABLE, blockCnt=2482, waitCnt=3484]
>         at sun.management.ThreadImpl.dumpThreads0(Native Method)
>         at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:446)
>         at o.a.i.i.util.IgniteUtils.dumpThreads(IgniteUtils.java:1196)
>         at o.a.i.testframework.junits.GridAbstractTest.runTest(GridAbstractTest.java:1624)
>         at junit.framework.TestCase.runBare(TestCase.java:141)
>         at junit.framework.TestResult$1.protect(TestResult.java:122)
>         at junit.framework.TestResult.runProtected(TestResult.java:142)
>         at junit.framework.TestResult.run(TestResult.java:125)
>         at junit.framework.TestCase.run(TestCase.java:129)
>         at junit.framework.TestSuite.runTest(TestSuite.java:255)
>         at junit.framework.TestSuite.run(TestSuite.java:250)
>         at junit.framework.TestSuite.runTest(TestSuite.java:255)
>         at junit.framework.TestSuite.run(TestSuite.java:250)
>         at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:84)
>         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
>         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
>         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:606)
>         at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:208)
>         at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:156)
>         at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:82)
>         at org.apache.maven.plugin.surefire.InPluginVMSurefireStarter.runSuitesInProcess(InPluginVMSurefireStarter.java:82)
>         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:951)
>         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:831)
>         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:729)
>         at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:101)
>         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:209)
>         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:153)
>         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:145)
>         at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:84)
>         at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:59)
>         at org.apache.maven.lifecycle.internal.LifecycleStarter.singleThreadedBuild(LifecycleStarter.java:183)
>         at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:161)
>         at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:320)
>         at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:156)
>         at org.apache.maven.cli.MavenCli.execute(MavenCli.java:537)
>         at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:196)
>         at org.apache.maven.cli.MavenCli.main(MavenCli.java:141)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:606)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:290)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:230)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:409)
>         at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:352)
>     Locked synchronizers:
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@2739ebb6
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4ba20dd8
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@7c3b06b2
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@734cfd6
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1c38ee4c
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@5da4ce0d
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@6ed3e782
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1a0440a6
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@d305fa2
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@62d68b71
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@735cda68
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@55071d03
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4601db91
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@23f6307f
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@77e9f44a
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@20a563c7
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4a39ae8f
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@10f3545b
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@57272b0e
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@4c7bd0d5
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@54cb772d
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@79fd56d8
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@6750bd79
>         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@65c787ec
> {noformat}



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)


Mime
View raw message