Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id ADA73200C52 for ; Mon, 10 Apr 2017 12:52:00 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id AC1D0160B99; Mon, 10 Apr 2017 10:52:00 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 3778F160B85 for ; Mon, 10 Apr 2017 12:51:58 +0200 (CEST) Received: (qmail 62287 invoked by uid 500); 10 Apr 2017 10:51:57 -0000 Mailing-List: contact issues-help@ignite.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@ignite.apache.org Delivered-To: mailing list issues@ignite.apache.org Received: (qmail 62278 invoked by uid 99); 10 Apr 2017 10:51:57 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 10 Apr 2017 10:51:57 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 82F89C036E for ; Mon, 10 Apr 2017 10:51:56 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -98.952 X-Spam-Level: X-Spam-Status: No, score=-98.952 tagged_above=-999 required=6.31 tests=[KAM_ASCII_DIVIDERS=0.8, KAM_LOTSOFHASH=0.25, RP_MATCHES_RCVD=-0.001, SPF_PASS=-0.001, USER_IN_WHITELIST=-100] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id YAYAwpRyTi-D for ; Mon, 10 Apr 2017 10:51:46 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 684B15FE56 for ; Mon, 10 Apr 2017 10:51:44 +0000 (UTC) Received: from jira-lw-us.apache.org (unknown [207.244.88.139]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id 02DA3E0D18 for ; Mon, 10 Apr 2017 10:51:43 +0000 (UTC) Received: from jira-lw-us.apache.org (localhost [127.0.0.1]) by jira-lw-us.apache.org (ASF Mail Server at jira-lw-us.apache.org) with ESMTP id 1C2622406F for ; Mon, 10 Apr 2017 10:51:42 +0000 (UTC) Date: Mon, 10 Apr 2017 10:51:42 +0000 (UTC) From: "Vladimir Ozerov (JIRA)" To: issues@ignite.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Updated] (IGNITE-1611) [Test Failed] GridNearCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 archived-at: Mon, 10 Apr 2017 10:52:00 -0000 [ https://issues.apache.org/jira/browse/IGNITE-1611?page=3Dcom.atlassi= an.jira.plugin.system.issuetabpanels:all-tabpanel ] Vladimir Ozerov updated IGNITE-1611: ------------------------------------ Fix Version/s: (was: 2.0) 2.1 > [Test Failed] GridNearCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBa= ckupCommitImplicit > -------------------------------------------------------------------------= ------------------ > > Key: IGNITE-1611 > URL: https://issues.apache.org/jira/browse/IGNITE-1611 > Project: Ignite > Issue Type: Test > Affects Versions: 1.5.0.final > Reporter: Anton Vinogradov > Priority: Critical > Labels: Muted_test > Fix For: 2.1 > > > There is no guarantee that test failure will be produced each test run, p= lease analyze logs in case of reproduction problem: > {noformat} > junit.framework.AssertionFailedError: near=3DGridNearCacheEntry [topVer= =3D4, dhtVer=3DGridCacheVersion [topVer=3D55263880, nodeOrderDrId=3D1, glob= alTime=3D1443783881869, order=3D1443783876675], part=3D2, super=3DGridDistr= ibutedCacheEntry [super=3DGridCacheMapEntry [key=3DKeyCacheObjectImpl [val= =3D2, hasValBytes=3Dtrue], val=3DCacheObjectImpl [val=3D2, hasValBytes=3Dtr= ue], startVer=3D1443783876676, ver=3DGridCacheVersion [topVer=3D55263880, n= odeOrderDrId=3D1, globalTime=3D1443783881869, order=3D1443783876675], hash= =3D821347078, extras=3Dnull, flags=3D0]]] > at junit.framework.Assert.fail(Assert.java:57) > at junit.framework.Assert.assertTrue(Assert.java:22) > at junit.framework.Assert.assertNull(Assert.java:277) > at junit.framework.TestCase.assertNull(TestCase.java:447) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.dataCheck(GridCacheTxNodeFailureSelfTest.java:342) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.checkPrimaryNodeFailureBackupCommit(GridCacheTxNod= eFailureSelfTest.java:302) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit(GridCac= heTxNodeFailureSelfTest.java:161) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorIm= pl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAc= cessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at junit.framework.TestCase.runTest(TestCase.java:176) > at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInt= ernal(GridAbstractTest.java:1665) > at org.apache.ignite.testframework.junits.GridAbstractTest.access$000= (GridAbstractTest.java:111) > at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(Grid= AbstractTest.java:1603) > ------- Stdout: ------- > [14:04:36,650][INFO ][main][root] >>> Starting test: testPrimaryNodeFailu= reBackupCommitImplicit <<< > [14:04:36,661][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]= =20 > >>> __________ ________________ =20 > >>> / _/ ___/ |/ / _/_ __/ __/ =20 > >>> _/ // (7 7 // / / / / _/ =20 > >>> /___/\___/_/|_/___/ /_/ /___/ =20 > >>>=20 > >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> 2015 Copyright(C) Apache Software Foundation > >>>=20 > >>> Ignite documentation: http://ignite.apache.org > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] C= onfig URL: n/a > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] D= aemon mode: off > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] O= S: Linux 2.6.32-38-generic amd64 > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] O= S user: teamcity > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] L= anguage runtime: Java Platform API Specification ver. 1.7 > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] V= M information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporat= ion Java HotSpot(TM) 64-Bit Server VM 24.80-b11 > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] V= M total memory: 2.7GB > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] R= emote Management [restart: off, REST: off, JMX (remote: off)] > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] I= GNITE_HOME=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13 > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] V= M arguments: [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.1= 3.1.2, -Dagent.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity= -3, -Dagent.ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/wor= k, -Dbuild.number=3D3390, -Dbuild.vcs.number=3D7b54cbd7499cd498b04e821dfa3b= 572bd94debec, -Dbuild.vcs.number.1=3D7b54cbd7499cd498b04e821dfa3b572bd94deb= ec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=3D7b54cbd7499cd498b04e821= dfa3b572bd94debec, -Dclassworlds.conf=3D/usr/local/TeamCityAgent/temp/build= Tmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=3D/usr/loca= l/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=3D/usr= /local/TeamCityAgent/temp/buildTmp, -Dmaven.home=3D/usr/local/TeamCityAgent= /tools/maven3, -Dmaven.repo.local=3D/home/teamcity/.m2/repository, -Dteamci= ty.agent.cpuBenchmark=3D651, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D547276, -Dteamcity.aut= h.password=3Dw2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D547276, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity= .build.id=3D547276, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buil= dConfName=3DIgnite Cache Failover, -Dteamcity.buildType.id=3DIgnite_DataGri= dFailover, -Dteamcity.configuration.properties.file=3D/usr/local/TeamCityAg= ent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity= .idea.home=3D/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watche= r.home=3D/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteam= city.projectName=3DIgnite Tests, -Dteamcity.runner.properties.file=3D/usr/l= ocal/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.propert= ies, -Dteamcity.tests.recentlyFailedTests.file=3D/usr/local/TeamCityAgent/t= emp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=3D8= .1.4 (build 30168), -ea, -XX:MaxPermSize=3D1024m, -Xms1g, -Xmx3g, -XX:+Heap= DumpOnOutOfMemoryError, -XX:HeapDumpPath=3D/usr/local/TeamCityAgent/work/87= 1ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCi= tyAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_N= O_DISCO_ORDER=3Dtrue, -DIGNITE_QUIET=3Dfalse] > [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] C= onfigured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignit= e-atomics-sys-cache', 'default'] > [14:04:36,667][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] 3= -rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46= e450b13/libs/licenses > [14:04:36,671][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] N= on-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2 > [14:04:36,671][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] E= nabled local MACs: C860006858B6 > [14:04:36,671][INFO ][test-runner][IgnitePluginProcessor] Configured plug= ins: > [14:04:36,671][INFO ][test-runner][IgnitePluginProcessor] ^-- None > [14:04:36,671][INFO ][test-runner][IgnitePluginProcessor]=20 > [14:04:36,672][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] IPC shared memory server endpoint started [port=3D48100, = tokDir=3D/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/00b32= 3c7-25f0-42a6-819b-e95d5173a000-5591] > [14:04:36,672][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound shared memory communication to TCP por= t [port=3D48100, locHost=3D/127.0.0.1] > [14:04:36,673][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound to TCP port [port=3D47100, locHost=3D/= 127.0.0.1] > [14:04:36,679][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] S= ecurity status [authentication=3Doff, tls/ssl=3Doff] > [14:04:36,688][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to= TCP port [port=3D47500, localHost=3D/127.0.0.1] > [14:04:36,693][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-marshaller-sys-cache, mode=3DREPLICATED] > [14:04:36,694][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-atomics-sys-cache, mode=3DPARTITIONED] > [14:04:36,697][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Ddefault, mode=3DPARTITIONED] > [14:04:36,699][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-sys-cache, mode=3DREPLICATED] > [14:04:36,707][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [14:04:36,707][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [14:04:36,707][INFO ][test-runner][GridDhtPreloader] Starting r= ebalancing in ASYNC mode: null > [14:04:36,707][INFO ][test-runner][GridDhtPreloader] S= tarting rebalancing in SYNC mode: ignite-sys-cache > [14:04:36,708][INFO ][preloader-demand-worker-#90271%dht.GridNearCacheTxN= odeFailureSelfTest0%][GridDhtPreloader] Compl= eted rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D= 0 ms] > [14:04:36,708][INFO ][preloader-demand-worker-#90275%dht.GridNearCacheTxN= odeFailureSelfTest0%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D0 ms] > [14:04:36,712][INFO ][preloader-demand-worker-#90279%dht.GridNearCacheTxN= odeFailureSelfTest0%][GridDhtPreloader] Completed rebalancing in = ASYNC mode [cache=3Dnull, time=3D0 ms] > [14:04:36,712][INFO ][preloader-demand-worker-#90285%dht.GridNearCacheTxN= odeFailureSelfTest0%][GridDhtPreloader] Completed rebala= ncing in SYNC mode [cache=3Dignite-sys-cache, time=3D0 ms] > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] P= erformance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest0' (= fix if possible) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] T= o disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] = ^-- Disable checkpoints (remove 'checkpointSpi' from configuration) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] = ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] = ^-- Disable grid events (remove 'includeEventTypes' from configuration) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] = ^-- Disable near cache (set 'nearConfiguration' to null) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] = ^-- Decrease number of backups (set 'keyBackups' to 0) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] = ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to A= TOMIC) > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]= =20 > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] T= o start Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]= =20 > [14:04:36,718][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]= =20 > >>> +------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> +------------------------------------------+ > >>> OS name: Linux 2.6.32-38-generic amd64 > >>> CPU(s): 4 > >>> Heap: 2.7GB > >>> VM name: 5591@gg-teamcity-3 > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest0 > >>> Local node [ID=3D00B323C7-25F0-42A6-819B-E95D5173A000, order=3D1, cli= entMode=3Dfalse] > >>> Local node addresses: [127.0.0.1] > >>> Local ports: TCP:47100 TCP:47500 TCP:48100=20 > [14:04:36,718][INFO ][test-runner][GridDiscoveryManager] Topology snapsho= t [ver=3D1, servers=3D1, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]= =20 > >>> __________ ________________ =20 > >>> / _/ ___/ |/ / _/_ __/ __/ =20 > >>> _/ // (7 7 // / / / / _/ =20 > >>> /___/\___/_/|_/___/ /_/ /___/ =20 > >>>=20 > >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> 2015 Copyright(C) Apache Software Foundation > >>>=20 > >>> Ignite documentation: http://ignite.apache.org > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] C= onfig URL: n/a > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] D= aemon mode: off > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] O= S: Linux 2.6.32-38-generic amd64 > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] O= S user: teamcity > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] L= anguage runtime: Java Platform API Specification ver. 1.7 > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] V= M information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporat= ion Java HotSpot(TM) 64-Bit Server VM 24.80-b11 > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] V= M total memory: 2.7GB > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] R= emote Management [restart: off, REST: off, JMX (remote: off)] > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] I= GNITE_HOME=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13 > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] V= M arguments: [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.1= 3.1.2, -Dagent.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity= -3, -Dagent.ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/wor= k, -Dbuild.number=3D3390, -Dbuild.vcs.number=3D7b54cbd7499cd498b04e821dfa3b= 572bd94debec, -Dbuild.vcs.number.1=3D7b54cbd7499cd498b04e821dfa3b572bd94deb= ec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=3D7b54cbd7499cd498b04e821= dfa3b572bd94debec, -Dclassworlds.conf=3D/usr/local/TeamCityAgent/temp/build= Tmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=3D/usr/loca= l/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=3D/usr= /local/TeamCityAgent/temp/buildTmp, -Dmaven.home=3D/usr/local/TeamCityAgent= /tools/maven3, -Dmaven.repo.local=3D/home/teamcity/.m2/repository, -Dteamci= ty.agent.cpuBenchmark=3D651, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D547276, -Dteamcity.aut= h.password=3Dw2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D547276, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity= .build.id=3D547276, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buil= dConfName=3DIgnite Cache Failover, -Dteamcity.buildType.id=3DIgnite_DataGri= dFailover, -Dteamcity.configuration.properties.file=3D/usr/local/TeamCityAg= ent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity= .idea.home=3D/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watche= r.home=3D/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteam= city.projectName=3DIgnite Tests, -Dteamcity.runner.properties.file=3D/usr/l= ocal/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.propert= ies, -Dteamcity.tests.recentlyFailedTests.file=3D/usr/local/TeamCityAgent/t= emp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=3D8= .1.4 (build 30168), -ea, -XX:MaxPermSize=3D1024m, -Xms1g, -Xmx3g, -XX:+Heap= DumpOnOutOfMemoryError, -XX:HeapDumpPath=3D/usr/local/TeamCityAgent/work/87= 1ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCi= tyAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_N= O_DISCO_ORDER=3Dtrue, -DIGNITE_QUIET=3Dfalse] > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] C= onfigured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignit= e-atomics-sys-cache', 'default'] > [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] 3= -rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46= e450b13/libs/licenses > [14:04:36,725][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] N= on-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2 > [14:04:36,725][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] E= nabled local MACs: C860006858B6 > [14:04:36,725][INFO ][test-runner][IgnitePluginProcessor] Configured plug= ins: > [14:04:36,725][INFO ][test-runner][IgnitePluginProcessor] ^-- None > [14:04:36,725][INFO ][test-runner][IgnitePluginProcessor]=20 > [14:04:36,726][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] IPC shared memory server endpoint started [port=3D48101, = tokDir=3D/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/10ca1= 73e-8703-475c-95a2-21db704e7001-5591] > [14:04:36,726][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound shared memory communication to TCP por= t [port=3D48101, locHost=3D/127.0.0.1] > [14:04:36,726][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound to TCP port [port=3D47101, locHost=3D/= 127.0.0.1] > [14:04:36,729][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] S= ecurity status [authentication=3Doff, tls/ssl=3Doff] > [14:04:36,732][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to= TCP port [port=3D47501, localHost=3D/127.0.0.1] > [14:04:36,745][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDisco= veryNode [id=3D10ca173e-8703-475c-95a2-21db704e7001, addrs=3D[127.0.0.1], s= ockAddrs=3D[/127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, l= astExchangeTime=3D1443783876734, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:000= 00000, isClient=3Dfalse] > [14:04:36,745][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3D2, servers= =3D2, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:36,746][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-marshaller-sys-cache, mode=3DREPLICATED] > [14:04:36,749][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-atomics-sys-cache, mode=3DPARTITIONED] > [14:04:36,852][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Ddefault, mode=3DPARTITIONED] > [14:04:36,853][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-sys-cache, mode=3DREPLICATED] > [14:04:36,866][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [14:04:36,866][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [14:04:36,866][INFO ][test-runner][GridDhtPreloader] Starting r= ebalancing in ASYNC mode: null > [14:04:36,866][INFO ][test-runner][GridDhtPreloader] S= tarting rebalancing in SYNC mode: ignite-sys-cache > [14:04:36,870][INFO ][preloader-demand-worker-#90335%dht.GridNearCacheTxN= odeFailureSelfTest1%][GridDhtPreloader] Compl= eted rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D= 0 ms] > [14:04:36,871][INFO ][preloader-demand-worker-#90350%dht.GridNearCacheTxN= odeFailureSelfTest1%][GridDhtPreloader] Completed rebala= ncing in SYNC mode [cache=3Dignite-sys-cache, time=3D10 ms] > [14:04:36,872][INFO ][preloader-demand-worker-#90340%dht.GridNearCacheTxN= odeFailureSelfTest1%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D10 ms] > [14:04:36,873][INFO ][preloader-demand-worker-#90344%dht.GridNearCacheTxN= odeFailureSelfTest1%][GridDhtPreloader] Completed rebalancing in = ASYNC mode [cache=3Dnull, time=3D10 ms] > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] P= erformance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest1' (= fix if possible) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] T= o disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] = ^-- Disable checkpoints (remove 'checkpointSpi' from configuration) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] = ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] = ^-- Disable grid events (remove 'includeEventTypes' from configuration) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] = ^-- Disable near cache (set 'nearConfiguration' to null) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] = ^-- Decrease number of backups (set 'keyBackups' to 0) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] = ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to A= TOMIC) > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]= =20 > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] T= o start Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]= =20 > [14:04:36,879][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]= =20 > >>> +------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> +------------------------------------------+ > >>> OS name: Linux 2.6.32-38-generic amd64 > >>> CPU(s): 4 > >>> Heap: 2.7GB > >>> VM name: 5591@gg-teamcity-3 > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest1 > >>> Local node [ID=3D10CA173E-8703-475C-95A2-21DB704E7001, order=3D2, cli= entMode=3Dfalse] > >>> Local node addresses: [127.0.0.1] > >>> Local ports: TCP:47101 TCP:47501 TCP:48101=20 > [14:04:36,879][INFO ][test-runner][GridDiscoveryManager] Topology snapsho= t [ver=3D2, servers=3D2, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:36,885][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]= =20 > >>> __________ ________________ =20 > >>> / _/ ___/ |/ / _/_ __/ __/ =20 > >>> _/ // (7 7 // / / / / _/ =20 > >>> /___/\___/_/|_/___/ /_/ /___/ =20 > >>>=20 > >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> 2015 Copyright(C) Apache Software Foundation > >>>=20 > >>> Ignite documentation: http://ignite.apache.org > [14:04:36,885][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] C= onfig URL: n/a > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] D= aemon mode: off > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] O= S: Linux 2.6.32-38-generic amd64 > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] O= S user: teamcity > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] L= anguage runtime: Java Platform API Specification ver. 1.7 > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] V= M information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporat= ion Java HotSpot(TM) 64-Bit Server VM 24.80-b11 > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] V= M total memory: 2.7GB > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] R= emote Management [restart: off, REST: off, JMX (remote: off)] > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] I= GNITE_HOME=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13 > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] V= M arguments: [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.1= 3.1.2, -Dagent.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity= -3, -Dagent.ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/wor= k, -Dbuild.number=3D3390, -Dbuild.vcs.number=3D7b54cbd7499cd498b04e821dfa3b= 572bd94debec, -Dbuild.vcs.number.1=3D7b54cbd7499cd498b04e821dfa3b572bd94deb= ec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=3D7b54cbd7499cd498b04e821= dfa3b572bd94debec, -Dclassworlds.conf=3D/usr/local/TeamCityAgent/temp/build= Tmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=3D/usr/loca= l/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=3D/usr= /local/TeamCityAgent/temp/buildTmp, -Dmaven.home=3D/usr/local/TeamCityAgent= /tools/maven3, -Dmaven.repo.local=3D/home/teamcity/.m2/repository, -Dteamci= ty.agent.cpuBenchmark=3D651, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D547276, -Dteamcity.aut= h.password=3Dw2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D547276, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity= .build.id=3D547276, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buil= dConfName=3DIgnite Cache Failover, -Dteamcity.buildType.id=3DIgnite_DataGri= dFailover, -Dteamcity.configuration.properties.file=3D/usr/local/TeamCityAg= ent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity= .idea.home=3D/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watche= r.home=3D/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteam= city.projectName=3DIgnite Tests, -Dteamcity.runner.properties.file=3D/usr/l= ocal/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.propert= ies, -Dteamcity.tests.recentlyFailedTests.file=3D/usr/local/TeamCityAgent/t= emp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=3D8= .1.4 (build 30168), -ea, -XX:MaxPermSize=3D1024m, -Xms1g, -Xmx3g, -XX:+Heap= DumpOnOutOfMemoryError, -XX:HeapDumpPath=3D/usr/local/TeamCityAgent/work/87= 1ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCi= tyAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_N= O_DISCO_ORDER=3Dtrue, -DIGNITE_QUIET=3Dfalse] > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] C= onfigured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignit= e-atomics-sys-cache', 'default'] > [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] 3= -rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46= e450b13/libs/licenses > [14:04:36,888][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] N= on-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2 > [14:04:36,888][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] E= nabled local MACs: C860006858B6 > [14:04:36,888][INFO ][test-runner][IgnitePluginProcessor] Configured plug= ins: > [14:04:36,888][INFO ][test-runner][IgnitePluginProcessor] ^-- None > [14:04:36,888][INFO ][test-runner][IgnitePluginProcessor]=20 > [14:04:36,890][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] IPC shared memory server endpoint started [port=3D48102, = tokDir=3D/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/20cd9= 8aa-ef5a-4eac-b28e-27c6df0b4002-5591] > [14:04:36,890][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound shared memory communication to TCP por= t [port=3D48102, locHost=3D/127.0.0.1] > [14:04:36,891][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound to TCP port [port=3D47102, locHost=3D/= 127.0.0.1] > [14:04:36,896][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] S= ecurity status [authentication=3Doff, tls/ssl=3Doff] > [14:04:36,899][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to= TCP port [port=3D47502, localHost=3D/127.0.0.1] > [14:04:36,909][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDisco= veryNode [id=3D20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=3D[127.0.0.1], s= ockAddrs=3D[/127.0.0.1:47502], discPort=3D47502, order=3D3, intOrder=3D3, l= astExchangeTime=3D1443783876901, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:000= 00000, isClient=3Dfalse] > [14:04:36,910][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFa= ilureSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDisco= veryNode [id=3D20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=3D[127.0.0.1], s= ockAddrs=3D[/127.0.0.1:47502], discPort=3D47502, order=3D3, intOrder=3D3, l= astExchangeTime=3D1443783876901, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:000= 00000, isClient=3Dfalse] > [14:04:36,910][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3D3, servers= =3D3, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:36,910][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFa= ilureSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=3D3, servers= =3D3, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:36,912][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-marshaller-sys-cache, mode=3DREPLICATED] > [14:04:36,914][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-atomics-sys-cache, mode=3DPARTITIONED] > [14:04:36,918][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Ddefault, mode=3DPARTITIONED] > [14:04:36,919][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-sys-cache, mode=3DREPLICATED] > [14:04:36,932][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [14:04:36,932][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [14:04:36,932][INFO ][test-runner][GridDhtPreloader] Starting r= ebalancing in ASYNC mode: null > [14:04:36,932][INFO ][test-runner][GridDhtPreloader] S= tarting rebalancing in SYNC mode: ignite-sys-cache > [14:04:36,936][INFO ][preloader-demand-worker-#90403%dht.GridNearCacheTxN= odeFailureSelfTest2%][GridDhtPreloader] Compl= eted rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D= 0 ms] > [14:04:36,939][INFO ][preloader-demand-worker-#90418%dht.GridNearCacheTxN= odeFailureSelfTest2%][GridDhtPreloader] Completed rebala= ncing in SYNC mode [cache=3Dignite-sys-cache, time=3D0 ms] > [14:04:36,939][INFO ][preloader-demand-worker-#90412%dht.GridNearCacheTxN= odeFailureSelfTest2%][GridDhtPreloader] Completed rebalancing in = ASYNC mode [cache=3Dnull, time=3D0 ms] > [14:04:36,978][INFO ][preloader-demand-worker-#90407%dht.GridNearCacheTxN= odeFailureSelfTest2%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D41 ms] > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] P= erformance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest2' (= fix if possible) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] T= o disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] = ^-- Disable checkpoints (remove 'checkpointSpi' from configuration) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] = ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] = ^-- Disable grid events (remove 'includeEventTypes' from configuration) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] = ^-- Disable near cache (set 'nearConfiguration' to null) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] = ^-- Decrease number of backups (set 'keyBackups' to 0) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] = ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to A= TOMIC) > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]= =20 > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] T= o start Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]= =20 > [14:04:36,988][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]= =20 > >>> +------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> +------------------------------------------+ > >>> OS name: Linux 2.6.32-38-generic amd64 > >>> CPU(s): 4 > >>> Heap: 2.7GB > >>> VM name: 5591@gg-teamcity-3 > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest2 > >>> Local node [ID=3D20CD98AA-EF5A-4EAC-B28E-27C6DF0B4002, order=3D3, cli= entMode=3Dfalse] > >>> Local node addresses: [127.0.0.1] > >>> Local ports: TCP:47102 TCP:47502 TCP:48102=20 > [14:04:36,988][INFO ][test-runner][GridDiscoveryManager] Topology snapsho= t [ver=3D3, servers=3D3, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]= =20 > >>> __________ ________________ =20 > >>> / _/ ___/ |/ / _/_ __/ __/ =20 > >>> _/ // (7 7 // / / / / _/ =20 > >>> /___/\___/_/|_/___/ /_/ /___/ =20 > >>>=20 > >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> 2015 Copyright(C) Apache Software Foundation > >>>=20 > >>> Ignite documentation: http://ignite.apache.org > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] C= onfig URL: n/a > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] D= aemon mode: off > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] O= S: Linux 2.6.32-38-generic amd64 > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] O= S user: teamcity > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] L= anguage runtime: Java Platform API Specification ver. 1.7 > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] V= M information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporat= ion Java HotSpot(TM) 64-Bit Server VM 24.80-b11 > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] V= M total memory: 2.7GB > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] R= emote Management [restart: off, REST: off, JMX (remote: off)] > [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] I= GNITE_HOME=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13 > [14:04:36,997][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] V= M arguments: [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.1= 3.1.2, -Dagent.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity= -3, -Dagent.ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/wor= k, -Dbuild.number=3D3390, -Dbuild.vcs.number=3D7b54cbd7499cd498b04e821dfa3b= 572bd94debec, -Dbuild.vcs.number.1=3D7b54cbd7499cd498b04e821dfa3b572bd94deb= ec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=3D7b54cbd7499cd498b04e821= dfa3b572bd94debec, -Dclassworlds.conf=3D/usr/local/TeamCityAgent/temp/build= Tmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=3D/usr/loca= l/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=3D/usr= /local/TeamCityAgent/temp/buildTmp, -Dmaven.home=3D/usr/local/TeamCityAgent= /tools/maven3, -Dmaven.repo.local=3D/home/teamcity/.m2/repository, -Dteamci= ty.agent.cpuBenchmark=3D651, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D547276, -Dteamcity.aut= h.password=3Dw2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D547276, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity= .build.id=3D547276, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buil= dConfName=3DIgnite Cache Failover, -Dteamcity.buildType.id=3DIgnite_DataGri= dFailover, -Dteamcity.configuration.properties.file=3D/usr/local/TeamCityAg= ent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity= .idea.home=3D/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watche= r.home=3D/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteam= city.projectName=3DIgnite Tests, -Dteamcity.runner.properties.file=3D/usr/l= ocal/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.propert= ies, -Dteamcity.tests.recentlyFailedTests.file=3D/usr/local/TeamCityAgent/t= emp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=3D8= .1.4 (build 30168), -ea, -XX:MaxPermSize=3D1024m, -Xms1g, -Xmx3g, -XX:+Heap= DumpOnOutOfMemoryError, -XX:HeapDumpPath=3D/usr/local/TeamCityAgent/work/87= 1ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCi= tyAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_N= O_DISCO_ORDER=3Dtrue, -DIGNITE_QUIET=3Dfalse] > [14:04:36,997][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] C= onfigured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignit= e-atomics-sys-cache', 'default'] > [14:04:36,997][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] 3= -rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46= e450b13/libs/licenses > [14:04:37,000][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] N= on-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2 > [14:04:37,000][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] E= nabled local MACs: C860006858B6 > [14:04:37,000][INFO ][test-runner][IgnitePluginProcessor] Configured plug= ins: > [14:04:37,000][INFO ][test-runner][IgnitePluginProcessor] ^-- None > [14:04:37,000][INFO ][test-runner][IgnitePluginProcessor]=20 > [14:04:37,002][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] IPC shared memory server endpoint started [port=3D48103, = tokDir=3D/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/3035d= 755-8140-4af5-820d-df1d537b4003-5591] > [14:04:37,002][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound shared memory communication to TCP por= t [port=3D48103, locHost=3D/127.0.0.1] > [14:04:37,003][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$Banning= CommunicationSpi] Successfully bound to TCP port [port=3D47103, locHost=3D/= 127.0.0.1] > [14:04:37,009][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] S= ecurity status [authentication=3Doff, tls/ssl=3Doff] > [14:04:37,015][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to= TCP port [port=3D47503, localHost=3D/127.0.0.1] > [14:04:37,030][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDisco= veryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], s= ockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, l= astExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:000= 00000, isClient=3Dfalse] > [14:04:37,031][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFa= ilureSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDisco= veryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], s= ockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, l= astExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:000= 00000, isClient=3Dfalse] > [14:04:37,031][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3D4, servers= =3D4, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:37,031][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFa= ilureSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=3D4, servers= =3D4, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:37,031][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFa= ilureSelfTest2%][GridDiscoveryManager] Added new node to topology: TcpDisco= veryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], s= ockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, l= astExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:000= 00000, isClient=3Dfalse] > [14:04:37,031][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFa= ilureSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=3D4, servers= =3D4, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:37,037][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-marshaller-sys-cache, mode=3DREPLICATED] > [14:04:37,039][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-atomics-sys-cache, mode=3DPARTITIONED] > [14:04:37,043][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Ddefault, mode=3DPARTITIONED] > [14:04:37,045][INFO ][test-runner][GridCacheProcessor] Started cache [nam= e=3Dignite-sys-cache, mode=3DREPLICATED] > [14:04:37,133][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [14:04:37,133][INFO ][test-runner][GridDhtPreloader] Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [14:04:37,133][INFO ][test-runner][GridDhtPreloader] Starting r= ebalancing in ASYNC mode: null > [14:04:37,133][INFO ][test-runner][GridDhtPreloader] S= tarting rebalancing in SYNC mode: ignite-sys-cache > [14:04:37,188][INFO ][preloader-demand-worker-#90473%dht.GridNearCacheTxN= odeFailureSelfTest3%][GridDhtPreloader] Compl= eted rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D= 50 ms] > [14:04:37,190][INFO ][preloader-demand-worker-#90482%dht.GridNearCacheTxN= odeFailureSelfTest3%][GridDhtPreloader] Completed rebalancing in = ASYNC mode [cache=3Dnull, time=3D50 ms] > [14:04:37,191][INFO ][preloader-demand-worker-#90486%dht.GridNearCacheTxN= odeFailureSelfTest3%][GridDhtPreloader] Completed rebala= ncing in SYNC mode [cache=3Dignite-sys-cache, time=3D50 ms] > [14:04:37,230][INFO ][preloader-demand-worker-#90478%dht.GridNearCacheTxN= odeFailureSelfTest3%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D91 ms] > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] P= erformance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest3' (= fix if possible) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] T= o disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] = ^-- Disable checkpoints (remove 'checkpointSpi' from configuration) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] = ^-- Disable peer class loading (set 'peerClassLoadingEnabled' to false) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] = ^-- Disable grid events (remove 'includeEventTypes' from configuration) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] = ^-- Disable near cache (set 'nearConfiguration' to null) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] = ^-- Decrease number of backups (set 'keyBackups' to 0) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] = ^-- Enable ATOMIC mode if not using transactions (set 'atomicityMode' to A= TOMIC) > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]= =20 > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] T= o start Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]= =20 > [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]= =20 > >>> +------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV > >>> +------------------------------------------+ > >>> OS name: Linux 2.6.32-38-generic amd64 > >>> CPU(s): 4 > >>> Heap: 2.7GB > >>> VM name: 5591@gg-teamcity-3 > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest3 > >>> Local node [ID=3D3035D755-8140-4AF5-820D-DF1D537B4003, order=3D4, cli= entMode=3Dfalse] > >>> Local node addresses: [127.0.0.1] > >>> Local ports: TCP:47103 TCP:47503 TCP:48103=20 > [14:04:37,241][INFO ][test-runner][GridDiscoveryManager] Topology snapsho= t [ver=3D4, servers=3D4, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:41,868][INFO ][test-runner][root] Grid 0: 00b323c7-25f0-42a6-819b-= e95d5173a000 > [14:04:41,868][INFO ][test-runner][root] Grid 1: 10ca173e-8703-475c-95a2-= 21db704e7001 > [14:04:41,868][INFO ][test-runner][root] Grid 2: 20cd98aa-ef5a-4eac-b28e-= 27c6df0b4002 > [14:04:41,868][INFO ][test-runner][root] Grid 3: 3035d755-8140-4af5-820d-= df1d537b4003 > [14:04:42,873][INFO ][test-runner][root] >>> Stopping grid [name=3Ddht.Gr= idNearCacheTxNodeFailureSelfTest1, id=3D10ca173e-8703-475c-95a2-21db704e700= 1] > [14:04:42,884][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode= [id=3D10ca173e-8703-475c-95a2-21db704e7001, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, lastExcha= ngeTime=3D1443783876734, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, i= sClient=3Dfalse] > [14:04:42,885][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFa= ilureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3D5, servers= =3D3, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:42,885][INFO ][test-runner][GridCacheProcessor] Stopped cache: nul= l > [14:04:42,886][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-marshaller-sys-cache > [14:04:42,886][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-sys-cache > [14:04:42,886][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-atomics-sys-cache > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Removed unde= ployed class: GridDeployment [ts=3D1443783876861, depMode=3DSHARED, clsLdr= =3DIsolatedClassLoader{roleName=3D'test'}, clsLdrId=3D82e91823051-10ca173e-= 8703-475c-95a2-21db704e7001, userVer=3D0, loc=3Dtrue, sampleClsName=3Dorg.a= pache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPar= titionFullMap, pendingUndeploy=3Dfalse, undeployed=3Dtrue, usage=3D0] > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionFullMap > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.util.GridLeanMap > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class java.lang.Integer > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3 > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionMap > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.GridCachePa= rtitionExchangeManager$ResendTimeoutObject$1 > [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.GridTopic$T7 > [14:04:42,893][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFa= ilureSelfTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode= [id=3D10ca173e-8703-475c-95a2-21db704e7001, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, lastExcha= ngeTime=3D1443783876901, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, i= sClient=3Dfalse] > [14:04:42,893][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFa= ilureSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=3D5, servers= =3D3, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:42,894][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFa= ilureSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode= [id=3D10ca173e-8703-475c-95a2-21db704e7001, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, lastExcha= ngeTime=3D1443783877022, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, i= sClient=3Dfalse] > [14:04:42,894][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFa= ilureSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=3D5, servers= =3D3, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:42,900][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]= =20 > >>> +-----------------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK > >>> +-----------------------------------------------------+ > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest1 > >>> Grid uptime: 00:00:06:27 > [14:04:42,902][INFO ][test-runner][root] >>> Stopping grid [name=3Ddht.Gr= idNearCacheTxNodeFailureSelfTest0, id=3D00b323c7-25f0-42a6-819b-e95d5173a00= 0] > [14:04:42,910][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFa= ilureSelfTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode= [id=3D00b323c7-25f0-42a6-819b-e95d5173a000, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47500], discPort=3D47500, order=3D1, intOrder=3D1, lastExcha= ngeTime=3D1443783876901, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, i= sClient=3Dfalse] > [14:04:42,910][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFa= ilureSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=3D6, servers= =3D2, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:42,910][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFa= ilureSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode= [id=3D00b323c7-25f0-42a6-819b-e95d5173a000, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47500], discPort=3D47500, order=3D1, intOrder=3D1, lastExcha= ngeTime=3D1443783877022, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, i= sClient=3Dfalse] > [14:04:42,910][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFa= ilureSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=3D6, servers= =3D2, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: nul= l > [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-marshaller-sys-cache > [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-sys-cache > [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-atomics-sys-cache > [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Removed unde= ployed class: GridDeployment [ts=3D1443783876861, depMode=3DSHARED, clsLdr= =3DIsolatedClassLoader{roleName=3D'test'}, clsLdrId=3D62e91823051-00b323c7-= 25f0-42a6-819b-e95d5173a000, userVer=3D0, loc=3Dtrue, sampleClsName=3Dorg.a= pache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPar= titionMap, pendingUndeploy=3Dfalse, undeployed=3Dtrue, usage=3D0] > [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class java.lang.Integer > [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionMap > [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.GridCachePa= rtitionExchangeManager$ResendTimeoutObject$1 > [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.GridTopic$T7 > [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.task.GridTaskProc= essor$TaskDiscoveryListener$1 > [14:04:42,988][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]= =20 > >>> +-----------------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK > >>> +-----------------------------------------------------+ > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest0 > >>> Grid uptime: 00:00:06:264 > [14:04:42,995][INFO ][test-runner][root] >>> Stopping grid [name=3Ddht.Gr= idNearCacheTxNodeFailureSelfTest2, id=3D20cd98aa-ef5a-4eac-b28e-27c6df0b400= 2] > [14:04:43,004][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFa= ilureSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode= [id=3D20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47502], discPort=3D47502, order=3D3, intOrder=3D3, lastExcha= ngeTime=3D1443783877022, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, i= sClient=3Dfalse] > [14:04:43,004][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFa= ilureSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=3D7, servers= =3D1, clients=3D0, CPUs=3D4, heap=3D2.7GB] > [14:04:43,004][INFO ][test-runner][GridCacheProcessor] Stopped cache: nul= l > [14:04:43,005][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-marshaller-sys-cache > [14:04:43,005][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-sys-cache > [14:04:43,005][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-atomics-sys-cache > [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Removed unde= ployed class: GridDeployment [ts=3D1443783876921, depMode=3DSHARED, clsLdr= =3DIsolatedClassLoader{roleName=3D'test'}, clsLdrId=3D6d2a1823051-20cd98aa-= ef5a-4eac-b28e-27c6df0b4002, userVer=3D0, loc=3Dtrue, sampleClsName=3Dorg.a= pache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPar= titionFullMap, pendingUndeploy=3Dfalse, undeployed=3Dtrue, usage=3D0] > [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionFullMap > [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionMap > [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.GridCachePa= rtitionExchangeManager$ResendTimeoutObject$1 > [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.GridTopic$T7 > [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.task.GridTaskProc= essor$TaskDiscoveryListener$1 > [14:04:43,014][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]= =20 > >>> +-----------------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK > >>> +-----------------------------------------------------+ > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest2 > >>> Grid uptime: 00:00:06:27 > [14:04:43,016][INFO ][test-runner][root] >>> Stopping grid [name=3Ddht.Gr= idNearCacheTxNodeFailureSelfTest3, id=3D3035d755-8140-4af5-820d-df1d537b400= 3] > [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: nul= l > [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-marshaller-sys-cache > [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-sys-cache > [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: ign= ite-atomics-sys-cache > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Removed unde= ployed class: GridDeployment [ts=3D1443783877123, depMode=3DSHARED, clsLdr= =3DIsolatedClassLoader{roleName=3D'test'}, clsLdrId=3Ddf7a1823051-3035d755-= 8140-4af5-820d-df1d537b4003, userVer=3D0, loc=3Dtrue, sampleClsName=3Dorg.a= pache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPar= titionFullMap, pendingUndeploy=3Dfalse, undeployed=3Dtrue, usage=3D0] > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionFullMap > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.util.GridLeanMap > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3 > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.distributed= .dht.preloader.GridDhtPartitionMap > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.cache.GridCachePa= rtitionExchangeManager$ResendTimeoutObject$1 > [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locall= y undeployed: class org.apache.ignite.internal.processors.task.GridTaskProc= essor$TaskDiscoveryListener$1 > [14:04:43,038][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]= =20 > >>> +-----------------------------------------------------+ > >>> Ignite ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV stopped OK > >>> +-----------------------------------------------------+ > >>> Grid name: dht.GridNearCacheTxNodeFailureSelfTest3 > >>> Grid uptime: 00:00:05:795 > [14:04:43,043][INFO ][main][root] >>> Stopping test: testPrimaryNodeFailu= reBackupCommitImplicit in 6393 ms <<< > ------- Stderr: ------- > [14:04:36,664][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest0] P= eer class loading is enabled (disable it in production for performance and = deployment consistency reasons) > [14:04:36,677][WARN ][test-runner][GridCollisionManager] Collision resolu= tion is disabled (all jobs will be activated upon arrival). > [14:04:36,678][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabl= ed. To enable use FileSwapSpaceSpi. > [14:04:36,723][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest1] P= eer class loading is enabled (disable it in production for performance and = deployment consistency reasons) > [14:04:36,729][WARN ][test-runner][GridCollisionManager] Collision resolu= tion is disabled (all jobs will be activated upon arrival). > [14:04:36,729][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabl= ed. To enable use FileSwapSpaceSpi. > [14:04:36,886][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest2] P= eer class loading is enabled (disable it in production for performance and = deployment consistency reasons) > [14:04:36,895][WARN ][test-runner][GridCollisionManager] Collision resolu= tion is disabled (all jobs will be activated upon arrival). > [14:04:36,896][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabl= ed. To enable use FileSwapSpaceSpi. > [14:04:36,911][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest2] A= ttempting to start more nodes than physical RAM available on current host (= this can cause significant slowdown) > [14:04:36,997][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest3] P= eer class loading is enabled (disable it in production for performance and = deployment consistency reasons) > [14:04:37,008][WARN ][test-runner][GridCollisionManager] Collision resolu= tion is disabled (all jobs will be activated upon arrival). > [14:04:37,008][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabl= ed. To enable use FileSwapSpaceSpi. > [14:04:37,035][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest3] A= ttempting to start more nodes than physical RAM available on current host (= this can cause significant slowdown) > [14:04:37,242][WARN ][test-runner][root] Waiting for topology map update = [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest0, cache=3Dignite-atomics-sys= -cache, cacheId=3D689859866, topVer=3DAffinityTopologyVersion [topVer=3D4, = minorTopVer=3D0], topFut=3DGridDhtPartitionsExchangeFuture [dummy=3Dfalse, = forcePreload=3Dfalse, reassign=3Dfalse, discoEvt=3DDiscoveryEvent [evtNode= =3DTcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[12= 7.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intO= rder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197001= 01-sha1:00000000, isClient=3Dfalse], topVer=3D4, nodeId8=3D00b323c7, msg=3D= Node joined: TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, a= ddrs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order= =3D4, intOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse], type=3DNODE_JOINED, tstamp= =3D1443783877022], rcvdIds=3DGridConcurrentHashSet [elements=3D[20cd98aa-ef= 5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8= 703-475c-95a2-21db704e7001]], rmtIds=3D[20cd98aa-ef5a-4eac-b28e-27c6df0b400= 2, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e70= 01], exchId=3DGridDhtPartitionExchangeId [topVer=3DAffinityTopologyVersion = [topVer=3D4, minorTopVer=3D0], nodeId=3D3035d755, evt=3DNODE_JOINED], init= =3Dtrue, ready=3Dtrue, replied=3Dtrue, added=3Dtrue, initFut=3DGridFutureAd= apter [resFlag=3D2, res=3Dtrue, startTime=3D1443783877022, endTime=3D144378= 3877042, ignoreInterrupts=3Dfalse, lsnr=3Dnull, state=3DDONE], topSnapshot= =3Dnull, lastVer=3DGridCacheVersion [topVer=3D0, nodeOrderDrId=3D0, globalT= ime=3D0, order=3D1443783877002], partReleaseFut=3DGridCompoundFuture [lsnrC= alls=3D3, finished=3Dtrue, rdc=3Dnull, init=3Dtrue, res=3Djava.util.concurr= ent.atomic.AtomicMarkableReference@6fc0daa5, err=3Dnull, done=3Dtrue, cance= lled=3Dfalse, err=3Dnull, futs=3D[true, true, true]], skipPreload=3Dfalse, = clientOnlyExchange=3Dfalse, oldest=3D00b323c7-25f0-42a6-819b-e95d5173a000, = oldestOrder=3D1, evtLatch=3D0, remaining=3D[], super=3DGridFutureAdapter [r= esFlag=3D2, res=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], st= artTime=3D1443783877022, endTime=3D1443783877123, ignoreInterrupts=3Dfalse,= lsnr=3Dnull, state=3DDONE]], p=3D0, affNodesCnt=3D1, ownersCnt=3D2, affNod= es=3D[TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D= [127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, i= ntOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197= 00101-sha1:00000000, isClient=3Dfalse]], owners=3D[TcpDiscoveryNode [id=3D3= 035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], sockAddrs=3D[/127= .0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, lastExchangeTime= =3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, isClient= =3Dfalse], TcpDiscoveryNode [id=3D10ca173e-8703-475c-95a2-21db704e7001, add= rs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47501], discPort=3D47501, order= =3D2, intOrder=3D2, lastExchangeTime=3D1443783876734, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse]], locNode=3DTcpDiscoveryNode = [id=3D00b323c7-25f0-42a6-819b-e95d5173a000, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47500], discPort=3D47500, order=3D1, intOrder=3D1, lastExcha= ngeTime=3D1443783877234, loc=3Dtrue, ver=3D1.5.0#19700101-sha1:00000000, is= Client=3Dfalse]] > [14:04:37,845][WARN ][test-runner][root] Waiting for topology map update = [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest0, cache=3Dignite-atomics-sys= -cache, cacheId=3D689859866, topVer=3DAffinityTopologyVersion [topVer=3D4, = minorTopVer=3D0], topFut=3DGridDhtPartitionsExchangeFuture [dummy=3Dfalse, = forcePreload=3Dfalse, reassign=3Dfalse, discoEvt=3DDiscoveryEvent [evtNode= =3DTcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[12= 7.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intO= rder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197001= 01-sha1:00000000, isClient=3Dfalse], topVer=3D4, nodeId8=3D00b323c7, msg=3D= Node joined: TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, a= ddrs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order= =3D4, intOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse], type=3DNODE_JOINED, tstamp= =3D1443783877022], rcvdIds=3DGridConcurrentHashSet [elements=3D[20cd98aa-ef= 5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8= 703-475c-95a2-21db704e7001]], rmtIds=3D[20cd98aa-ef5a-4eac-b28e-27c6df0b400= 2, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e70= 01], exchId=3DGridDhtPartitionExchangeId [topVer=3DAffinityTopologyVersion = [topVer=3D4, minorTopVer=3D0], nodeId=3D3035d755, evt=3DNODE_JOINED], init= =3Dtrue, ready=3Dtrue, replied=3Dtrue, added=3Dtrue, initFut=3DGridFutureAd= apter [resFlag=3D2, res=3Dtrue, startTime=3D1443783877022, endTime=3D144378= 3877042, ignoreInterrupts=3Dfalse, lsnr=3Dnull, state=3DDONE], topSnapshot= =3Dnull, lastVer=3DGridCacheVersion [topVer=3D0, nodeOrderDrId=3D0, globalT= ime=3D0, order=3D1443783877002], partReleaseFut=3DGridCompoundFuture [lsnrC= alls=3D3, finished=3Dtrue, rdc=3Dnull, init=3Dtrue, res=3Djava.util.concurr= ent.atomic.AtomicMarkableReference@6fc0daa5, err=3Dnull, done=3Dtrue, cance= lled=3Dfalse, err=3Dnull, futs=3D[true, true, true]], skipPreload=3Dfalse, = clientOnlyExchange=3Dfalse, oldest=3D00b323c7-25f0-42a6-819b-e95d5173a000, = oldestOrder=3D1, evtLatch=3D0, remaining=3D[], super=3DGridFutureAdapter [r= esFlag=3D2, res=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], st= artTime=3D1443783877022, endTime=3D1443783877123, ignoreInterrupts=3Dfalse,= lsnr=3Dnull, state=3DDONE]], p=3D0, affNodesCnt=3D1, ownersCnt=3D2, affNod= es=3D[TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D= [127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, i= ntOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197= 00101-sha1:00000000, isClient=3Dfalse]], owners=3D[TcpDiscoveryNode [id=3D3= 035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], sockAddrs=3D[/127= .0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, lastExchangeTime= =3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, isClient= =3Dfalse], TcpDiscoveryNode [id=3D10ca173e-8703-475c-95a2-21db704e7001, add= rs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47501], discPort=3D47501, order= =3D2, intOrder=3D2, lastExchangeTime=3D1443783876734, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse]], locNode=3DTcpDiscoveryNode = [id=3D00b323c7-25f0-42a6-819b-e95d5173a000, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47500], discPort=3D47500, order=3D1, intOrder=3D1, lastExcha= ngeTime=3D1443783877697, loc=3Dtrue, ver=3D1.5.0#19700101-sha1:00000000, is= Client=3Dfalse]] > [14:04:38,849][WARN ][test-runner][root] Waiting for topology map update = [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest0, cache=3Dignite-atomics-sys= -cache, cacheId=3D689859866, topVer=3DAffinityTopologyVersion [topVer=3D4, = minorTopVer=3D0], topFut=3DGridDhtPartitionsExchangeFuture [dummy=3Dfalse, = forcePreload=3Dfalse, reassign=3Dfalse, discoEvt=3DDiscoveryEvent [evtNode= =3DTcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[12= 7.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intO= rder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197001= 01-sha1:00000000, isClient=3Dfalse], topVer=3D4, nodeId8=3D00b323c7, msg=3D= Node joined: TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, a= ddrs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order= =3D4, intOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse], type=3DNODE_JOINED, tstamp= =3D1443783877022], rcvdIds=3DGridConcurrentHashSet [elements=3D[20cd98aa-ef= 5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8= 703-475c-95a2-21db704e7001]], rmtIds=3D[20cd98aa-ef5a-4eac-b28e-27c6df0b400= 2, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e70= 01], exchId=3DGridDhtPartitionExchangeId [topVer=3DAffinityTopologyVersion = [topVer=3D4, minorTopVer=3D0], nodeId=3D3035d755, evt=3DNODE_JOINED], init= =3Dtrue, ready=3Dtrue, replied=3Dtrue, added=3Dtrue, initFut=3DGridFutureAd= apter [resFlag=3D2, res=3Dtrue, startTime=3D1443783877022, endTime=3D144378= 3877042, ignoreInterrupts=3Dfalse, lsnr=3Dnull, state=3DDONE], topSnapshot= =3Dnull, lastVer=3DGridCacheVersion [topVer=3D0, nodeOrderDrId=3D0, globalT= ime=3D0, order=3D1443783877002], partReleaseFut=3DGridCompoundFuture [lsnrC= alls=3D3, finished=3Dtrue, rdc=3Dnull, init=3Dtrue, res=3Djava.util.concurr= ent.atomic.AtomicMarkableReference@6fc0daa5, err=3Dnull, done=3Dtrue, cance= lled=3Dfalse, err=3Dnull, futs=3D[true, true, true]], skipPreload=3Dfalse, = clientOnlyExchange=3Dfalse, oldest=3D00b323c7-25f0-42a6-819b-e95d5173a000, = oldestOrder=3D1, evtLatch=3D0, remaining=3D[], super=3DGridFutureAdapter [r= esFlag=3D2, res=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], st= artTime=3D1443783877022, endTime=3D1443783877123, ignoreInterrupts=3Dfalse,= lsnr=3Dnull, state=3DDONE]], p=3D0, affNodesCnt=3D1, ownersCnt=3D2, affNod= es=3D[TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D= [127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, i= ntOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197= 00101-sha1:00000000, isClient=3Dfalse]], owners=3D[TcpDiscoveryNode [id=3D3= 035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], sockAddrs=3D[/127= .0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, lastExchangeTime= =3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, isClient= =3Dfalse], TcpDiscoveryNode [id=3D10ca173e-8703-475c-95a2-21db704e7001, add= rs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47501], discPort=3D47501, order= =3D2, intOrder=3D2, lastExchangeTime=3D1443783876734, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse]], locNode=3DTcpDiscoveryNode = [id=3D00b323c7-25f0-42a6-819b-e95d5173a000, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47500], discPort=3D47500, order=3D1, intOrder=3D1, lastExcha= ngeTime=3D1443783878715, loc=3Dtrue, ver=3D1.5.0#19700101-sha1:00000000, is= Client=3Dfalse]] > [14:04:39,853][WARN ][test-runner][root] Waiting for topology map update = [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest0, cache=3Dignite-atomics-sys= -cache, cacheId=3D689859866, topVer=3DAffinityTopologyVersion [topVer=3D4, = minorTopVer=3D0], topFut=3DGridDhtPartitionsExchangeFuture [dummy=3Dfalse, = forcePreload=3Dfalse, reassign=3Dfalse, discoEvt=3DDiscoveryEvent [evtNode= =3DTcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[12= 7.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intO= rder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197001= 01-sha1:00000000, isClient=3Dfalse], topVer=3D4, nodeId8=3D00b323c7, msg=3D= Node joined: TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, a= ddrs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order= =3D4, intOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse], type=3DNODE_JOINED, tstamp= =3D1443783877022], rcvdIds=3DGridConcurrentHashSet [elements=3D[20cd98aa-ef= 5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8= 703-475c-95a2-21db704e7001]], rmtIds=3D[20cd98aa-ef5a-4eac-b28e-27c6df0b400= 2, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e70= 01], exchId=3DGridDhtPartitionExchangeId [topVer=3DAffinityTopologyVersion = [topVer=3D4, minorTopVer=3D0], nodeId=3D3035d755, evt=3DNODE_JOINED], init= =3Dtrue, ready=3Dtrue, replied=3Dtrue, added=3Dtrue, initFut=3DGridFutureAd= apter [resFlag=3D2, res=3Dtrue, startTime=3D1443783877022, endTime=3D144378= 3877042, ignoreInterrupts=3Dfalse, lsnr=3Dnull, state=3DDONE], topSnapshot= =3Dnull, lastVer=3DGridCacheVersion [topVer=3D0, nodeOrderDrId=3D0, globalT= ime=3D0, order=3D1443783877002], partReleaseFut=3DGridCompoundFuture [lsnrC= alls=3D3, finished=3Dtrue, rdc=3Dnull, init=3Dtrue, res=3Djava.util.concurr= ent.atomic.AtomicMarkableReference@6fc0daa5, err=3Dnull, done=3Dtrue, cance= lled=3Dfalse, err=3Dnull, futs=3D[true, true, true]], skipPreload=3Dfalse, = clientOnlyExchange=3Dfalse, oldest=3D00b323c7-25f0-42a6-819b-e95d5173a000, = oldestOrder=3D1, evtLatch=3D0, remaining=3D[], super=3DGridFutureAdapter [r= esFlag=3D2, res=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], st= artTime=3D1443783877022, endTime=3D1443783877123, ignoreInterrupts=3Dfalse,= lsnr=3Dnull, state=3DDONE]], p=3D0, affNodesCnt=3D1, ownersCnt=3D2, affNod= es=3D[TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D= [127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, i= ntOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197= 00101-sha1:00000000, isClient=3Dfalse]], owners=3D[TcpDiscoveryNode [id=3D3= 035d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], sockAddrs=3D[/127= .0.0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, lastExchangeTime= =3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, isClient= =3Dfalse], TcpDiscoveryNode [id=3D10ca173e-8703-475c-95a2-21db704e7001, add= rs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47501], discPort=3D47501, order= =3D2, intOrder=3D2, lastExchangeTime=3D1443783876734, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse]], locNode=3DTcpDiscoveryNode = [id=3D00b323c7-25f0-42a6-819b-e95d5173a000, addrs=3D[127.0.0.1], sockAddrs= =3D[/127.0.0.1:47500], discPort=3D47500, order=3D1, intOrder=3D1, lastExcha= ngeTime=3D1443783879713, loc=3Dtrue, ver=3D1.5.0#19700101-sha1:00000000, is= Client=3Dfalse]] > [14:04:40,254][WARN ][test-runner][root] Finished waiting for topology ma= p update [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest0, p=3D0, duration= =3D3012ms] > [14:04:40,257][WARN ][test-runner][root] Waiting for topology map update = [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest1, cache=3Dignite-atomics-sys= -cache, cacheId=3D689859866, topVer=3DAffinityTopologyVersion [topVer=3D4, = minorTopVer=3D0], topFut=3DGridDhtPartitionsExchangeFuture [dummy=3Dfalse, = forcePreload=3Dfalse, reassign=3Dfalse, discoEvt=3DDiscoveryEvent [evtNode= =3DTcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[12= 7.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intO= rder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197001= 01-sha1:00000000, isClient=3Dfalse], topVer=3D4, nodeId8=3D10ca173e, msg=3D= Node joined: TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, a= ddrs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order= =3D4, intOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse], type=3DNODE_JOINED, tstamp= =3D1443783877022], rcvdIds=3DGridConcurrentHashSet [elements=3D[]], rmtIds= =3D[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a= 000, 3035d755-8140-4af5-820d-df1d537b4003], exchId=3DGridDhtPartitionExchan= geId [topVer=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], nodeI= d=3D3035d755, evt=3DNODE_JOINED], init=3Dtrue, ready=3Dtrue, replied=3Dfals= e, added=3Dtrue, initFut=3DGridFutureAdapter [resFlag=3D2, res=3Dtrue, star= tTime=3D1443783877022, endTime=3D1443783877042, ignoreInterrupts=3Dfalse, l= snr=3Dnull, state=3DDONE], topSnapshot=3Dnull, lastVer=3Dnull, partReleaseF= ut=3DGridCompoundFuture [lsnrCalls=3D3, finished=3Dtrue, rdc=3Dnull, init= =3Dtrue, res=3Djava.util.concurrent.atomic.AtomicMarkableReference@2233267a= , err=3Dnull, done=3Dtrue, cancelled=3Dfalse, err=3Dnull, futs=3D[true, tru= e, true]], skipPreload=3Dfalse, clientOnlyExchange=3Dfalse, oldest=3D00b323= c7-25f0-42a6-819b-e95d5173a000, oldestOrder=3D1, evtLatch=3D0, remaining=3D= [20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a000= , 3035d755-8140-4af5-820d-df1d537b4003], super=3DGridFutureAdapter [resFlag= =3D2, res=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], startTim= e=3D1443783877022, endTime=3D1443783877133, ignoreInterrupts=3Dfalse, lsnr= =3Dnull, state=3DDONE]], p=3D14, affNodesCnt=3D1, ownersCnt=3D2, affNodes= =3D[TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[1= 27.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, int= Order=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700= 101-sha1:00000000, isClient=3Dfalse]], owners=3D[TcpDiscoveryNode [id=3D303= 5d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], sockAddrs=3D[/127.0= .0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, lastExchangeTime=3D= 1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, isClient=3D= false], TcpDiscoveryNode [id=3D20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs= =3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47502], discPort=3D47502, order=3D3= , intOrder=3D3, lastExchangeTime=3D1443783876901, loc=3Dfalse, ver=3D1.5.0#= 19700101-sha1:00000000, isClient=3Dfalse]], locNode=3DTcpDiscoveryNode [id= =3D10ca173e-8703-475c-95a2-21db704e7001, addrs=3D[127.0.0.1], sockAddrs=3D[= /127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, lastExchangeT= ime=3D1443783880186, loc=3Dtrue, ver=3D1.5.0#19700101-sha1:00000000, isClie= nt=3Dfalse]] > [14:04:40,859][WARN ][test-runner][root] Waiting for topology map update = [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest1, cache=3Dignite-atomics-sys= -cache, cacheId=3D689859866, topVer=3DAffinityTopologyVersion [topVer=3D4, = minorTopVer=3D0], topFut=3DGridDhtPartitionsExchangeFuture [dummy=3Dfalse, = forcePreload=3Dfalse, reassign=3Dfalse, discoEvt=3DDiscoveryEvent [evtNode= =3DTcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[12= 7.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, intO= rder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#197001= 01-sha1:00000000, isClient=3Dfalse], topVer=3D4, nodeId8=3D10ca173e, msg=3D= Node joined: TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, a= ddrs=3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order= =3D4, intOrder=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.= 5.0#19700101-sha1:00000000, isClient=3Dfalse], type=3DNODE_JOINED, tstamp= =3D1443783877022], rcvdIds=3DGridConcurrentHashSet [elements=3D[]], rmtIds= =3D[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a= 000, 3035d755-8140-4af5-820d-df1d537b4003], exchId=3DGridDhtPartitionExchan= geId [topVer=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], nodeI= d=3D3035d755, evt=3DNODE_JOINED], init=3Dtrue, ready=3Dtrue, replied=3Dfals= e, added=3Dtrue, initFut=3DGridFutureAdapter [resFlag=3D2, res=3Dtrue, star= tTime=3D1443783877022, endTime=3D1443783877042, ignoreInterrupts=3Dfalse, l= snr=3Dnull, state=3DDONE], topSnapshot=3Dnull, lastVer=3Dnull, partReleaseF= ut=3DGridCompoundFuture [lsnrCalls=3D3, finished=3Dtrue, rdc=3Dnull, init= =3Dtrue, res=3Djava.util.concurrent.atomic.AtomicMarkableReference@2233267a= , err=3Dnull, done=3Dtrue, cancelled=3Dfalse, err=3Dnull, futs=3D[true, tru= e, true]], skipPreload=3Dfalse, clientOnlyExchange=3Dfalse, oldest=3D00b323= c7-25f0-42a6-819b-e95d5173a000, oldestOrder=3D1, evtLatch=3D0, remaining=3D= [20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a000= , 3035d755-8140-4af5-820d-df1d537b4003], super=3DGridFutureAdapter [resFlag= =3D2, res=3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D0], startTim= e=3D1443783877022, endTime=3D1443783877133, ignoreInterrupts=3Dfalse, lsnr= =3Dnull, state=3DDONE]], p=3D14, affNodesCnt=3D1, ownersCnt=3D2, affNodes= =3D[TcpDiscoveryNode [id=3D3035d755-8140-4af5-820d-df1d537b4003, addrs=3D[1= 27.0.0.1], sockAddrs=3D[/127.0.0.1:47503], discPort=3D47503, order=3D4, int= Order=3D4, lastExchangeTime=3D1443783877012, loc=3Dfalse, ver=3D1.5.0#19700= 101-sha1:00000000, isClient=3Dfalse]], owners=3D[TcpDiscoveryNode [id=3D303= 5d755-8140-4af5-820d-df1d537b4003, addrs=3D[127.0.0.1], sockAddrs=3D[/127.0= .0.1:47503], discPort=3D47503, order=3D4, intOrder=3D4, lastExchangeTime=3D= 1443783877012, loc=3Dfalse, ver=3D1.5.0#19700101-sha1:00000000, isClient=3D= false], TcpDiscoveryNode [id=3D20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs= =3D[127.0.0.1], sockAddrs=3D[/127.0.0.1:47502], discPort=3D47502, order=3D3= , intOrder=3D3, lastExchangeTime=3D1443783876901, loc=3Dfalse, ver=3D1.5.0#= 19700101-sha1:00000000, isClient=3Dfalse]], locNode=3DTcpDiscoveryNode [id= =3D10ca173e-8703-475c-95a2-21db704e7001, addrs=3D[127.0.0.1], sockAddrs=3D[= /127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, lastExchangeT= ime=3D1443783880730, loc=3Dtrue, ver=3D1.5.0#19700101-sha1:00000000, isClie= nt=3Dfalse]] > [14:04:41,862][WARN ][test-runner][root] Finished waiting for topology ma= p update [grid=3Ddht.GridNearCacheTxNodeFailureSelfTest1, p=3D14, duration= =3D1605ms] > [14:04:42,877][ERROR][test-runner][IgniteTxHandler] Failed to prepare DHT= transaction: GridDhtTxLocal [nearNodeId=3D00b323c7-25f0-42a6-819b-e95d5173= a000, nearFutId=3D2eda1823051-353c0984-390a-4992-bb7a-2cd66298ba94, nearMin= iId=3D3eda1823051-353c0984-390a-4992-bb7a-2cd66298ba94, nearFinFutId=3Dnull= , nearFinMiniId=3Dnull, nearXidVer=3DGridCacheVersion [topVer=3D55263880, n= odeOrderDrId=3D1, globalTime=3D1443783881869, order=3D1443783876675], super= =3DGridDhtTxLocalAdapter [dhtThreadId=3D122646, needsCompletedVers=3Dtrue, = nearOnOriginatingNode=3Dtrue, nearNodes=3D[], dhtNodes=3D[3035d755-8140-4af= 5-820d-df1d537b4003], explicitLock=3Dfalse, super=3DIgniteTxLocalAdapter [t= xMap=3D{IgniteTxKey [key=3DKeyCacheObjectImpl [val=3D2, hasValBytes=3Dtrue]= , cacheId=3D1]=3DIgniteTxEntry [key=3DKeyCacheObjectImpl [val=3D2, hasValBy= tes=3Dtrue], cacheId=3D1, txKey=3DIgniteTxKey [key=3DKeyCacheObjectImpl [va= l=3D2, hasValBytes=3Dtrue], cacheId=3D1], val=3D[op=3DCREATE, val=3DCacheOb= jectImpl [val=3D2, hasValBytes=3Dtrue]], prevVal=3D[op=3DNOOP, val=3Dnull],= entryProcessorsCol=3Dnull, entryProcessorCalcVal=3Dnull, ttl=3D-1, conflic= tExpireTime=3D-1, conflictVer=3Dnull, explicitVer=3Dnull, dhtVer=3Dnull, fi= lters=3D[], filtersPassed=3Dfalse, filtersSet=3Dfalse, entry=3DGridDhtCache= Entry [rdrs=3D[ReaderId [nodeId=3D00b323c7-25f0-42a6-819b-e95d5173a000, msg= Id=3D14714753, txFut=3Dnull]], locPart=3DGridDhtLocalPartition [id=3D2, map= PubSize=3D1, rmvQueue=3DGridCircularBuffer [sizeMask=3D31, idxGen=3D0], sta= te=3DOWNING, reservations=3D0, empty=3Dfalse, createTime=3D10/02/2015 14:04= :36, mapPubSize=3D1], super=3DGridDistributedCacheEntry [super=3DGridCacheM= apEntry [key=3DKeyCacheObjectImpl [val=3D2, hasValBytes=3Dtrue], val=3Dnull= , startVer=3D1443783877004, ver=3DGridCacheVersion [topVer=3D55263880, node= OrderDrId=3D2, globalTime=3D1443783881874, order=3D1443783877004], hash=3D8= 21347078, extras=3DGridCacheMvccEntryExtras [mvcc=3DGridCacheMvcc [locs=3D[= GridCacheMvccCandidate [nodeId=3D10ca173e-8703-475c-95a2-21db704e7001, ver= =3DGridCacheVersion [topVer=3D55263880, nodeOrderDrId=3D2, globalTime=3D144= 3783881874, order=3D1443783877003], timeout=3D0, ts=3D1443783881869, thread= Id=3D122879, id=3D1313281, topVer=3DAffinityTopologyVersion [topVer=3D4, mi= norTopVer=3D0], reentry=3Dnull, otherNodeId=3D00b323c7-25f0-42a6-819b-e95d5= 173a000, otherVer=3DGridCacheVersion [topVer=3D55263880, nodeOrderDrId=3D1,= globalTime=3D1443783881869, order=3D1443783876675], mappedDhtNodes=3Dnull,= mappedNearNodes=3Dnull, ownerVer=3Dnull, key=3DKeyCacheObjectImpl [val=3D2= , hasValBytes=3Dtrue], masks=3Dlocal=3D1|owner=3D1|ready=3D1|reentry=3D0|us= ed=3D0|tx=3D1|single_implicit=3D1|dht_local=3D1|near_local=3D0|removed=3D0,= prevVer=3Dnull, nextVer=3Dnull]], rmts=3Dnull]], flags=3D0]]], prepared=3D= true, locked=3Dfalse, nodeId=3Dnull, locMapped=3Dfalse, expiryPlc=3Dnull, t= ransferExpiryPlc=3Dfalse, flags=3D0, xidVer=3Dnull]}, completedBase=3Dnull,= sndTransformedVals=3Dfalse, super=3DIgniteTxAdapter [xidVer=3DGridCacheVer= sion [topVer=3D55263880, nodeOrderDrId=3D2, globalTime=3D1443783881874, ord= er=3D1443783877003], writeVer=3DGridCacheVersion [topVer=3D55263880, nodeOr= derDrId=3D2, globalTime=3D1443783881874, order=3D1443783877005], implicit= =3Dtrue, implicitSingle=3Dtrue, loc=3Dtrue, threadId=3D122879, startTime=3D= 1443783881869, nodeId=3D10ca173e-8703-475c-95a2-21db704e7001, startVer=3DGr= idCacheVersion [topVer=3D55263880, nodeOrderDrId=3D2, globalTime=3D14437838= 81874, order=3D1443783877003], endVer=3Dnull, isolation=3DREAD_COMMITTED, c= oncurrency=3DOPTIMISTIC, timeout=3D0, sysInvalidate=3Dfalse, sys=3Dfalse, p= lc=3D2, commitVer=3Dnull, finalizing=3DNONE, preparing=3Dfalse, invalidPart= s=3D{}, state=3DMARKED_ROLLBACK, timedOut=3Dfalse, topVer=3DAffinityTopolog= yVersion [topVer=3D4, minorTopVer=3D0], duration=3D997ms, onePhaseCommit=3D= true], size=3D1]]] > class org.apache.ignite.IgniteCheckedException: Operation has been cancel= led (node is stopping). > at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.s= topError(GridCacheMvccManager.java:373) > at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.o= nStop(GridCacheMvccManager.java:334) > at org.apache.ignite.internal.processors.cache.GridCacheProcessor.can= celFutures(GridCacheProcessor.java:3167) > at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onK= ernalStop(GridCacheProcessor.java:968) > at org.apache.ignite.internal.IgniteKernal.stop0(IgniteKernal.java:18= 20) > at org.apache.ignite.internal.IgniteKernal.stop(IgniteKernal.java:176= 6) > at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop0(Ig= nitionEx.java:2133) > at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop(Ign= itionEx.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(G= ridAbstractTest.java:801) > at org.apache.ignite.testframework.junits.GridAbstractTest.stopGrid(G= ridAbstractTest.java:1059) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.checkPrimaryNodeFailureBackupCommit(GridCacheTxNod= eFailureSelfTest.java:292) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit(GridCac= heTxNodeFailureSelfTest.java:161) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorIm= pl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAc= cessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at junit.framework.TestCase.runTest(TestCase.java:176) > at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInt= ernal(GridAbstractTest.java:1665) > at org.apache.ignite.testframework.junits.GridAbstractTest.access$000= (GridAbstractTest.java:111) > at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(Grid= AbstractTest.java:1603) > [14:04:42,989][WARN ][ignite-#90371%sys-dht.GridNearCacheTxNodeFailureSel= fTest2%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Connect ti= med out (consider increasing 'failureDetectionTimeout' configuration proper= ty) [addr=3D/127.0.0.1:47100, failureDetectionTimeout=3D10000] > [14:04:42,994][WARN ][ignite-#90371%sys-dht.GridNearCacheTxNodeFailureSel= fTest2%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Failed to = connect to a remote node (make sure that destination node is alive and oper= ating system firewall is disabled on local and remote hosts) [addrs=3D[/127= .0.0.1:47100]] > [14:04:43,013][WARN ][exchange-worker-#90468%dht.GridNearCacheTxNodeFailu= reSelfTest3%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Conne= ct timed out (consider increasing 'failureDetectionTimeout' configuration p= roperty) [addr=3D/127.0.0.1:47102, failureDetectionTimeout=3D10000] > [14:04:43,013][WARN ][exchange-worker-#90468%dht.GridNearCacheTxNodeFailu= reSelfTest3%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Faile= d 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=3D= [/127.0.0.1:47102]] > [14:04:43,039][ERROR][main][root] Test failed. > junit.framework.AssertionFailedError: near=3DGridNearCacheEntry [topVer= =3D4, dhtVer=3DGridCacheVersion [topVer=3D55263880, nodeOrderDrId=3D1, glob= alTime=3D1443783881869, order=3D1443783876675], part=3D2, super=3DGridDistr= ibutedCacheEntry [super=3DGridCacheMapEntry [key=3DKeyCacheObjectImpl [val= =3D2, hasValBytes=3Dtrue], val=3DCacheObjectImpl [val=3D2, hasValBytes=3Dtr= ue], startVer=3D1443783876676, ver=3DGridCacheVersion [topVer=3D55263880, n= odeOrderDrId=3D1, globalTime=3D1443783881869, order=3D1443783876675], hash= =3D821347078, extras=3Dnull, flags=3D0]]] > at junit.framework.Assert.fail(Assert.java:57) > at junit.framework.Assert.assertTrue(Assert.java:22) > at junit.framework.Assert.assertNull(Assert.java:277) > at junit.framework.TestCase.assertNull(TestCase.java:447) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.dataCheck(GridCacheTxNodeFailureSelfTest.java:342) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.checkPrimaryNodeFailureBackupCommit(GridCacheTxNod= eFailureSelfTest.java:302) > at org.apache.ignite.internal.processors.cache.distributed.dht.GridCa= cheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit(GridCac= heTxNodeFailureSelfTest.java:161) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorIm= pl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAc= cessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at junit.framework.TestCase.runTest(TestCase.java:176) > at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInt= ernal(GridAbstractTest.java:1665) > at org.apache.ignite.testframework.junits.GridAbstractTest.access$000= (GridAbstractTest.java:111) > at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(Grid= AbstractTest.java:1603) > {noformat} -- This message was sent by Atlassian JIRA (v6.3.15#6346)