You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Pavel Tupitsyn (JIRA)" <ji...@apache.org> on 2016/08/09 12:33:31 UTC

[jira] [Updated] (IGNITE-1738) [Test Failed] IgniteClientReconnectCacheTest.testReconnect

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

Pavel Tupitsyn updated IGNITE-1738:
-----------------------------------
    Fix Version/s:     (was: 1.7)
                   1.8

> [Test Failed] IgniteClientReconnectCacheTest.testReconnect
> ----------------------------------------------------------
>
>                 Key: IGNITE-1738
>                 URL: https://issues.apache.org/jira/browse/IGNITE-1738
>             Project: Ignite
>          Issue Type: Test
>            Reporter: Artem Shutak
>            Priority: Blocker
>              Labels: Muted_test
>             Fix For: 1.8
>
>
> Test fails on TC sometime. Could not reproduce locally.
> Test log
> {noformat}
> junit.framework.AssertionFailedError: expected:<21> but was:<20>
>     at junit.framework.Assert.fail(Assert.java:57)
>     at junit.framework.Assert.failNotEquals(Assert.java:329)
>     at junit.framework.Assert.assertEquals(Assert.java:78)
>     at junit.framework.Assert.assertEquals(Assert.java:86)
>     at junit.framework.TestCase.assertEquals(TestCase.java:253)
>     at org.apache.ignite.internal.IgniteClientReconnectCacheTest.testReconnect(IgniteClientReconnectCacheTest.java:280)
> ------- Stdout: -------
> [20:07:22,890][INFO ][main][root] >>> Starting test: testReconnect <<<
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a1
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] Config URL: n/a
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] Daemon mode: off
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] OS: Linux 2.6.32-74-generic amd64
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] OS user: teamcity
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] Language runtime: Java Platform API Specification ver. 1.7
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] VM information: Java(TM) SE Runtime Environment 1.7.0_21-b11 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 23.21-b01
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] VM total memory: 2.7GB
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [20:07:22,896][INFO ][main][IgniteClientReconnectCacheTest0] IGNITE_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2
> [20:07:22,897][INFO ][main][IgniteClientReconnectCacheTest0] VM arguments: [-DJAVA_HOME=/home/teamcity/jdk1.7.0_21, -DMCAST_GRP=229.15.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-5, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=560, -Dbuild.vcs.number=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.1=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=665, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=554010, -Dteamcity.auth.password=NZJLF36rtgBzyZan9Rlqx1nibYhaTdzk, -Dteamcity.auth.userId=TeamCityBuildId=554010, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles2364753307910338165.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.build.id=554010, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build7009344645725909149.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.buildConfName=Ignite Client Nodes, -Dteamcity.buildType.id=Ignite_IgniteClients, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8423242305191431229.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2097693476768347057.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6738565233989795504.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [20:07:22,897][INFO ][main][IgniteClientReconnectCacheTest0] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'static-cache']
> [20:07:22,897][INFO ][main][IgniteClientReconnectCacheTest0] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/f87ae3766fbb88e2/libs/licenses
> [20:07:22,899][INFO ][main][IgniteClientReconnectCacheTest0] Non-loopback local IPs: 192.168.2.15, fe80:0:0:0:ca60:ff:fe68:582f%2
> [20:07:22,899][INFO ][main][IgniteClientReconnectCacheTest0] Enabled local MACs: C8600068582F
> [20:07:22,899][INFO ][main][IgnitePluginProcessor] Configured plugins:
> [20:07:22,899][INFO ][main][IgnitePluginProcessor]   ^-- None
> [20:07:22,899][INFO ][main][IgnitePluginProcessor] 
> [20:07:22,900][INFO ][main][IgniteClientReconnectCacheTest$TestCommunicationSpi] Successfully bound to TCP port [port=47100, locHost=/127.0.0.1]
> [20:07:22,903][INFO ][main][IgniteClientReconnectCacheTest0] Security status [authentication=off, tls/ssl=off]
> [20:07:22,906][INFO ][main][IgniteClientReconnectAbstractTest$TestTcpDiscoverySpi] Successfully bound to TCP port [port=47500, localHost=/127.0.0.1]
> [20:07:24,916][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [20:07:24,919][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [20:07:24,922][INFO ][main][GridCacheProcessor] Started cache [name=static-cache, mode=PARTITIONED]
> [20:07:24,924][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [20:07:24,931][INFO ][main][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [20:07:24,931][INFO ][main][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [20:07:24,931][INFO ][main][GridDhtPreloader] <static-cache> Starting rebalancing in ASYNC mode: static-cache
> [20:07:24,931][INFO ][main][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [20:07:24,932][INFO ][preloader-demand-worker-#4824%internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [20:07:24,934][INFO ][preloader-demand-worker-#4828%internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
> [20:07:24,935][INFO ][preloader-demand-worker-#4833%internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <static-cache> Completed rebalancing in ASYNC mode [cache=static-cache, time=0 ms]
> [20:07:24,935][INFO ][preloader-demand-worker-#4837%internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0] Performance suggestions for grid 'internal.IgniteClientReconnectCacheTest0' (fix if possible)
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0] 
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [20:07:24,941][INFO ][main][IgniteClientReconnectCacheTest0] 
> [20:07:24,942][INFO ][main][IgniteClientReconnectCacheTest0] 
> >>> +-------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08
> >>> +-------------------------------------------------------------------------------+
> >>> OS name: Linux 2.6.32-74-generic amd64
> >>> CPU(s): 4
> >>> Heap: 2.7GB
> >>> VM name: 20794@gg-teamcity-5
> >>> Grid name: internal.IgniteClientReconnectCacheTest0
> >>> Local node [ID=009C5A78-BAED-4ACA-B211-B2A634C93000, order=1, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47100 TCP:47500 
> [20:07:24,943][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=1, servers=1, clients=0, CPUs=4, heap=2.7GB]
> [20:07:24,951][INFO ][main][IgniteClientReconnectCacheTest1] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a1
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [20:07:24,951][INFO ][main][IgniteClientReconnectCacheTest1] Config URL: n/a
> [20:07:24,951][INFO ][main][IgniteClientReconnectCacheTest1] Daemon mode: off
> [20:07:24,951][INFO ][main][IgniteClientReconnectCacheTest1] OS: Linux 2.6.32-74-generic amd64
> [20:07:24,951][INFO ][main][IgniteClientReconnectCacheTest1] OS user: teamcity
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] Language runtime: Java Platform API Specification ver. 1.7
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] VM information: Java(TM) SE Runtime Environment 1.7.0_21-b11 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 23.21-b01
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] VM total memory: 2.7GB
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] IGNITE_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] VM arguments: [-DJAVA_HOME=/home/teamcity/jdk1.7.0_21, -DMCAST_GRP=229.15.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-5, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=560, -Dbuild.vcs.number=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.1=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=665, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=554010, -Dteamcity.auth.password=NZJLF36rtgBzyZan9Rlqx1nibYhaTdzk, -Dteamcity.auth.userId=TeamCityBuildId=554010, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles2364753307910338165.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.build.id=554010, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build7009344645725909149.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.buildConfName=Ignite Client Nodes, -Dteamcity.buildType.id=Ignite_IgniteClients, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8423242305191431229.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2097693476768347057.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6738565233989795504.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'static-cache']
> [20:07:24,952][INFO ][main][IgniteClientReconnectCacheTest1] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/f87ae3766fbb88e2/libs/licenses
> [20:07:24,955][INFO ][main][IgniteClientReconnectCacheTest1] Non-loopback local IPs: 192.168.2.15, fe80:0:0:0:ca60:ff:fe68:582f%2
> [20:07:24,956][INFO ][main][IgniteClientReconnectCacheTest1] Enabled local MACs: C8600068582F
> [20:07:24,956][INFO ][main][IgnitePluginProcessor] Configured plugins:
> [20:07:24,956][INFO ][main][IgnitePluginProcessor]   ^-- None
> [20:07:24,956][INFO ][main][IgnitePluginProcessor] 
> [20:07:24,958][INFO ][main][IgniteClientReconnectCacheTest$TestCommunicationSpi] Successfully bound to TCP port [port=47101, locHost=/127.0.0.1]
> [20:07:24,964][INFO ][main][IgniteClientReconnectCacheTest1] Security status [authentication=off, tls/ssl=off]
> [20:07:24,970][INFO ][main][IgniteClientReconnectAbstractTest$TestTcpDiscoverySpi] Successfully bound to TCP port [port=47501, localHost=/127.0.0.1]
> [20:07:24,983][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=1014f5ac-19c1-482c-be6c-337755519001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1445274444969, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false]
> [20:07:24,984][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Topology snapshot [ver=2, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [20:07:24,986][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [20:07:24,988][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [20:07:24,992][INFO ][main][GridCacheProcessor] Started cache [name=static-cache, mode=PARTITIONED]
> [20:07:24,994][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [20:07:25,011][INFO ][main][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [20:07:25,011][INFO ][main][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [20:07:25,011][INFO ][main][GridDhtPreloader] <static-cache> Starting rebalancing in ASYNC mode: static-cache
> [20:07:25,011][INFO ][main][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [20:07:25,016][INFO ][preloader-demand-worker-#4885%internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [20:07:25,018][INFO ][preloader-demand-worker-#4901%internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [20:07:25,018][INFO ][preloader-demand-worker-#4890%internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
> [20:07:25,019][INFO ][preloader-demand-worker-#4896%internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <static-cache> Completed rebalancing in ASYNC mode [cache=static-cache, time=0 ms]
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1] Performance suggestions for grid 'internal.IgniteClientReconnectCacheTest1' (fix if possible)
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1] 
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [20:07:25,027][INFO ][main][IgniteClientReconnectCacheTest1] 
> [20:07:25,028][INFO ][main][IgniteClientReconnectCacheTest1] 
> >>> +-------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08
> >>> +-------------------------------------------------------------------------------+
> >>> OS name: Linux 2.6.32-74-generic amd64
> >>> CPU(s): 4
> >>> Heap: 2.7GB
> >>> VM name: 20794@gg-teamcity-5
> >>> Grid name: internal.IgniteClientReconnectCacheTest1
> >>> Local node [ID=1014F5AC-19C1-482C-BE6C-337755519001, order=2, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47101 TCP:47501 
> [20:07:25,028][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=2, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,036][INFO ][main][IgniteClientReconnectCacheTest2] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a1
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [20:07:25,036][INFO ][main][IgniteClientReconnectCacheTest2] Config URL: n/a
> [20:07:25,036][INFO ][main][IgniteClientReconnectCacheTest2] Daemon mode: off
> [20:07:25,036][INFO ][main][IgniteClientReconnectCacheTest2] OS: Linux 2.6.32-74-generic amd64
> [20:07:25,036][INFO ][main][IgniteClientReconnectCacheTest2] OS user: teamcity
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] Language runtime: Java Platform API Specification ver. 1.7
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] VM information: Java(TM) SE Runtime Environment 1.7.0_21-b11 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 23.21-b01
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] VM total memory: 2.7GB
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] IGNITE_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] VM arguments: [-DJAVA_HOME=/home/teamcity/jdk1.7.0_21, -DMCAST_GRP=229.15.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-5, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=560, -Dbuild.vcs.number=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.1=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=665, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=554010, -Dteamcity.auth.password=NZJLF36rtgBzyZan9Rlqx1nibYhaTdzk, -Dteamcity.auth.userId=TeamCityBuildId=554010, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles2364753307910338165.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.build.id=554010, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build7009344645725909149.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.buildConfName=Ignite Client Nodes, -Dteamcity.buildType.id=Ignite_IgniteClients, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8423242305191431229.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2097693476768347057.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6738565233989795504.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'static-cache']
> [20:07:25,037][INFO ][main][IgniteClientReconnectCacheTest2] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/f87ae3766fbb88e2/libs/licenses
> [20:07:25,040][INFO ][main][IgniteClientReconnectCacheTest2] Non-loopback local IPs: 192.168.2.15, fe80:0:0:0:ca60:ff:fe68:582f%2
> [20:07:25,040][INFO ][main][IgniteClientReconnectCacheTest2] Enabled local MACs: C8600068582F
> [20:07:25,041][INFO ][main][IgnitePluginProcessor] Configured plugins:
> [20:07:25,041][INFO ][main][IgnitePluginProcessor]   ^-- None
> [20:07:25,041][INFO ][main][IgnitePluginProcessor] 
> [20:07:25,043][INFO ][main][IgniteClientReconnectCacheTest$TestCommunicationSpi] Successfully bound to TCP port [port=47102, locHost=/127.0.0.1]
> [20:07:25,047][INFO ][main][IgniteClientReconnectCacheTest2] Security status [authentication=off, tls/ssl=off]
> [20:07:25,051][INFO ][main][IgniteClientReconnectAbstractTest$TestTcpDiscoverySpi] Successfully bound to TCP port [port=47502, localHost=/127.0.0.1]
> [20:07:25,062][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=20397987-c902-4000-9a54-fd39da1ac002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1445274445050, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false]
> [20:07:25,063][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=20397987-c902-4000-9a54-fd39da1ac002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1445274445050, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false]
> [20:07:25,063][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,063][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,066][INFO ][main][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [20:07:25,068][INFO ][main][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [20:07:25,070][INFO ][main][GridCacheProcessor] Started cache [name=static-cache, mode=PARTITIONED]
> [20:07:25,072][INFO ][main][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [20:07:25,084][INFO ][main][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [20:07:25,354][INFO ][main][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [20:07:25,354][INFO ][main][GridDhtPreloader] <static-cache> Starting rebalancing in ASYNC mode: static-cache
> [20:07:25,354][INFO ][main][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [20:07:25,357][INFO ][preloader-demand-worker-#4951%internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=273 ms]
> [20:07:25,358][INFO ][preloader-demand-worker-#4960%internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <static-cache> Completed rebalancing in ASYNC mode [cache=static-cache, time=0 ms]
> [20:07:25,359][INFO ][preloader-demand-worker-#4955%internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=273 ms]
> [20:07:25,359][INFO ][preloader-demand-worker-#4965%internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2] Performance suggestions for grid 'internal.IgniteClientReconnectCacheTest2' (fix if possible)
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2] 
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2] 
> [20:07:25,368][INFO ][main][IgniteClientReconnectCacheTest2] 
> >>> +-------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08
> >>> +-------------------------------------------------------------------------------+
> >>> OS name: Linux 2.6.32-74-generic amd64
> >>> CPU(s): 4
> >>> Heap: 2.7GB
> >>> VM name: 20794@gg-teamcity-5
> >>> Grid name: internal.IgniteClientReconnectCacheTest2
> >>> Local node [ID=20397987-C902-4000-9A54-FD39DA1AC002, order=3, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47102 TCP:47502 
> [20:07:25,368][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,374][INFO ][test-runner][IgniteClientReconnectCacheTest3] 
> >>>    __________  ________________  
> >>>   /  _/ ___/ |/ /  _/_  __/ __/  
> >>>  _/ // (7 7    // /  / / / _/    
> >>> /___/\___/_/|_/___/ /_/ /___/   
> >>> 
> >>> ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a1
> >>> 2015 Copyright(C) Apache Software Foundation
> >>> 
> >>> Ignite documentation: http://ignite.apache.org
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] Config URL: n/a
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] Daemon mode: off
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] OS: Linux 2.6.32-74-generic amd64
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] OS user: teamcity
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] Language runtime: Java Platform API Specification ver. 1.7
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] VM information: Java(TM) SE Runtime Environment 1.7.0_21-b11 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 23.21-b01
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] VM total memory: 2.7GB
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] IGNITE_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] VM arguments: [-DJAVA_HOME=/home/teamcity/jdk1.7.0_21, -DMCAST_GRP=229.15.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-5, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=560, -Dbuild.vcs.number=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.1=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=10ef06a14245c73cbfe66b744406a0391c4a9c08, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=665, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=554010, -Dteamcity.auth.password=NZJLF36rtgBzyZan9Rlqx1nibYhaTdzk, -Dteamcity.auth.userId=TeamCityBuildId=554010, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles2364753307910338165.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.build.id=554010, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build7009344645725909149.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -Dteamcity.buildConfName=Ignite Client Nodes, -Dteamcity.buildType.id=Ignite_IgniteClients, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8423242305191431229.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2097693476768347057.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6738565233989795504.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/f87ae3766fbb88e2, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [20:07:25,375][INFO ][test-runner][IgniteClientReconnectCacheTest3] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'static-cache']
> [20:07:25,377][INFO ][test-runner][IgniteClientReconnectCacheTest3] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/f87ae3766fbb88e2/libs/licenses
> [20:07:25,380][INFO ][test-runner][IgniteClientReconnectCacheTest3] Non-loopback local IPs: 192.168.2.15, fe80:0:0:0:ca60:ff:fe68:582f%2
> [20:07:25,380][INFO ][test-runner][IgniteClientReconnectCacheTest3] Enabled local MACs: C8600068582F
> [20:07:25,381][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
> [20:07:25,381][INFO ][test-runner][IgnitePluginProcessor]   ^-- None
> [20:07:25,381][INFO ][test-runner][IgnitePluginProcessor] 
> [20:07:25,383][INFO ][test-runner][IgniteClientReconnectCacheTest$TestCommunicationSpi] Successfully bound to TCP port [port=47103, locHost=/127.0.0.1]
> [20:07:25,389][INFO ][test-runner][IgniteClientReconnectCacheTest3] Security status [authentication=off, tls/ssl=off]
> [20:07:25,408][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, lastExchangeTime=1445274445393, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:25,409][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:25,410][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, lastExchangeTime=1445274445393, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:25,410][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:25,412][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, lastExchangeTime=1445274445404, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:25,412][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:25,416][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [20:07:25,417][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [20:07:25,419][INFO ][test-runner][GridCacheProcessor] Started cache [name=static-cache, mode=PARTITIONED]
> [20:07:25,419][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3] Performance suggestions for grid 'internal.IgniteClientReconnectCacheTest3' (fix if possible)
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3]   ^-- Disable checkpoints (remove 'checkpointSpi' from configuration)
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3]   ^-- Disable grid events (remove 'includeEventTypes' from configuration)
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3] 
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [20:07:25,454][INFO ][test-runner][IgniteClientReconnectCacheTest3] 
> [20:07:25,455][INFO ][test-runner][IgniteClientReconnectCacheTest3] 
> >>> +-------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08
> >>> +-------------------------------------------------------------------------------+
> >>> OS name: Linux 2.6.32-74-generic amd64
> >>> CPU(s): 4
> >>> Heap: 2.7GB
> >>> VM name: 20794@gg-teamcity-5
> >>> Grid name: internal.IgniteClientReconnectCacheTest3
> >>> Local node [ID=30FF918B-A495-42A3-A953-C71291E41003, order=4, clientMode=true]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47103 
> [20:07:25,455][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=4, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:25,462][INFO ][exchange-worker-#4821%internal.IgniteClientReconnectCacheTest0%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [20:07:25,462][INFO ][exchange-worker-#4882%internal.IgniteClientReconnectCacheTest1%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [20:07:25,463][INFO ][exchange-worker-#4947%internal.IgniteClientReconnectCacheTest2%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [20:07:25,464][INFO ][exchange-worker-#5002%internal.IgniteClientReconnectCacheTest3%][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [20:07:25,481][INFO ][ignite-#4804%sys-internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [20:07:25,482][INFO ][ignite-#4929%sys-internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [20:07:25,483][INFO ][ignite-#4866%sys-internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [20:07:25,485][INFO ][preloader-demand-worker-#5013%internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=10 ms]
> [20:07:25,486][INFO ][preloader-demand-worker-#5017%internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=10 ms]
> [20:07:25,486][INFO ][preloader-demand-worker-#5019%internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=10 ms]
> [20:07:25,492][INFO ][exchange-worker-#4882%internal.IgniteClientReconnectCacheTest1%][GridCacheProcessor] Started cache [name=nearCache, mode=PARTITIONED]
> [20:07:25,492][INFO ][exchange-worker-#4947%internal.IgniteClientReconnectCacheTest2%][GridCacheProcessor] Started cache [name=nearCache, mode=PARTITIONED]
> [20:07:25,493][INFO ][exchange-worker-#4821%internal.IgniteClientReconnectCacheTest0%][GridCacheProcessor] Started cache [name=nearCache, mode=PARTITIONED]
> [20:07:25,494][INFO ][exchange-worker-#5002%internal.IgniteClientReconnectCacheTest3%][GridCacheProcessor] Started cache [name=nearCache, mode=PARTITIONED]
> [20:07:25,515][INFO ][ignite-#4794%sys-internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <nearCache> Starting rebalancing in ASYNC mode: nearCache
> [20:07:25,516][INFO ][preloader-demand-worker-#5034%internal.IgniteClientReconnectCacheTest0%][GridDhtPreloader] <nearCache> Completed rebalancing in ASYNC mode [cache=nearCache, time=0 ms]
> [20:07:25,516][INFO ][ignite-#4931%sys-internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <nearCache> Starting rebalancing in ASYNC mode: nearCache
> [20:07:25,517][INFO ][ignite-#4868%sys-internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <nearCache> Starting rebalancing in ASYNC mode: nearCache
> [20:07:25,521][INFO ][test-runner][root] Block reconnect.
> [20:07:25,521][INFO ][preloader-demand-worker-#5036%internal.IgniteClientReconnectCacheTest2%][GridDhtPreloader] <nearCache> Completed rebalancing in ASYNC mode [cache=nearCache, time=0 ms]
> [20:07:25,522][INFO ][test-runner][root] Fail client.
> [20:07:25,523][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Topology snapshot [ver=5, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,523][INFO ][preloader-demand-worker-#5026%internal.IgniteClientReconnectCacheTest1%][GridDhtPreloader] <nearCache> Completed rebalancing in ASYNC mode [cache=nearCache, time=0 ms]
> [20:07:25,523][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=5, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,524][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Topology snapshot [ver=5, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:25,528][INFO ][tcp-client-disco-msg-worker-#1262%internal.IgniteClientReconnectCacheTest3][IgniteClientReconnectAbstractTest$TestTcpDiscoverySpi] Client node disconnected from cluster, will try to reconnect with new id [newId=9af84855-5704-4680-9681-24f30dd12d22, prevId=30ff918b-a495-42a3-a953-c71291e41003, locNode=TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=0, lastExchangeTime=1445274445393, loc=true, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]]
> [20:07:25,528][INFO ][disco-event-worker-#5000%internal.IgniteClientReconnectCacheTest3%][root] Disconnected: DiscoveryEvent [evtNode=TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=0, lastExchangeTime=1445274445393, loc=true, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true], topVer=4, nodeId8=30ff918b, msg=Client node disconnected: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=0, lastExchangeTime=1445274445393, loc=true, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true], type=CLIENT_NODE_DISCONNECTED, tstamp=1445274445524]
> [20:07:25,529][INFO ][async-runner-1][root] Start put.
> [20:07:25,529][INFO ][async-runner-1][root] Expected exception: javax.cache.CacheException: class org.apache.ignite.IgniteClientDisconnectedException: Client node disconnected: internal.IgniteClientReconnectCacheTest3
> [20:07:25,530][INFO ][tcp-client-disco-msg-worker-#1262%internal.IgniteClientReconnectCacheTest3][IgniteClientReconnectAbstractTest$TestTcpDiscoverySpi] Block join request send: TcpDiscoveryJoinRequestMessage [node=TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=0, intOrder=0, lastExchangeTime=1445274445524, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true], super=TcpDiscoveryAbstractMessage [sndNodeId=null, id=fa032118051-9af84855-5704-4680-9681-24f30dd12d22, verifierNodeId=null, topVer=0, pendingIdx=0, isClient=true]]
> [20:07:30,529][INFO ][test-runner][root] Allow reconnect.
> [20:07:30,541][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,542][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:30,543][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,543][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:30,546][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,546][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:30,558][INFO ][disco-event-worker-#5000%internal.IgniteClientReconnectCacheTest3%][GridDiscoveryManager] Client node reconnected to topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=0, lastExchangeTime=1445274445393, loc=true, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,558][INFO ][disco-event-worker-#5000%internal.IgniteClientReconnectCacheTest3%][GridDiscoveryManager] Topology snapshot [ver=6, servers=3, clients=1, CPUs=4, heap=2.7GB]
> [20:07:30,559][INFO ][disco-event-worker-#5000%internal.IgniteClientReconnectCacheTest3%][root] Reconnected: DiscoveryEvent [evtNode=TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=0, lastExchangeTime=1445274445393, loc=true, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true], topVer=6, nodeId8=9af84855, msg=Client node reconnected: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=0, lastExchangeTime=1445274445393, loc=true, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true], type=CLIENT_NODE_RECONNECTED, tstamp=1445274450551]
> [20:07:30,560][INFO ][async-runner-1][root] Finish put.
> [20:07:30,566][INFO ][main][root] >>> Stopping test: testReconnect in 5198 ms <<<
> [20:07:30,566][INFO ][main][root] >>> Stopping grid [name=internal.IgniteClientReconnectCacheTest3, id=9af84855-5704-4680-9681-24f30dd12d22]
> [20:07:30,571][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,571][INFO ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Topology snapshot [ver=7, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:30,574][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,574][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=7, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:30,574][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,574][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Topology snapshot [ver=7, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [20:07:30,575][INFO ][main][GridCacheProcessor] Stopped cache: static-cache
> [20:07:30,575][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [20:07:30,576][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [20:07:30,576][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [20:07:30,576][INFO ][main][GridCacheProcessor] Stopped cache: nearCache
> [20:07:30,576][INFO ][main][GridCacheProcessor] Stopped cache: null
> [20:07:30,577][INFO ][main][IgniteClientReconnectCacheTest3] 
> >>> +------------------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08 stopped OK
> >>> +------------------------------------------------------------------------------------------+
> >>> Grid name: internal.IgniteClientReconnectCacheTest3
> >>> Grid uptime: 00:00:05:128
> [20:07:30,577][INFO ][main][root] >>> Stopping grid [name=internal.IgniteClientReconnectCacheTest0, id=009c5a78-baed-4aca-b211-b2a634c93000]
> [20:07:30,581][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=009c5a78-baed-4aca-b211-b2a634c93000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445274444979, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false]
> [20:07:30,581][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=009c5a78-baed-4aca-b211-b2a634c93000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1445274445050, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false]
> [20:07:30,581][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=8, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [20:07:30,581][INFO ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Topology snapshot [ver=8, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [20:07:30,589][INFO ][main][GridCacheProcessor] Stopped cache: static-cache
> [20:07:30,590][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [20:07:30,590][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [20:07:30,590][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [20:07:30,590][INFO ][main][GridCacheProcessor] Stopped cache: nearCache
> [20:07:30,590][INFO ][main][GridCacheProcessor] Stopped cache: null
> [20:07:30,591][INFO ][main][IgniteClientReconnectCacheTest0] 
> >>> +------------------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08 stopped OK
> >>> +------------------------------------------------------------------------------------------+
> >>> Grid name: internal.IgniteClientReconnectCacheTest0
> >>> Grid uptime: 00:00:05:643
> [20:07:30,599][INFO ][main][root] >>> Stopping grid [name=internal.IgniteClientReconnectCacheTest2, id=20397987-c902-4000-9a54-fd39da1ac002]
> [20:07:30,609][INFO ][main][GridCacheProcessor] Stopped cache: static-cache
> [20:07:30,609][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=20397987-c902-4000-9a54-fd39da1ac002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1445274445050, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false]
> [20:07:30,609][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [20:07:30,609][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [20:07:30,610][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [20:07:30,610][INFO ][main][GridCacheProcessor] Stopped cache: nearCache
> [20:07:30,610][INFO ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Topology snapshot [ver=9, servers=1, clients=0, CPUs=4, heap=2.7GB]
> [20:07:30,610][INFO ][main][GridCacheProcessor] Stopped cache: null
> [20:07:30,611][INFO ][main][IgniteClientReconnectCacheTest2] 
> >>> +------------------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08 stopped OK
> >>> +------------------------------------------------------------------------------------------+
> >>> Grid name: internal.IgniteClientReconnectCacheTest2
> >>> Grid uptime: 00:00:05:242
> [20:07:30,611][INFO ][main][root] >>> Stopping grid [name=internal.IgniteClientReconnectCacheTest1, id=1014f5ac-19c1-482c-be6c-337755519001]
> [20:07:30,615][INFO ][main][GridCacheProcessor] Stopped cache: static-cache
> [20:07:30,615][INFO ][main][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [20:07:30,616][INFO ][main][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [20:07:30,616][INFO ][main][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [20:07:30,616][INFO ][main][GridCacheProcessor] Stopped cache: nearCache
> [20:07:30,616][INFO ][main][GridCacheProcessor] Stopped cache: null
> [20:07:30,617][INFO ][main][IgniteClientReconnectCacheTest1] 
> >>> +------------------------------------------------------------------------------------------+
> >>> Ignite ver. 1.5.0-SNAPSHOT#20151019-sha1:10ef06a14245c73cbfe66b744406a0391c4a9c08 stopped OK
> >>> +------------------------------------------------------------------------------------------+
> >>> Grid name: internal.IgniteClientReconnectCacheTest1
> >>> Grid uptime: 00:00:05:595
> [20:07:30,618][INFO ][main][root] >>> Stopping test class: IgniteClientReconnectCacheTest <<<
> ------- Stderr: -------
> [20:07:22,903][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [20:07:22,903][WARN ][main][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [20:07:24,964][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [20:07:24,964][WARN ][main][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [20:07:25,047][WARN ][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [20:07:25,047][WARN ][main][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [20:07:25,065][WARN ][main][IgniteClientReconnectCacheTest2] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
> [20:07:25,389][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [20:07:25,389][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [20:07:25,415][WARN ][test-runner][IgniteClientReconnectCacheTest3] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
> [20:07:25,522][WARN ][disco-event-worker-#4818%internal.IgniteClientReconnectCacheTest0%][GridDiscoveryManager] Node FAILED: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, lastExchangeTime=1445274445393, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:25,523][WARN ][disco-event-worker-#4880%internal.IgniteClientReconnectCacheTest1%][GridDiscoveryManager] Node FAILED: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, lastExchangeTime=1445274445393, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:25,524][WARN ][disco-event-worker-#4945%internal.IgniteClientReconnectCacheTest2%][GridDiscoveryManager] Node FAILED: TcpDiscoveryNode [id=30ff918b-a495-42a3-a953-c71291e41003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=4, intOrder=4, lastExchangeTime=1445274445404, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true]
> [20:07:30,550][ERROR][grid-time-coordinator-#4820%internal.IgniteClientReconnectCacheTest0%][GridClockSyncProcessor] Failed to send time sync snapshot to remote node (did not leave grid?) [nodeId=9af84855-5704-4680-9681-24f30dd12d22, msg=GridClockDeltaSnapshotMessage [snapVer=GridClockDeltaVersion [ver=6, topVer=6], deltas={1014f5ac-19c1-482c-be6c-337755519001=0, 9af84855-5704-4680-9681-24f30dd12d22=0, 20397987-c902-4000-9a54-fd39da1ac002=0}], err=Failed to send message (node may have left the grid or TCP connection cannot be established due to firewall issues) [node=TcpDiscoveryNode [id=9af84855-5704-4680-9681-24f30dd12d22, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:0], discPort=0, order=6, intOrder=5, lastExchangeTime=1445274450531, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=true], topic=TOPIC_TIME_SYNC, msg=GridClockDeltaSnapshotMessage [snapVer=GridClockDeltaVersion [ver=6, topVer=6], deltas={1014f5ac-19c1-482c-be6c-337755519001=0, 9af84855-5704-4680-9681-24f30dd12d22=0, 20397987-c902-4000-9a54-fd39da1ac002=0}], policy=2]]
> [20:07:30,565][ERROR][main][root] Test failed.
> junit.framework.AssertionFailedError: expected:<21> but was:<20>
>     at junit.framework.Assert.fail(Assert.java:57)
>     at junit.framework.Assert.failNotEquals(Assert.java:329)
>     at junit.framework.Assert.assertEquals(Assert.java:78)
>     at junit.framework.Assert.assertEquals(Assert.java:86)
>     at junit.framework.TestCase.assertEquals(TestCase.java:253)
>     at org.apache.ignite.internal.IgniteClientReconnectCacheTest.testReconnect(IgniteClientReconnectCacheTest.java:280)
>     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:601)
>     at junit.framework.TestCase.runTest(TestCase.java:176)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1658)
>     at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:112)
>     at org.apache.ignite.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1596)
> [20:07:30,607][WARN ][exchange-worker-#4882%internal.IgniteClientReconnectCacheTest1%][GridCachePartitionExchangeManager] Failed to send partitions full message [node=TcpDiscoveryNode [id=20397987-c902-4000-9a54-fd39da1ac002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1445274445050, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false], err=class o.a.i.IgniteCheckedException: Failed to send message (node may have left the grid or TCP connection cannot be established due to firewall issues) [node=TcpDiscoveryNode [id=20397987-c902-4000-9a54-fd39da1ac002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1445274445050, loc=false, ver=1.5.0#20151019-sha1:10ef06a1, isClient=false], topic=TOPIC_CACHE, msg=GridDhtPartitionsFullMessage [parts={1=GridDhtPartitionFullMap [nodeId=1014f5ac-19c1-482c-be6c-337755519001, nodeOrder=2, updateSeq=17, size=2], -2100569601=GridDhtPartitionFullMap [nodeId=1014f5ac-19c1-482c-be6c-337755519001, nodeOrder=2, updateSeq=121, size=2], 689859866=GridDhtPartitionFullMap [nodeId=1014f5ac-19c1-482c-be6c-337755519001, nodeOrder=2, updateSeq=522, size=2], -2099179462=GridDhtPartitionFullMap [nodeId=1014f5ac-19c1-482c-be6c-337755519001, nodeOrder=2, updateSeq=12, size=2], 236160867=GridDhtPartitionFullMap [nodeId=1014f5ac-19c1-482c-be6c-337755519001, nodeOrder=2, updateSeq=522, size=2], 1325947219=GridDhtPartitionFullMap [nodeId=1014f5ac-19c1-482c-be6c-337755519001, nodeOrder=2, updateSeq=41, size=2]}, topVer=AffinityTopologyVersion [topVer=-1, minorTopVer=0], partCnt=6, super=GridDhtPartitionsAbstractMessage [exchId=null, lastVer=null, super=GridCacheMessage [msgId=1828, depInfo=null, err=null, skipPrepare=false]]], policy=2]]
> [20:07:30,608][WARN ][tcp-comm-worker-#1242%internal.IgniteClientReconnectCacheTest1][IgniteClientReconnectAbstractTest$TestTcpDiscoverySpi] Failed to ping node (status check will be initiated): 20397987-c902-4000-9a54-fd39da1ac002
> {noformat}



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