You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Vladimir Ozerov (JIRA)" <ji...@apache.org> on 2017/07/10 07:02:00 UTC
[jira] [Updated] (IGNITE-1611) [Test Failed]
GridNearCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit
[ https://issues.apache.org/jira/browse/IGNITE-1611?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Vladimir Ozerov updated IGNITE-1611:
------------------------------------
Fix Version/s: (was: 2.1)
2.2
> [Test Failed] GridNearCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit
> -------------------------------------------------------------------------------------------
>
> 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.2
>
>
> There is no guarantee that test failure will be produced each test run, please analyze logs in case of reproduction problem:
> {noformat}
> junit.framework.AssertionFailedError: near=GridNearCacheEntry [topVer=4, dhtVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=1, globalTime=1443783881869, order=1443783876675], part=2, super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], val=CacheObjectImpl [val=2, hasValBytes=true], startVer=1443783876676, ver=GridCacheVersion [topVer=55263880, nodeOrderDrId=1, globalTime=1443783881869, order=1443783876675], hash=821347078, extras=null, flags=0]]]
> 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.GridCacheTxNodeFailureSelfTest.dataCheck(GridCacheTxNodeFailureSelfTest.java:342)
> at org.apache.ignite.internal.processors.cache.distributed.dht.GridCacheTxNodeFailureSelfTest.checkPrimaryNodeFailureBackupCommit(GridCacheTxNodeFailureSelfTest.java:302)
> at org.apache.ignite.internal.processors.cache.distributed.dht.GridCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit(GridCacheTxNodeFailureSelfTest.java:161)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at junit.framework.TestCase.runTest(TestCase.java:176)
> at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665)
> at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111)
> at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603)
> ------- Stdout: -------
> [14:04:36,650][INFO ][main][root] >>> Starting test: testPrimaryNodeFailureBackupCommitImplicit <<<
> [14:04:36,661][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]
> >>> __________ ________________
> >>> / _/ ___/ |/ / _/_ __/ __/
> >>> _/ // (7 7 // / / / / _/
> >>> /___/\___/_/|_/___/ /_/ /___/
> >>>
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>>
> >>> Ignite documentation: http://ignite.apache.org
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Config URL: n/a
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Daemon mode: off
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] OS: Linux 2.6.32-38-generic amd64
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] OS user: teamcity
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Language runtime: Java Platform API Specification ver. 1.7
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] VM total memory: 2.7GB
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] IGNITE_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.13.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-3, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=3390, -Dbuild.vcs.number=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.1=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=651, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=547276, -Dteamcity.auth.password=w2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=TeamCityBuildId=547276, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.build.id=547276, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache Failover, -Dteamcity.buildType.id=Ignite_DataGridFailover, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [14:04:36,664][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
> [14:04:36,667][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46e450b13/libs/licenses
> [14:04:36,671][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Non-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2
> [14:04:36,671][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Enabled local MACs: C860006858B6
> [14:04:36,671][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
> [14:04:36,671][INFO ][test-runner][IgnitePluginProcessor] ^-- None
> [14:04:36,671][INFO ][test-runner][IgnitePluginProcessor]
> [14:04:36,672][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] IPC shared memory server endpoint started [port=48100, tokDir=/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/00b323c7-25f0-42a6-819b-e95d5173a000-5591]
> [14:04:36,672][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48100, locHost=/127.0.0.1]
> [14:04:36,673][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound to TCP port [port=47100, locHost=/127.0.0.1]
> [14:04:36,679][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Security status [authentication=off, tls/ssl=off]
> [14:04:36,688][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47500, localHost=/127.0.0.1]
> [14:04:36,693][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [14:04:36,694][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [14:04:36,697][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [14:04:36,699][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [14:04:36,707][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [14:04:36,707][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [14:04:36,707][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [14:04:36,707][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [14:04:36,708][INFO ][preloader-demand-worker-#90271%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [14:04:36,708][INFO ][preloader-demand-worker-#90275%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=0 ms]
> [14:04:36,712][INFO ][preloader-demand-worker-#90279%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
> [14:04:36,712][INFO ][preloader-demand-worker-#90285%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Performance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest0' (fix if possible)
> [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [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 ATOMIC)
> [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]
> [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [14:04:36,717][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]
> [14:04:36,718][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]
> >>> +------------------------------------------+
> >>> 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=00B323C7-25F0-42A6-819B-E95D5173A000, order=1, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47100 TCP:47500 TCP:48100
> [14:04:36,718][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=1, servers=1, clients=0, CPUs=4, heap=2.7GB]
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]
> >>> __________ ________________
> >>> / _/ ___/ |/ / _/_ __/ __/
> >>> _/ // (7 7 // / / / / _/
> >>> /___/\___/_/|_/___/ /_/ /___/
> >>>
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>>
> >>> Ignite documentation: http://ignite.apache.org
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Config URL: n/a
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Daemon mode: off
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] OS: Linux 2.6.32-38-generic amd64
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] OS user: teamcity
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Language runtime: Java Platform API Specification ver. 1.7
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] VM total memory: 2.7GB
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] IGNITE_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.13.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-3, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=3390, -Dbuild.vcs.number=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.1=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=651, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=547276, -Dteamcity.auth.password=w2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=TeamCityBuildId=547276, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.build.id=547276, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache Failover, -Dteamcity.buildType.id=Ignite_DataGridFailover, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
> [14:04:36,723][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46e450b13/libs/licenses
> [14:04:36,725][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Non-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2
> [14:04:36,725][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Enabled local MACs: C860006858B6
> [14:04:36,725][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
> [14:04:36,725][INFO ][test-runner][IgnitePluginProcessor] ^-- None
> [14:04:36,725][INFO ][test-runner][IgnitePluginProcessor]
> [14:04:36,726][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] IPC shared memory server endpoint started [port=48101, tokDir=/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/10ca173e-8703-475c-95a2-21db704e7001-5591]
> [14:04:36,726][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48101, locHost=/127.0.0.1]
> [14:04:36,726][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound to TCP port [port=47101, locHost=/127.0.0.1]
> [14:04:36,729][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Security status [authentication=off, tls/ssl=off]
> [14:04:36,732][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47501, localHost=/127.0.0.1]
> [14:04:36,745][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876734, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:36,745][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=2, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [14:04:36,746][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [14:04:36,749][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [14:04:36,852][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [14:04:36,853][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [14:04:36,866][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [14:04:36,866][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [14:04:36,866][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [14:04:36,866][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [14:04:36,870][INFO ][preloader-demand-worker-#90335%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [14:04:36,871][INFO ][preloader-demand-worker-#90350%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=10 ms]
> [14:04:36,872][INFO ][preloader-demand-worker-#90340%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=10 ms]
> [14:04:36,873][INFO ][preloader-demand-worker-#90344%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=10 ms]
> [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Performance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest1' (fix if possible)
> [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [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 ATOMIC)
> [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]
> [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [14:04:36,878][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]
> [14:04:36,879][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]
> >>> +------------------------------------------+
> >>> 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=10CA173E-8703-475C-95A2-21DB704E7001, order=2, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47101 TCP:47501 TCP:48101
> [14:04:36,879][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=2, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [14:04:36,885][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]
> >>> __________ ________________
> >>> / _/ ___/ |/ / _/_ __/ __/
> >>> _/ // (7 7 // / / / / _/
> >>> /___/\___/_/|_/___/ /_/ /___/
> >>>
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>>
> >>> Ignite documentation: http://ignite.apache.org
> [14:04:36,885][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Config URL: n/a
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Daemon mode: off
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] OS: Linux 2.6.32-38-generic amd64
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] OS user: teamcity
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Language runtime: Java Platform API Specification ver. 1.7
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] VM total memory: 2.7GB
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] IGNITE_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.13.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-3, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=3390, -Dbuild.vcs.number=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.1=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=651, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=547276, -Dteamcity.auth.password=w2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=TeamCityBuildId=547276, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.build.id=547276, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache Failover, -Dteamcity.buildType.id=Ignite_DataGridFailover, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
> [14:04:36,886][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46e450b13/libs/licenses
> [14:04:36,888][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Non-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2
> [14:04:36,888][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Enabled local MACs: C860006858B6
> [14:04:36,888][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
> [14:04:36,888][INFO ][test-runner][IgnitePluginProcessor] ^-- None
> [14:04:36,888][INFO ][test-runner][IgnitePluginProcessor]
> [14:04:36,890][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] IPC shared memory server endpoint started [port=48102, tokDir=/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/20cd98aa-ef5a-4eac-b28e-27c6df0b4002-5591]
> [14:04:36,890][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48102, locHost=/127.0.0.1]
> [14:04:36,891][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound to TCP port [port=47102, locHost=/127.0.0.1]
> [14:04:36,896][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Security status [authentication=off, tls/ssl=off]
> [14:04:36,899][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47502, localHost=/127.0.0.1]
> [14:04:36,909][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1443783876901, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:36,910][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1443783876901, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:36,910][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [14:04:36,910][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [14:04:36,912][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [14:04:36,914][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [14:04:36,918][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [14:04:36,919][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [14:04:36,932][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [14:04:36,932][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [14:04:36,932][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [14:04:36,932][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [14:04:36,936][INFO ][preloader-demand-worker-#90403%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=0 ms]
> [14:04:36,939][INFO ][preloader-demand-worker-#90418%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=0 ms]
> [14:04:36,939][INFO ][preloader-demand-worker-#90412%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=0 ms]
> [14:04:36,978][INFO ][preloader-demand-worker-#90407%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=41 ms]
> [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Performance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest2' (fix if possible)
> [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [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 ATOMIC)
> [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]
> [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [14:04:36,987][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]
> [14:04:36,988][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]
> >>> +------------------------------------------+
> >>> 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=20CD98AA-EF5A-4EAC-B28E-27C6DF0B4002, order=3, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47102 TCP:47502 TCP:48102
> [14:04:36,988][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=3, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]
> >>> __________ ________________
> >>> / _/ ___/ |/ / _/_ __/ __/
> >>> _/ // (7 7 // / / / / _/
> >>> /___/\___/_/|_/___/ /_/ /___/
> >>>
> >>> ver. 1.5.0-SNAPSHOT#19700101-sha1:DEV
> >>> 2015 Copyright(C) Apache Software Foundation
> >>>
> >>> Ignite documentation: http://ignite.apache.org
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Config URL: n/a
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Daemon mode: off
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] OS: Linux 2.6.32-38-generic amd64
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] OS user: teamcity
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Language runtime: Java Platform API Specification ver. 1.7
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] VM information: Java(TM) SE Runtime Environment 1.7.0_80-b15 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 24.80-b11
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] VM total memory: 2.7GB
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Remote Management [restart: off, REST: off, JMX (remote: off)]
> [14:04:36,996][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] IGNITE_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13
> [14:04:36,997][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] VM arguments: [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.13.1.2, -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-3, -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, -Dbuild.number=3390, -Dbuild.vcs.number=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.1=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dbuild.vcs.number.ApacheIgniteMirrorOnGitHub=7b54cbd7499cd498b04e821dfa3b572bd94debec, -Dclassworlds.conf=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dcom.jetbrains.maven.watcher.report.file=/usr/local/TeamCityAgent/temp/buildTmp/maven-build-info.xml, -Djava.io.tmpdir=/usr/local/TeamCityAgent/temp/buildTmp, -Dmaven.home=/usr/local/TeamCityAgent/tools/maven3, -Dmaven.repo.local=/home/teamcity/.m2/repository, -Dteamcity.agent.cpuBenchmark=651, -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=547276, -Dteamcity.auth.password=w2hE3gDUVmGePWVIkHEnpJdjkGh1RgTm, -Dteamcity.auth.userId=TeamCityBuildId=547276, -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles8662756275591896227.txt, -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.build.id=547276, -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build5726589901755458521.properties, -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -Dteamcity.buildConfName=Ignite Cache Failover, -Dteamcity.buildType.id=Ignite_DataGridFailover, -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config8728884784532753636.properties, -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName=Ignite Tests, -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner2314285968189682922.properties, -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst8419038600795387618.txt, -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=1024m, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -XX:+AggressiveOpts, -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/871ff4a46e450b13, -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, -DIGNITE_QUIET=false]
> [14:04:36,997][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Configured caches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-atomics-sys-cache', 'default']
> [14:04:36,997][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] 3-rd party licenses can be found at: /usr/local/TeamCityAgent/work/871ff4a46e450b13/libs/licenses
> [14:04:37,000][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Non-loopback local IPs: 192.168.2.13, fe80:0:0:0:ca60:ff:fe68:58b6%2
> [14:04:37,000][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Enabled local MACs: C860006858B6
> [14:04:37,000][INFO ][test-runner][IgnitePluginProcessor] Configured plugins:
> [14:04:37,000][INFO ][test-runner][IgnitePluginProcessor] ^-- None
> [14:04:37,000][INFO ][test-runner][IgnitePluginProcessor]
> [14:04:37,002][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] IPC shared memory server endpoint started [port=48103, tokDir=/usr/local/TeamCityAgent/temp/buildTmp/ignite/work/ipc/shmem/3035d755-8140-4af5-820d-df1d537b4003-5591]
> [14:04:37,002][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound shared memory communication to TCP port [port=48103, locHost=/127.0.0.1]
> [14:04:37,003][INFO ][test-runner][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Successfully bound to TCP port [port=47103, locHost=/127.0.0.1]
> [14:04:37,009][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Security status [authentication=off, tls/ssl=off]
> [14:04:37,015][INFO ][test-runner][TcpDiscoverySpi] Successfully bound to TCP port [port=47503, localHost=/127.0.0.1]
> [14:04:37,030][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:37,031][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:37,031][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=4, heap=2.7GB]
> [14:04:37,031][INFO ][disco-event-worker-#90328%dht.GridNearCacheTxNodeFailureSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=4, heap=2.7GB]
> [14:04:37,031][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDiscoveryManager] Added new node to topology: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:37,031][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=4, heap=2.7GB]
> [14:04:37,037][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-marshaller-sys-cache, mode=REPLICATED]
> [14:04:37,039][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-atomics-sys-cache, mode=PARTITIONED]
> [14:04:37,043][INFO ][test-runner][GridCacheProcessor] Started cache [name=default, mode=PARTITIONED]
> [14:04:37,045][INFO ][test-runner][GridCacheProcessor] Started cache [name=ignite-sys-cache, mode=REPLICATED]
> [14:04:37,133][INFO ][test-runner][GridDhtPreloader] <ignite-marshaller-sys-cache> Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache
> [14:04:37,133][INFO ][test-runner][GridDhtPreloader] <ignite-atomics-sys-cache> Starting rebalancing in SYNC mode: ignite-atomics-sys-cache
> [14:04:37,133][INFO ][test-runner][GridDhtPreloader] <default> Starting rebalancing in ASYNC mode: null
> [14:04:37,133][INFO ][test-runner][GridDhtPreloader] <ignite-sys-cache> Starting rebalancing in SYNC mode: ignite-sys-cache
> [14:04:37,188][INFO ][preloader-demand-worker-#90473%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDhtPreloader] <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-marshaller-sys-cache, time=50 ms]
> [14:04:37,190][INFO ][preloader-demand-worker-#90482%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDhtPreloader] <default> Completed rebalancing in ASYNC mode [cache=null, time=50 ms]
> [14:04:37,191][INFO ][preloader-demand-worker-#90486%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDhtPreloader] <ignite-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-sys-cache, time=50 ms]
> [14:04:37,230][INFO ][preloader-demand-worker-#90478%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDhtPreloader] <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode [cache=ignite-atomics-sys-cache, time=91 ms]
> [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Performance suggestions for grid 'dht.GridNearCacheTxNodeFailureSelfTest3' (fix if possible)
> [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
> [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 ATOMIC)
> [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]
> [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat}
> [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]
> [14:04:37,241][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]
> >>> +------------------------------------------+
> >>> 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=3035D755-8140-4AF5-820D-DF1D537B4003, order=4, clientMode=false]
> >>> Local node addresses: [127.0.0.1]
> >>> Local ports: TCP:47103 TCP:47503 TCP:48103
> [14:04:37,241][INFO ][test-runner][GridDiscoveryManager] Topology snapshot [ver=4, servers=4, clients=0, CPUs=4, heap=2.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=dht.GridNearCacheTxNodeFailureSelfTest1, id=10ca173e-8703-475c-95a2-21db704e7001]
> [14:04:42,884][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876734, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:42,885][INFO ][disco-event-worker-#90265%dht.GridNearCacheTxNodeFailureSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=5, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [14:04:42,885][INFO ][test-runner][GridCacheProcessor] Stopped cache: null
> [14:04:42,886][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [14:04:42,886][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [14:04:42,886][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443783876861, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=82e91823051-10ca173e-8703-475c-95a2-21db704e7001, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [14:04:42,886][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [14:04:42,893][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876901, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:42,893][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=5, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [14:04:42,894][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783877022, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:42,894][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=5, servers=3, clients=0, CPUs=4, heap=2.7GB]
> [14:04:42,900][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest1]
> >>> +-----------------------------------------------------+
> >>> 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=dht.GridNearCacheTxNodeFailureSelfTest0, id=00b323c7-25f0-42a6-819b-e95d5173a000]
> [14:04:42,910][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=00b323c7-25f0-42a6-819b-e95d5173a000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1443783876901, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:42,910][INFO ][disco-event-worker-#90396%dht.GridNearCacheTxNodeFailureSelfTest2%][GridDiscoveryManager] Topology snapshot [ver=6, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [14:04:42,910][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=00b323c7-25f0-42a6-819b-e95d5173a000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1443783877022, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:42,910][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=6, servers=2, clients=0, CPUs=4, heap=2.7GB]
> [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: null
> [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [14:04:42,911][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443783876861, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=62e91823051-00b323c7-25f0-42a6-819b-e95d5173a000, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap, pendingUndeploy=false, undeployed=true, usage=0]
> [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class java.lang.Integer
> [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [14:04:42,911][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [14:04:42,988][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest0]
> >>> +-----------------------------------------------------+
> >>> 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=dht.GridNearCacheTxNodeFailureSelfTest2, id=20cd98aa-ef5a-4eac-b28e-27c6df0b4002]
> [14:04:43,004][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDiscoveryManager] Node left topology: TcpDiscoveryNode [id=20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1443783877022, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]
> [14:04:43,004][INFO ][disco-event-worker-#90466%dht.GridNearCacheTxNodeFailureSelfTest3%][GridDiscoveryManager] Topology snapshot [ver=7, servers=1, clients=0, CPUs=4, heap=2.7GB]
> [14:04:43,004][INFO ][test-runner][GridCacheProcessor] Stopped cache: null
> [14:04:43,005][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [14:04:43,005][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [14:04:43,005][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443783876921, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=6d2a1823051-20cd98aa-ef5a-4eac-b28e-27c6df0b4002, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.GridTopic$T7
> [14:04:43,005][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [14:04:43,014][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest2]
> >>> +-----------------------------------------------------+
> >>> 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=dht.GridNearCacheTxNodeFailureSelfTest3, id=3035d755-8140-4af5-820d-df1d537b4003]
> [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: null
> [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-marshaller-sys-cache
> [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-sys-cache
> [14:04:43,025][INFO ][test-runner][GridCacheProcessor] Stopped cache: ignite-atomics-sys-cache
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Removed undeployed class: GridDeployment [ts=1443783877123, depMode=SHARED, clsLdr=IsolatedClassLoader{roleName='test'}, clsLdrId=df7a1823051-3035d755-8140-4af5-820d-df1d537b4003, userVer=0, loc=true, sampleClsName=org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap, pendingUndeploy=false, undeployed=true, usage=0]
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionFullMap
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.util.GridLeanMap$Map3
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionMap
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager$ResendTimeoutObject$1
> [14:04:43,025][INFO ][test-runner][GridDeploymentLocalStore] Class locally undeployed: class org.apache.ignite.internal.processors.task.GridTaskProcessor$TaskDiscoveryListener$1
> [14:04:43,038][INFO ][test-runner][GridNearCacheTxNodeFailureSelfTest3]
> >>> +-----------------------------------------------------+
> >>> 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: testPrimaryNodeFailureBackupCommitImplicit in 6393 ms <<<
> ------- Stderr: -------
> [14:04:36,664][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest0] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [14:04:36,677][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [14:04:36,678][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [14:04:36,723][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest1] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [14:04:36,729][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [14:04:36,729][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [14:04:36,886][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [14:04:36,895][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [14:04:36,896][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [14:04:36,911][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest2] Attempting to start more nodes than physical RAM available on current host (this can cause significant slowdown)
> [14:04:36,997][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
> [14:04:37,008][WARN ][test-runner][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
> [14:04:37,008][WARN ][test-runner][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
> [14:04:37,035][WARN ][test-runner][GridNearCacheTxNodeFailureSelfTest3] Attempting 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=dht.GridNearCacheTxNodeFailureSelfTest0, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=00b323c7, msg=Node joined: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1443783877022], rcvdIds=GridConcurrentHashSet [elements=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001]], rmtIds=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=3035d755, evt=NODE_JOINED], init=true, ready=true, replied=true, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1443783877022, endTime=1443783877042, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=GridCacheVersion [topVer=0, nodeOrderDrId=0, globalTime=0, order=1443783877002], partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@6fc0daa5, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, oldest=00b323c7-25f0-42a6-819b-e95d5173a000, oldestOrder=1, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1443783877022, endTime=1443783877123, ignoreInterrupts=false, lsnr=null, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876734, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=00b323c7-25f0-42a6-819b-e95d5173a000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1443783877234, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [14:04:37,845][WARN ][test-runner][root] Waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest0, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=00b323c7, msg=Node joined: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1443783877022], rcvdIds=GridConcurrentHashSet [elements=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001]], rmtIds=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=3035d755, evt=NODE_JOINED], init=true, ready=true, replied=true, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1443783877022, endTime=1443783877042, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=GridCacheVersion [topVer=0, nodeOrderDrId=0, globalTime=0, order=1443783877002], partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@6fc0daa5, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, oldest=00b323c7-25f0-42a6-819b-e95d5173a000, oldestOrder=1, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1443783877022, endTime=1443783877123, ignoreInterrupts=false, lsnr=null, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876734, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=00b323c7-25f0-42a6-819b-e95d5173a000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1443783877697, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [14:04:38,849][WARN ][test-runner][root] Waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest0, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=00b323c7, msg=Node joined: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1443783877022], rcvdIds=GridConcurrentHashSet [elements=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001]], rmtIds=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=3035d755, evt=NODE_JOINED], init=true, ready=true, replied=true, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1443783877022, endTime=1443783877042, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=GridCacheVersion [topVer=0, nodeOrderDrId=0, globalTime=0, order=1443783877002], partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@6fc0daa5, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, oldest=00b323c7-25f0-42a6-819b-e95d5173a000, oldestOrder=1, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1443783877022, endTime=1443783877123, ignoreInterrupts=false, lsnr=null, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876734, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=00b323c7-25f0-42a6-819b-e95d5173a000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1443783878715, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [14:04:39,853][WARN ][test-runner][root] Waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest0, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=00b323c7, msg=Node joined: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1443783877022], rcvdIds=GridConcurrentHashSet [elements=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001]], rmtIds=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 3035d755-8140-4af5-820d-df1d537b4003, 10ca173e-8703-475c-95a2-21db704e7001], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=3035d755, evt=NODE_JOINED], init=true, ready=true, replied=true, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1443783877022, endTime=1443783877042, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=GridCacheVersion [topVer=0, nodeOrderDrId=0, globalTime=0, order=1443783877002], partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@6fc0daa5, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, oldest=00b323c7-25f0-42a6-819b-e95d5173a000, oldestOrder=1, evtLatch=0, remaining=[], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1443783877022, endTime=1443783877123, ignoreInterrupts=false, lsnr=null, state=DONE]], p=0, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783876734, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=00b323c7-25f0-42a6-819b-e95d5173a000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=1, intOrder=1, lastExchangeTime=1443783879713, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [14:04:40,254][WARN ][test-runner][root] Finished waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest0, p=0, duration=3012ms]
> [14:04:40,257][WARN ][test-runner][root] Waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=10ca173e, msg=Node joined: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1443783877022], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a000, 3035d755-8140-4af5-820d-df1d537b4003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=3035d755, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1443783877022, endTime=1443783877042, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@2233267a, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, oldest=00b323c7-25f0-42a6-819b-e95d5173a000, oldestOrder=1, evtLatch=0, remaining=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a000, 3035d755-8140-4af5-820d-df1d537b4003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1443783877022, endTime=1443783877133, ignoreInterrupts=false, lsnr=null, state=DONE]], p=14, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1443783876901, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783880186, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [14:04:40,859][WARN ][test-runner][root] Waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], topVer=4, nodeId8=10ca173e, msg=Node joined: TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], type=NODE_JOINED, tstamp=1443783877022], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a000, 3035d755-8140-4af5-820d-df1d537b4003], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], nodeId=3035d755, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1443783877022, endTime=1443783877042, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [lsnrCalls=3, finished=true, rdc=null, init=true, res=java.util.concurrent.atomic.AtomicMarkableReference@2233267a, err=null, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, oldest=00b323c7-25f0-42a6-819b-e95d5173a000, oldestOrder=1, evtLatch=0, remaining=[20cd98aa-ef5a-4eac-b28e-27c6df0b4002, 00b323c7-25f0-42a6-819b-e95d5173a000, 3035d755-8140-4af5-820d-df1d537b4003], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=4, minorTopVer=0], startTime=1443783877022, endTime=1443783877133, ignoreInterrupts=false, lsnr=null, state=DONE]], p=14, affNodesCnt=1, ownersCnt=2, affNodes=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], owners=[TcpDiscoveryNode [id=3035d755-8140-4af5-820d-df1d537b4003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=4, intOrder=4, lastExchangeTime=1443783877012, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false], TcpDiscoveryNode [id=20cd98aa-ef5a-4eac-b28e-27c6df0b4002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1443783876901, loc=false, ver=1.5.0#19700101-sha1:00000000, isClient=false]], locNode=TcpDiscoveryNode [id=10ca173e-8703-475c-95a2-21db704e7001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, lastExchangeTime=1443783880730, loc=true, ver=1.5.0#19700101-sha1:00000000, isClient=false]]
> [14:04:41,862][WARN ][test-runner][root] Finished waiting for topology map update [grid=dht.GridNearCacheTxNodeFailureSelfTest1, p=14, duration=1605ms]
> [14:04:42,877][ERROR][test-runner][IgniteTxHandler] Failed to prepare DHT transaction: GridDhtTxLocal [nearNodeId=00b323c7-25f0-42a6-819b-e95d5173a000, nearFutId=2eda1823051-353c0984-390a-4992-bb7a-2cd66298ba94, nearMiniId=3eda1823051-353c0984-390a-4992-bb7a-2cd66298ba94, nearFinFutId=null, nearFinMiniId=null, nearXidVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=1, globalTime=1443783881869, order=1443783876675], super=GridDhtTxLocalAdapter [dhtThreadId=122646, needsCompletedVers=true, nearOnOriginatingNode=true, nearNodes=[], dhtNodes=[3035d755-8140-4af5-820d-df1d537b4003], explicitLock=false, super=IgniteTxLocalAdapter [txMap={IgniteTxKey [key=KeyCacheObjectImpl [val=2, hasValBytes=true], cacheId=1]=IgniteTxEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], cacheId=1, txKey=IgniteTxKey [key=KeyCacheObjectImpl [val=2, hasValBytes=true], cacheId=1], val=[op=CREATE, val=CacheObjectImpl [val=2, hasValBytes=true]], prevVal=[op=NOOP, val=null], entryProcessorsCol=null, entryProcessorCalcVal=null, ttl=-1, conflictExpireTime=-1, conflictVer=null, explicitVer=null, dhtVer=null, filters=[], filtersPassed=false, filtersSet=false, entry=GridDhtCacheEntry [rdrs=[ReaderId [nodeId=00b323c7-25f0-42a6-819b-e95d5173a000, msgId=14714753, txFut=null]], locPart=GridDhtLocalPartition [id=2, mapPubSize=1, rmvQueue=GridCircularBuffer [sizeMask=31, idxGen=0], state=OWNING, reservations=0, empty=false, createTime=10/02/2015 14:04:36, mapPubSize=1], super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], val=null, startVer=1443783877004, ver=GridCacheVersion [topVer=55263880, nodeOrderDrId=2, globalTime=1443783881874, order=1443783877004], hash=821347078, extras=GridCacheMvccEntryExtras [mvcc=GridCacheMvcc [locs=[GridCacheMvccCandidate [nodeId=10ca173e-8703-475c-95a2-21db704e7001, ver=GridCacheVersion [topVer=55263880, nodeOrderDrId=2, globalTime=1443783881874, order=1443783877003], timeout=0, ts=1443783881869, threadId=122879, id=1313281, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], reentry=null, otherNodeId=00b323c7-25f0-42a6-819b-e95d5173a000, otherVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=1, globalTime=1443783881869, order=1443783876675], mappedDhtNodes=null, mappedNearNodes=null, ownerVer=null, key=KeyCacheObjectImpl [val=2, hasValBytes=true], masks=local=1|owner=1|ready=1|reentry=0|used=0|tx=1|single_implicit=1|dht_local=1|near_local=0|removed=0, prevVer=null, nextVer=null]], rmts=null]], flags=0]]], prepared=true, locked=false, nodeId=null, locMapped=false, expiryPlc=null, transferExpiryPlc=false, flags=0, xidVer=null]}, completedBase=null, sndTransformedVals=false, super=IgniteTxAdapter [xidVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=2, globalTime=1443783881874, order=1443783877003], writeVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=2, globalTime=1443783881874, order=1443783877005], implicit=true, implicitSingle=true, loc=true, threadId=122879, startTime=1443783881869, nodeId=10ca173e-8703-475c-95a2-21db704e7001, startVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=2, globalTime=1443783881874, order=1443783877003], endVer=null, isolation=READ_COMMITTED, concurrency=OPTIMISTIC, timeout=0, sysInvalidate=false, sys=false, plc=2, commitVer=null, finalizing=NONE, preparing=false, invalidParts={}, state=MARKED_ROLLBACK, timedOut=false, topVer=AffinityTopologyVersion [topVer=4, minorTopVer=0], duration=997ms, onePhaseCommit=true], size=1]]]
> class org.apache.ignite.IgniteCheckedException: Operation has been cancelled (node is stopping).
> at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.stopError(GridCacheMvccManager.java:373)
> at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.onStop(GridCacheMvccManager.java:334)
> at org.apache.ignite.internal.processors.cache.GridCacheProcessor.cancelFutures(GridCacheProcessor.java:3167)
> at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStop(GridCacheProcessor.java:968)
> at org.apache.ignite.internal.IgniteKernal.stop0(IgniteKernal.java:1820)
> at org.apache.ignite.internal.IgniteKernal.stop(IgniteKernal.java:1766)
> at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop0(IgnitionEx.java:2133)
> at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop(IgnitionEx.java:2096)
> at org.apache.ignite.internal.IgnitionEx.stop(IgnitionEx.java:314)
> at org.apache.ignite.Ignition.stop(Ignition.java:223)
> at org.apache.ignite.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:801)
> at org.apache.ignite.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:1059)
> at org.apache.ignite.internal.processors.cache.distributed.dht.GridCacheTxNodeFailureSelfTest.checkPrimaryNodeFailureBackupCommit(GridCacheTxNodeFailureSelfTest.java:292)
> at org.apache.ignite.internal.processors.cache.distributed.dht.GridCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit(GridCacheTxNodeFailureSelfTest.java:161)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at junit.framework.TestCase.runTest(TestCase.java:176)
> at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665)
> at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111)
> at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603)
> [14:04:42,989][WARN ][ignite-#90371%sys-dht.GridNearCacheTxNodeFailureSelfTest2%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Connect timed out (consider increasing 'failureDetectionTimeout' configuration property) [addr=/127.0.0.1:47100, failureDetectionTimeout=10000]
> [14:04:42,994][WARN ][ignite-#90371%sys-dht.GridNearCacheTxNodeFailureSelfTest2%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Failed to connect to a remote node (make sure that destination node is alive and operating system firewall is disabled on local and remote hosts) [addrs=[/127.0.0.1:47100]]
> [14:04:43,013][WARN ][exchange-worker-#90468%dht.GridNearCacheTxNodeFailureSelfTest3%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Connect timed out (consider increasing 'failureDetectionTimeout' configuration property) [addr=/127.0.0.1:47102, failureDetectionTimeout=10000]
> [14:04:43,013][WARN ][exchange-worker-#90468%dht.GridNearCacheTxNodeFailureSelfTest3%][GridCacheTxNodeFailureSelfTest$BanningCommunicationSpi] Failed to connect to a remote node (make sure that destination node is alive and operating system firewall is disabled on local and remote hosts) [addrs=[/127.0.0.1:47102]]
> [14:04:43,039][ERROR][main][root] Test failed.
> junit.framework.AssertionFailedError: near=GridNearCacheEntry [topVer=4, dhtVer=GridCacheVersion [topVer=55263880, nodeOrderDrId=1, globalTime=1443783881869, order=1443783876675], part=2, super=GridDistributedCacheEntry [super=GridCacheMapEntry [key=KeyCacheObjectImpl [val=2, hasValBytes=true], val=CacheObjectImpl [val=2, hasValBytes=true], startVer=1443783876676, ver=GridCacheVersion [topVer=55263880, nodeOrderDrId=1, globalTime=1443783881869, order=1443783876675], hash=821347078, extras=null, flags=0]]]
> 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.GridCacheTxNodeFailureSelfTest.dataCheck(GridCacheTxNodeFailureSelfTest.java:342)
> at org.apache.ignite.internal.processors.cache.distributed.dht.GridCacheTxNodeFailureSelfTest.checkPrimaryNodeFailureBackupCommit(GridCacheTxNodeFailureSelfTest.java:302)
> at org.apache.ignite.internal.processors.cache.distributed.dht.GridCacheTxNodeFailureSelfTest.testPrimaryNodeFailureBackupCommitImplicit(GridCacheTxNodeFailureSelfTest.java:161)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at junit.framework.TestCase.runTest(TestCase.java:176)
> at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1665)
> at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:111)
> at org.apache.ignite.testframework.junits.GridAbstractTest$6.run(GridAbstractTest.java:1603)
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.4.14#64029)