Return-Path: X-Original-To: apmail-ignite-issues-archive@minotaur.apache.org Delivered-To: apmail-ignite-issues-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 56ED217370 for ; Fri, 27 Mar 2015 15:48:17 +0000 (UTC) Received: (qmail 17970 invoked by uid 500); 27 Mar 2015 15:48:17 -0000 Delivered-To: apmail-ignite-issues-archive@ignite.apache.org Received: (qmail 17923 invoked by uid 500); 27 Mar 2015 15:48:17 -0000 Mailing-List: contact issues-help@ignite.incubator.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@ignite.incubator.apache.org Delivered-To: mailing list issues@ignite.incubator.apache.org Received: (qmail 17900 invoked by uid 99); 27 Mar 2015 15:48:17 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 27 Mar 2015 15:48:17 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,T_RP_MATCHES_RCVD X-Spam-Check-By: apache.org Received: from [140.211.11.3] (HELO mail.apache.org) (140.211.11.3) by apache.org (qpsmtpd/0.29) with SMTP; Fri, 27 Mar 2015 15:48:14 +0000 Received: (qmail 17793 invoked by uid 99); 27 Mar 2015 15:47:53 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 27 Mar 2015 15:47:53 +0000 Date: Fri, 27 Mar 2015 15:47:53 +0000 (UTC) From: "Ivan Veselovsky (JIRA)" To: issues@ignite.incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Created] (IGNITE-633) Test HadoopMapReduceEmbeddedSelfTest.testWholeMapReduceExecution somatimes hangs MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 X-Virus-Checked: Checked by ClamAV on apache.org Ivan Veselovsky created IGNITE-633: -------------------------------------- Summary: Test HadoopMapReduceEmbeddedSelfTest.testWholeMapRedu= ceExecution somatimes hangs Key: IGNITE-633 URL: https://issues.apache.org/jira/browse/IGNITE-633 Project: Ignite Issue Type: Test Components: hadoop Affects Versions: sprint-2 Reporter: Ivan Veselovsky Assignee: Ivan Veselovsky Fix For: sprint-3 Test HadoopMapReduceEmbeddedSelfTest.testWholeMapReduceExecution =09 java.util.concurrent.TimeoutException: Test has been timed out [test=3Dtest= WholeMapReduceExecution, timeout=3D300000] at org.apache.ignite.testframework.junits.GridAbstractTest.runTest(Grid= AbstractTest.java:1317) at junit.framework.TestCase.runBare(TestCase.java:141) at junit.framework.TestResult$1.protect(TestResult.java:122) at junit.framework.TestResult.runProtected(TestResult.java:142) at junit.framework.TestResult.run(TestResult.java:125) at junit.framework.TestCase.run(TestCase.java:129) at junit.framework.TestSuite.runTest(TestSuite.java:255) at junit.framework.TestSuite.run(TestSuite.java:250) at junit.framework.TestSuite.runTest(TestSuite.java:255) at junit.framework.TestSuite.run(TestSuite.java:250) at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner= .java:84) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provid= er.java:264) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit= 4Provider.java:153) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provide= r.java:124) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl= .java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcce= ssorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray= 2(ReflectionUtils.java:208) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invok= e(ProviderFactory.java:156) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(Prov= iderFactory.java:82) at org.apache.maven.plugin.surefire.InPluginVMSurefireStarter.runSuites= InProcess(InPluginVMSurefireStarter.java:82) at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvide= r(AbstractSurefireMojo.java:951) at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPr= econditionsChecked(AbstractSurefireMojo.java:831) at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(Abstra= ctSurefireMojo.java:729) at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(Defaul= tBuildPluginManager.java:101) at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecuto= r.java:209) at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecuto= r.java:153) at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecuto= r.java:145) at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProj= ect(LifecycleModuleBuilder.java:84) at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProj= ect(LifecycleModuleBuilder.java:59) at org.apache.maven.lifecycle.internal.LifecycleStarter.singleThreadedB= uild(LifecycleStarter.java:183) at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(Lifecyc= leStarter.java:161) at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:320) at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:156) at org.apache.maven.cli.MavenCli.execute(MavenCli.java:537) at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:196) at org.apache.maven.cli.MavenCli.main(MavenCli.java:141) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl= .java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcce= ssorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Lau= ncher.java:290) at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.ja= va:230) at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(L= auncher.java:409) at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java= :352) ------- Stdout: ------- [16:37:40,133][INFO ][main][root] >>> Starting test: testWholeMapReduceExec= ution <<< [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0]=20 >>> __________ ________________ =20 >>> / _/ ___/ |/ / _/_ __/ __/ =20 >>> _/ // (7 7 // / / / / _/ =20 >>> /___/\___/_/|_/___/ /_/ /___/ =20 >>>=20 >>> ver. 1.0.0#19700101-sha1:DEV >>> 2015 Copyright(C) Apache Software Foundation [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Config URL: n= /a [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Daemon mode: = off [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] OS: Linux 2.6= .32-38-generic amd64 [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] OS user: team= city [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Language runt= ime: Java Platform API Specification ver. 1.7 [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] VM informatio= n: Java(TM) SE Runtime Environment 1.7.0_67-b01 Oracle Corporation Java Hot= Spot(TM) 64-Bit Server VM 24.65-b04 [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] VM total memo= ry: 2.9GB [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Remote Manage= ment [restart: off, REST: off, JMX (remote: off)] [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] IGNITE_HOME= =3D/usr/local/TeamCityAgent/work/c326f6bf1927b309 [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] VM arguments:= [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.17.1.2, -Dage= nt.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity-7, -Dagent.= ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/work, -Dbuild.n= umber=3D1560, -Dbuild.vcs.number=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4= , -Dbuild.vcs.number.1=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4, -Dbuild.= vcs.number.IgniteApache=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4, -Dclass= worlds.conf=3D/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dco= m.jetbrains.maven.watcher.report.file=3D/usr/local/TeamCityAgent/temp/build= Tmp/maven-build-info.xml, -Dhadoop.version=3D2.5.2, -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=3D663, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D400410, -Dteamcity.aut= h.password=3Dnu1IIi3SWEb8NNhLDhghzpZjQSPWkYV3, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D400410, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles6192942218181221816.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/c326f6bf1927b309, -Dteamcity= .build.id=3D400410, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build1936164090676629649.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/c326f6bf1927b309, -Dteamcity.buil= dConfName=3DIgnite Hadoop, -Dteamcity.buildType.id=3DIgnite_Hadoop, -Dteamc= ity.configuration.properties.file=3D/usr/local/TeamCityAgent/temp/buildTmp/= teamcity.config1651692759705343482.properties, -Dteamcity.idea.home=3D/usr/= local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=3D/usr/loca= l/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName= =3DIgnite, -Dteamcity.runner.properties.file=3D/usr/local/TeamCityAgent/tem= p/buildTmp/teamcity.runner4645183226190514392.properties, -Dteamcity.tests.= recentlyFailedTests.file=3D/usr/local/TeamCityAgent/temp/buildTmp/testsToRu= nFirst6297047454049666673.txt, -Dteamcity.version=3D8.1.4 (build 30168), -e= a, -XX:MaxPermSize=3D2g, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -= XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCityAgent/work/c326= f6bf1927b309, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_NO_DISCO_ORDER=3Dt= rue, -DIGNITE_QUIET=3Dfalse, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintG= CTimeStamps] [16:37:40,162][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Configured ca= ches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-hadoop-mr-= sys-cache', 'ignite-atomics-sys-cache', 'meta', 'data'] [16:37:40,162][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] 3-rd party li= censes can be found at: /usr/local/TeamCityAgent/work/c326f6bf1927b309/libs= /licenses [16:37:40,174][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Non-loopback = local IPs: 192.168.2.17, fe80:0:0:0:ca60:ff:fe68:5868%2 [16:37:40,174][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Enabled local= MACs: C86000685868 [16:37:40,175][INFO ][main][IgnitePluginProcessor] Configured plugins: [16:37:40,175][INFO ][main][IgnitePluginProcessor] ^-- None [16:37:40,175][INFO ][main][IgnitePluginProcessor]=20 [16:37:40,189][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP = port [port=3D45190, locHost=3D/127.0.0.1] [16:37:40,221][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Security stat= us [authentication=3Doff] [16:37:40,241][INFO ][main][IpcSharedMemoryServerEndpoint] IPC shared memor= y server endpoint started [port=3D10500, tokDir=3D/usr/local/TeamCityAgent/= work/c326f6bf1927b309/work/ipc/shmem/954f246d-c071-4378-b678-3d34f5e19eb0-2= 1398] [16:37:40,242][INFO ][main][IpcServerTcpEndpoint] IPC server loopback endpo= int started [port=3D11400] [16:37:40,242][INFO ][main][HadoopProcessor] HADOOP_HOME is set to /usr/loc= al/TeamCityAgent/temp/buildTmp/__hadoop/hadoop-2.5.2 [16:37:40,263][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port= [port=3D47500, localHost=3D/127.0.0.1] [16:37:40,273][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-marshaller-sys-cache, mode=3DREPLICATED] [16:37:40,278][INFO ][main][GridCacheProcessor] Started cache [name=3Dmeta,= mode=3DREPLICATED] [16:37:40,284][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-hadoop-mr-sys-cache, mode=3DREPLICATED] [16:37:40,292][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-atomics-sys-cache, mode=3DPARTITIONED] [16:37:40,299][INFO ][main][GridCacheProcessor] Started cache [name=3Ddata,= mode=3DPARTITIONED] [16:37:40,304][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-sys-cache, mode=3DREPLICATED] [16:37:40,425][INFO ][main][GridDhtPreloader] = Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache [16:37:40,425][INFO ][main][GridDhtPreloader] = Starting rebalancing in SYNC mode: ignite-hadoop-mr-sys-cache [16:37:40,425][INFO ][main][GridDhtPreloader] Starting rebalancing i= n ASYNC mode: meta [16:37:40,425][INFO ][main][GridDhtPreloader] St= arting rebalancing in SYNC mode: ignite-atomics-sys-cache [16:37:40,426][INFO ][main][GridDhtPreloader] Starting rebalancing i= n ASYNC mode: data [16:37:40,426][INFO ][main][GridDhtPreloader] Starting r= ebalancing in SYNC mode: ignite-sys-cache [16:37:40,465][INFO ][preloader-demand-worker-#19997%hadoop.HadoopMapReduce= EmbeddedSelfTest0%][GridDhtPreloader] Complet= ed rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D40= ms] [16:37:40,465][INFO ][preloader-demand-worker-#20001%hadoop.HadoopMapReduce= EmbeddedSelfTest0%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-hadoop-mr-sys-cache, time=3D40 m= s] [16:37:40,465][INFO ][preloader-demand-worker-#20006%hadoop.HadoopMapReduce= EmbeddedSelfTest0%][GridDhtPreloader] Completed rebalancing in ASYNC= mode [cache=3Dmeta, time=3D30 ms] [16:37:40,465][INFO ][preloader-demand-worker-#20017%hadoop.HadoopMapReduce= EmbeddedSelfTest0%][GridDhtPreloader] Completed rebalancing in ASYNC= mode [cache=3Ddata, time=3D30 ms] [16:37:40,465][INFO ][preloader-demand-worker-#20011%hadoop.HadoopMapReduce= EmbeddedSelfTest0%][GridDhtPreloader] Completed = rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D30 ms] [16:37:40,465][INFO ][preloader-demand-worker-#20022%hadoop.HadoopMapReduce= EmbeddedSelfTest0%][GridDhtPreloader] Completed rebalanc= ing in SYNC mode [cache=3Dignite-sys-cache, time=3D30 ms] [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Performance s= uggestions for grid 'hadoop.HadoopMapReduceEmbeddedSelfTest0' (fix if possi= ble) [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] To disable, s= et -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] ^-- Disable= checkpoints (remove 'checkpointSpi' from configuration) [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] ^-- Disable= grid events (remove 'includeEventTypes' from configuration) [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0]=20 [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] To start Cons= ole Management & Monitoring run ignitevisorcmd.{sh|bat} [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0]=20 [16:37:40,506][INFO ][main][HadoopMapReduceEmbeddedSelfTest0]=20 >>> +---------------------------------+ >>> Ignite ver. 1.0.0#19700101-sha1:DEV >>> +---------------------------------+ >>> OS name: Linux 2.6.32-38-generic amd64 >>> CPU(s): 4 >>> Heap: 2.9GB >>> VM name: 21398@gg-teamcity-7 >>> Grid name: hadoop.HadoopMapReduceEmbeddedSelfTest0 >>> Local node [ID=3D954F246D-C071-4378-B678-3D34F5E19EB0, order=3D1] >>> Local node addresses: [/127.0.0.1] >>> Local ports: TCP:10500 TCP:11400 TCP:45190 TCP:47500=20 >>> Ignite documentation: http://ignite.incubator.apache.org [16:37:40,506][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=3D= 1, nodes=3D1, CPUs=3D4, heap=3D2.9GB] [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1]=20 >>> __________ ________________ =20 >>> / _/ ___/ |/ / _/_ __/ __/ =20 >>> _/ // (7 7 // / / / / _/ =20 >>> /___/\___/_/|_/___/ /_/ /___/ =20 >>>=20 >>> ver. 1.0.0#19700101-sha1:DEV >>> 2015 Copyright(C) Apache Software Foundation [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Config URL: n= /a [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Daemon mode: = off [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] OS: Linux 2.6= .32-38-generic amd64 [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] OS user: team= city [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Language runt= ime: Java Platform API Specification ver. 1.7 [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] VM informatio= n: Java(TM) SE Runtime Environment 1.7.0_67-b01 Oracle Corporation Java Hot= Spot(TM) 64-Bit Server VM 24.65-b04 [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] VM total memo= ry: 2.9GB [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Remote Manage= ment [restart: off, REST: off, JMX (remote: off)] [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] IGNITE_HOME= =3D/usr/local/TeamCityAgent/work/c326f6bf1927b309 [16:37:40,523][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] VM arguments:= [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.17.1.2, -Dage= nt.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity-7, -Dagent.= ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/work, -Dbuild.n= umber=3D1560, -Dbuild.vcs.number=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4= , -Dbuild.vcs.number.1=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4, -Dbuild.= vcs.number.IgniteApache=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4, -Dclass= worlds.conf=3D/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dco= m.jetbrains.maven.watcher.report.file=3D/usr/local/TeamCityAgent/temp/build= Tmp/maven-build-info.xml, -Dhadoop.version=3D2.5.2, -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=3D663, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D400410, -Dteamcity.aut= h.password=3Dnu1IIi3SWEb8NNhLDhghzpZjQSPWkYV3, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D400410, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles6192942218181221816.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/c326f6bf1927b309, -Dteamcity= .build.id=3D400410, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build1936164090676629649.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/c326f6bf1927b309, -Dteamcity.buil= dConfName=3DIgnite Hadoop, -Dteamcity.buildType.id=3DIgnite_Hadoop, -Dteamc= ity.configuration.properties.file=3D/usr/local/TeamCityAgent/temp/buildTmp/= teamcity.config1651692759705343482.properties, -Dteamcity.idea.home=3D/usr/= local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=3D/usr/loca= l/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName= =3DIgnite, -Dteamcity.runner.properties.file=3D/usr/local/TeamCityAgent/tem= p/buildTmp/teamcity.runner4645183226190514392.properties, -Dteamcity.tests.= recentlyFailedTests.file=3D/usr/local/TeamCityAgent/temp/buildTmp/testsToRu= nFirst6297047454049666673.txt, -Dteamcity.version=3D8.1.4 (build 30168), -e= a, -XX:MaxPermSize=3D2g, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -= XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCityAgent/work/c326= f6bf1927b309, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_NO_DISCO_ORDER=3Dt= rue, -DIGNITE_QUIET=3Dfalse, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintG= CTimeStamps] [16:37:40,523][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Configured ca= ches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-hadoop-mr-= sys-cache', 'ignite-atomics-sys-cache', 'meta', 'data'] [16:37:40,523][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] 3-rd party li= censes can be found at: /usr/local/TeamCityAgent/work/c326f6bf1927b309/libs= /licenses [16:37:40,535][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Non-loopback = local IPs: 192.168.2.17, fe80:0:0:0:ca60:ff:fe68:5868%2 [16:37:40,535][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Enabled local= MACs: C86000685868 [16:37:40,536][INFO ][main][IgnitePluginProcessor] Configured plugins: [16:37:40,536][INFO ][main][IgnitePluginProcessor] ^-- None [16:37:40,536][INFO ][main][IgnitePluginProcessor]=20 [16:37:40,551][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP = port [port=3D45191, locHost=3D/127.0.0.1] [16:37:40,582][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Security stat= us [authentication=3Doff] [16:37:40,602][INFO ][main][HadoopProcessor] HADOOP_HOME is set to /usr/loc= al/TeamCityAgent/temp/buildTmp/__hadoop/hadoop-2.5.2 [16:37:40,624][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port= [port=3D47501, localHost=3D/127.0.0.1] [16:37:40,701][INFO ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbed= dedSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscove= ryNode [id=3D7a924c9a-3284-476f-ad7c-6ddc7d6b3edd, addrs=3D[127.0.0.1], soc= kAddrs=3D[/127.0.0.1:47501], discPort=3D47501, order=3D2, intOrder=3D2, loc= =3Dfalse, ver=3D1.0.0#19700101-sha1:00000000, isClient=3Dfalse] [16:37:40,702][INFO ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbed= dedSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3D2, nodes=3D2,= CPUs=3D4, heap=3D2.9GB] [16:37:40,715][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-marshaller-sys-cache, mode=3DREPLICATED] [16:37:40,721][INFO ][main][GridCacheProcessor] Started cache [name=3Dmeta,= mode=3DREPLICATED] [16:37:40,726][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-hadoop-mr-sys-cache, mode=3DREPLICATED] [16:37:40,735][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-atomics-sys-cache, mode=3DPARTITIONED] [16:37:40,741][INFO ][main][GridCacheProcessor] Started cache [name=3Ddata,= mode=3DPARTITIONED] [16:37:40,747][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-sys-cache, mode=3DREPLICATED] [16:37:42,075][INFO ][main][GridDhtPreloader] = Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache [16:37:42,075][INFO ][main][GridDhtPreloader] = Starting rebalancing in SYNC mode: ignite-hadoop-mr-sys-cache [16:37:42,076][INFO ][main][GridDhtPreloader] Starting rebalancing i= n ASYNC mode: meta [16:37:42,076][INFO ][main][GridDhtPreloader] St= arting rebalancing in SYNC mode: ignite-atomics-sys-cache [16:37:42,076][INFO ][main][GridDhtPreloader] Starting rebalancing i= n ASYNC mode: data [16:37:42,076][INFO ][main][GridDhtPreloader] Starting r= ebalancing in SYNC mode: ignite-sys-cache [16:37:42,237][INFO ][preloader-demand-worker-#20074%hadoop.HadoopMapReduce= EmbeddedSelfTest1%][GridDhtPreloader] Complet= ed rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D17= 1 ms] [16:37:42,255][INFO ][preloader-demand-worker-#20102%hadoop.HadoopMapReduce= EmbeddedSelfTest1%][GridDhtPreloader] Completed rebalanc= ing in SYNC mode [cache=3Dignite-sys-cache, time=3D171 ms] [16:37:42,298][INFO ][preloader-demand-worker-#20090%hadoop.HadoopMapReduce= EmbeddedSelfTest1%][GridDhtPreloader] Completed = rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D231 ms] [16:37:42,302][INFO ][preloader-demand-worker-#20087%hadoop.HadoopMapReduce= EmbeddedSelfTest1%][GridDhtPreloader] Completed rebalancing in ASYNC= mode [cache=3Dmeta, time=3D231 ms] [16:37:42,305][INFO ][preloader-demand-worker-#20080%hadoop.HadoopMapReduce= EmbeddedSelfTest1%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-hadoop-mr-sys-cache, time=3D231 = ms] [16:37:42,307][INFO ][preloader-demand-worker-#20097%hadoop.HadoopMapReduce= EmbeddedSelfTest1%][GridDhtPreloader] Completed rebalancing in ASYNC= mode [cache=3Ddata, time=3D231 ms] [16:37:42,331][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Performance s= uggestions for grid 'hadoop.HadoopMapReduceEmbeddedSelfTest1' (fix if possi= ble) [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] To disable, s= et -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] ^-- Disable= checkpoints (remove 'checkpointSpi' from configuration) [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] ^-- Disable= grid events (remove 'includeEventTypes' from configuration) [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1]=20 [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] To start Cons= ole Management & Monitoring run ignitevisorcmd.{sh|bat} [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1]=20 [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1]=20 >>> +---------------------------------+ >>> Ignite ver. 1.0.0#19700101-sha1:DEV >>> +---------------------------------+ >>> OS name: Linux 2.6.32-38-generic amd64 >>> CPU(s): 4 >>> Heap: 2.8GB >>> VM name: 21398@gg-teamcity-7 >>> Grid name: hadoop.HadoopMapReduceEmbeddedSelfTest1 >>> Local node [ID=3D7A924C9A-3284-476F-AD7C-6DDC7D6B3EDD, order=3D2] >>> Local node addresses: [/127.0.0.1] >>> Local ports: TCP:45191 TCP:47501=20 >>> Ignite documentation: http://ignite.incubator.apache.org [16:37:42,333][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=3D= 2, nodes=3D2, CPUs=3D4, heap=3D2.8GB] [16:37:42,348][INFO ][main][HadoopMapReduceEmbeddedSelfTest2]=20 >>> __________ ________________ =20 >>> / _/ ___/ |/ / _/_ __/ __/ =20 >>> _/ // (7 7 // / / / / _/ =20 >>> /___/\___/_/|_/___/ /_/ /___/ =20 >>>=20 >>> ver. 1.0.0#19700101-sha1:DEV >>> 2015 Copyright(C) Apache Software Foundation [16:37:42,348][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Config URL: n= /a [16:37:42,348][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Daemon mode: = off [16:37:42,349][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] OS: Linux 2.6= .32-38-generic amd64 [16:37:42,349][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] OS user: team= city [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Language runt= ime: Java Platform API Specification ver. 1.7 [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] VM informatio= n: Java(TM) SE Runtime Environment 1.7.0_67-b01 Oracle Corporation Java Hot= Spot(TM) 64-Bit Server VM 24.65-b04 [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] VM total memo= ry: 2.8GB [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Remote Manage= ment [restart: off, REST: off, JMX (remote: off)] [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] IGNITE_HOME= =3D/usr/local/TeamCityAgent/work/c326f6bf1927b309 [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] VM arguments:= [-DJAVA_HOME=3D/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=3D229.17.1.2, -Dage= nt.home.dir=3D/usr/local/TeamCityAgent, -Dagent.name=3Dteamcity-7, -Dagent.= ownPort=3D9090, -Dagent.work.dir=3D/usr/local/TeamCityAgent/work, -Dbuild.n= umber=3D1560, -Dbuild.vcs.number=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4= , -Dbuild.vcs.number.1=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4, -Dbuild.= vcs.number.IgniteApache=3Dcb074fe98614907b0dd20fca71a44beb2dc5d8f4, -Dclass= worlds.conf=3D/usr/local/TeamCityAgent/temp/buildTmp/teamcity.m2.conf, -Dco= m.jetbrains.maven.watcher.report.file=3D/usr/local/TeamCityAgent/temp/build= Tmp/maven-build-info.xml, -Dhadoop.version=3D2.5.2, -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=3D663, -Dteamcity.agent.dotnet.agent_url=3Dhttp://loc= alhost:9090/RPC2, -Dteamcity.agent.dotnet.build_id=3D400410, -Dteamcity.aut= h.password=3Dnu1IIi3SWEb8NNhLDhghzpZjQSPWkYV3, -Dteamcity.auth.userId=3DTea= mCityBuildId=3D400410, -Dteamcity.build.changedFiles.file=3D/usr/local/Team= CityAgent/temp/buildTmp/changedFiles6192942218181221816.txt, -Dteamcity.bui= ld.checkoutDir=3D/usr/local/TeamCityAgent/work/c326f6bf1927b309, -Dteamcity= .build.id=3D400410, -Dteamcity.build.properties.file=3D/usr/local/TeamCityA= gent/temp/buildTmp/teamcity.build1936164090676629649.properties, -Dteamcity= .build.tempDir=3D/usr/local/TeamCityAgent/temp/buildTmp, -Dteamcity.build.w= orkingDir=3D/usr/local/TeamCityAgent/work/c326f6bf1927b309, -Dteamcity.buil= dConfName=3DIgnite Hadoop, -Dteamcity.buildType.id=3DIgnite_Hadoop, -Dteamc= ity.configuration.properties.file=3D/usr/local/TeamCityAgent/temp/buildTmp/= teamcity.config1651692759705343482.properties, -Dteamcity.idea.home=3D/usr/= local/TeamCityAgent/plugins/idea, -Dteamcity.maven.watcher.home=3D/usr/loca= l/TeamCityAgent/plugins/mavenPlugin/maven-watcher, -Dteamcity.projectName= =3DIgnite, -Dteamcity.runner.properties.file=3D/usr/local/TeamCityAgent/tem= p/buildTmp/teamcity.runner4645183226190514392.properties, -Dteamcity.tests.= recentlyFailedTests.file=3D/usr/local/TeamCityAgent/temp/buildTmp/testsToRu= nFirst6297047454049666673.txt, -Dteamcity.version=3D8.1.4 (build 30168), -e= a, -XX:MaxPermSize=3D2g, -Xms1g, -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -= XX:+AggressiveOpts, -DIGNITE_TEST_HOME=3D/usr/local/TeamCityAgent/work/c326= f6bf1927b309, -DIGNITE_UPDATE_NOTIFIER=3Dfalse, -DIGNITE_NO_DISCO_ORDER=3Dt= rue, -DIGNITE_QUIET=3Dfalse, -XX:+PrintGC, -XX:+PrintGCDetails, -XX:+PrintG= CTimeStamps] [16:37:42,351][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Configured ca= ches ['ignite-marshaller-sys-cache', 'ignite-sys-cache', 'ignite-hadoop-mr-= sys-cache', 'ignite-atomics-sys-cache', 'meta', 'data'] [16:37:42,351][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] 3-rd party li= censes can be found at: /usr/local/TeamCityAgent/work/c326f6bf1927b309/libs= /licenses [16:37:42,363][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Non-loopback = local IPs: 192.168.2.17, fe80:0:0:0:ca60:ff:fe68:5868%2 [16:37:42,363][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Enabled local= MACs: C86000685868 [16:37:42,364][INFO ][main][IgnitePluginProcessor] Configured plugins: [16:37:42,364][INFO ][main][IgnitePluginProcessor] ^-- None [16:37:42,364][INFO ][main][IgnitePluginProcessor]=20 [16:37:42,379][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP = port [port=3D45192, locHost=3D/127.0.0.1] [16:37:42,411][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Security stat= us [authentication=3Doff] [16:37:42,432][INFO ][main][HadoopProcessor] HADOOP_HOME is set to /usr/loc= al/TeamCityAgent/temp/buildTmp/__hadoop/hadoop-2.5.2 [16:37:42,453][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port= [port=3D47502, localHost=3D/127.0.0.1] [16:37:42,598][INFO ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbed= dedSelfTest0%][GridDiscoveryManager] Added new node to topology: TcpDiscove= ryNode [id=3Dd313c493-0cbc-4ada-a03e-e48802166818, addrs=3D[127.0.0.1], soc= kAddrs=3D[/127.0.0.1:47502], discPort=3D47502, order=3D3, intOrder=3D3, loc= =3Dfalse, ver=3D1.0.0#19700101-sha1:00000000, isClient=3Dfalse] [16:37:42,599][INFO ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbed= dedSelfTest0%][GridDiscoveryManager] Topology snapshot [ver=3D3, nodes=3D3,= CPUs=3D4, heap=3D2.8GB] [16:37:42,600][INFO ][disco-event-worker-#20066%hadoop.HadoopMapReduceEmbed= dedSelfTest1%][GridDiscoveryManager] Added new node to topology: TcpDiscove= ryNode [id=3Dd313c493-0cbc-4ada-a03e-e48802166818, addrs=3D[127.0.0.1], soc= kAddrs=3D[/127.0.0.1:47502], discPort=3D47502, order=3D3, intOrder=3D3, loc= =3Dfalse, ver=3D1.0.0#19700101-sha1:00000000, isClient=3Dfalse] [16:37:42,600][INFO ][disco-event-worker-#20066%hadoop.HadoopMapReduceEmbed= dedSelfTest1%][GridDiscoveryManager] Topology snapshot [ver=3D3, nodes=3D3,= CPUs=3D4, heap=3D2.8GB] [16:37:42,612][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-marshaller-sys-cache, mode=3DREPLICATED] [16:37:42,617][INFO ][main][GridCacheProcessor] Started cache [name=3Dmeta,= mode=3DREPLICATED] [16:37:42,623][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-hadoop-mr-sys-cache, mode=3DREPLICATED] [16:37:42,632][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-atomics-sys-cache, mode=3DPARTITIONED] [16:37:42,639][INFO ][main][GridCacheProcessor] Started cache [name=3Ddata,= mode=3DPARTITIONED] [16:37:42,644][INFO ][main][GridCacheProcessor] Started cache [name=3Dignit= e-sys-cache, mode=3DREPLICATED] [16:37:43,306][INFO ][main][GridDhtPreloader] = Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache [16:37:43,306][INFO ][main][GridDhtPreloader] = Starting rebalancing in SYNC mode: ignite-hadoop-mr-sys-cache [16:37:43,306][INFO ][main][GridDhtPreloader] Starting rebalancing i= n ASYNC mode: meta [16:37:43,307][INFO ][main][GridDhtPreloader] St= arting rebalancing in SYNC mode: ignite-atomics-sys-cache [16:37:43,308][INFO ][main][GridDhtPreloader] Starting rebalancing i= n ASYNC mode: data [16:37:43,308][INFO ][main][GridDhtPreloader] Starting r= ebalancing in SYNC mode: ignite-sys-cache [16:37:43,476][INFO ][preloader-demand-worker-#20167%hadoop.HadoopMapReduce= EmbeddedSelfTest2%][GridDhtPreloader] Complet= ed rebalancing in SYNC mode [cache=3Dignite-marshaller-sys-cache, time=3D17= 1 ms] [16:37:43,496][INFO ][preloader-demand-worker-#20181%hadoop.HadoopMapReduce= EmbeddedSelfTest2%][GridDhtPreloader] Completed = rebalancing in SYNC mode [cache=3Dignite-atomics-sys-cache, time=3D193 ms] [16:37:43,500][INFO ][preloader-demand-worker-#20193%hadoop.HadoopMapReduce= EmbeddedSelfTest2%][GridDhtPreloader] Completed rebalanc= ing in SYNC mode [cache=3Dignite-sys-cache, time=3D193 ms] [16:37:43,505][INFO ][preloader-demand-worker-#20188%hadoop.HadoopMapReduce= EmbeddedSelfTest2%][GridDhtPreloader] Completed rebalancing in ASYNC= mode [cache=3Ddata, time=3D193 ms] [16:37:43,520][INFO ][preloader-demand-worker-#20173%hadoop.HadoopMapReduce= EmbeddedSelfTest2%][GridDhtPreloader] Complete= d rebalancing in SYNC mode [cache=3Dignite-hadoop-mr-sys-cache, time=3D213 = ms] [16:37:43,525][INFO ][preloader-demand-worker-#20178%hadoop.HadoopMapReduce= EmbeddedSelfTest2%][GridDhtPreloader] Completed rebalancing in ASYNC= mode [cache=3Dmeta, time=3D213 ms] [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Performance s= uggestions for grid 'hadoop.HadoopMapReduceEmbeddedSelfTest2' (fix if possi= ble) [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] To disable, s= et -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=3Dtrue [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] ^-- Disable= checkpoints (remove 'checkpointSpi' from configuration) [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] ^-- Disable= grid events (remove 'includeEventTypes' from configuration) [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2]=20 [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] To start Cons= ole Management & Monitoring run ignitevisorcmd.{sh|bat} [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2]=20 [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2]=20 >>> +---------------------------------+ >>> Ignite ver. 1.0.0#19700101-sha1:DEV >>> +---------------------------------+ >>> OS name: Linux 2.6.32-38-generic amd64 >>> CPU(s): 4 >>> Heap: 2.8GB >>> VM name: 21398@gg-teamcity-7 >>> Grid name: hadoop.HadoopMapReduceEmbeddedSelfTest2 >>> Local node [ID=3DD313C493-0CBC-4ADA-A03E-E48802166818, order=3D3] >>> Local node addresses: [/127.0.0.1] >>> Local ports: TCP:45192 TCP:47502=20 >>> Ignite documentation: http://ignite.incubator.apache.org [16:37:43,549][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=3D= 3, nodes=3D3, CPUs=3D4, heap=3D2.8GB] [16:37:44,371][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:38:34,058][INFO ][Hadoop-task-3642bd08-a856-457f-b7de-1eaabc4de4da_1-RE= DUCE-0-0-#20618%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitt= er] Saved output of task 'attempt_3642bd08-a856-457f-b7de-1eaabc4de4da_0001= _r_000000_0' to igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_te= mporary/0/task_3642bd08-a856-457f-b7de-1eaabc4de4da_0001_r_000000 [16:38:35,397][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:39:25,057][INFO ][Hadoop-task-afecac89-ef45-4a3d-988c-c5c16f231d52_1-RE= DUCE-0-0-#20969%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitt= er] Saved output of task 'attempt_afecac89-ef45-4a3d-988c-c5c16f231d52_0001= _r_000000_0' to igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_te= mporary/0/task_afecac89-ef45-4a3d-988c-c5c16f231d52_0001_r_000000 [16:39:26,206][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:40:16,204][INFO ][Hadoop-task-5a132c34-b235-4468-8c4c-70e4bc047897_1-RE= DUCE-0-0-#21316%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitt= er] Saved output of task 'attempt_5a132c34-b235-4468-8c4c-70e4bc047897_0001= _r_000000_0' to igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_te= mporary/0/task_5a132c34-b235-4468-8c4c-70e4bc047897_0001_r_000000 [16:40:17,783][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:41:07,878][INFO ][Hadoop-task-37d89f9d-7bf4-4bfc-acfe-8bdf2dfec0c1_1-RE= DUCE-0-0-#21663%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitt= er] Saved output of task 'attempt_37d89f9d-7bf4-4bfc-acfe-8bdf2dfec0c1_0001= _r_000000_0' to igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_te= mporary/0/task_37d89f9d-7bf4-4bfc-acfe-8bdf2dfec0c1_0001_r_000000 [16:41:09,319][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:41:59,352][INFO ][Hadoop-task-e9089c34-7864-412e-9507-2038b6fb0d6e_1-RE= DUCE-0-0-#22010%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitt= er] Saved output of task 'attempt_e9089c34-7864-412e-9507-2038b6fb0d6e_0001= _r_000000_0' to igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_te= mporary/0/task_e9089c34-7864-412e-9507-2038b6fb0d6e_0001_r_000000 [16:42:01,014][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:42:39,194][INFO ][Hadoop-task-b42447f3-7f75-4639-864f-72854bb81982_1-RE= DUCE-0-0-#22357%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitt= er] Saved output of task 'attempt_b42447f3-7f75-4639-864f-72854bb81982_0001= _r_000000_0' to igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_te= mporary/0/task_b42447f3-7f75-4639-864f-72854bb81982_0001_r_000000 [16:42:40,250][INFO ][test-runner][FileInputFormat] Total input paths to pr= ocess : 1 [16:42:43] (wrn) Thread dump at 2015/03/27 16:42:43 MSK Thread [name=3D"Hadoop-task-ef8347bb-a9de-454a-8823-60e3b3c513b7_1-MAP-232-= 0-#22374%hadoop.HadoopMapReduceEmbeddedSelfTest2%", id=3D30879, state=3DRUN= NABLE, blockCnt=3D0, waitCnt=3D4] at java.util.StringTokenizer.skipDelimiters(StringTokenizer.java:24= 5) at java.util.StringTokenizer.hasMoreTokens(StringTokenizer.java:323= ) at o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(Ha= doopWordCount2Mapper.java:50) at o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(Ha= doopWordCount2Mapper.java:30) at org.apache.hadoop.mapreduce.Mapper.run(Mapper.java:145) at o.a.i.i.processors.hadoop.v2.HadoopV2MapTask.run0(HadoopV2MapTas= k.java:84) at o.a.i.i.processors.hadoop.v2.HadoopV2Task.run(HadoopV2Task.java:= 50) at o.a.i.i.processors.hadoop.v2.HadoopV2TaskContext.run(HadoopV2Tas= kContext.java:193) at o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.runTas= k(HadoopRunnableTask.java:176) at o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(H= adoopRunnableTask.java:120) at o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(H= adoopRunnableTask.java:36) at o.a.i.i.processors.hadoop.taskexecutor.HadoopExecutorService$2.b= ody(HadoopExecutorService.java:183) at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:107) at java.lang.Thread.run(Thread.java:745) Thread [name=3D"hadoop-shuffle-ef8347bb-a9de-454a-8823-60e3b3c513b7_1-#2237= 3%hadoop.HadoopMapReduceEmbeddedSelfTest2%", id=3D30878, state=3DTIMED_WAIT= ING, blockCnt=3D0, waitCnt=3D28] at java.lang.Thread.sleep(Native Method) at o.a.i.i.processors.hadoop.shuffle.HadoopShuffleJob$1.body(Hadoop= ShuffleJob.java:159) at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:107) at java.lang.Thread.run(Thread.java:745) Thread [name=3D"Hadoop-task-ef8347bb-a9de-454a-8823-60e3b3c513b7_1-MAP-5-0-= #22372%hadoop.HadoopMapReduceEmbeddedSelfTest0%", id=3D30877, state=3DRUNNA= BLE, blockCnt=3D0, waitCnt=3D6] at org.apache.hadoop.io.Text.readFields(Text.java:291) at o.a.i.i.processors.hadoop.v2.HadoopWritableSerialization.read(Ha= doopWritableSerialization.java:61) at o.a.i.i.processors.hadoop.shuffle.collections.HadoopMultimapBase= $ReaderBase.read(HadoopMultimapBase.java:159) at o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$Rea= der.readKey(HadoopSkipList.java:260) at o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$Add= erImpl.cmp(HadoopSkipList.java:471) at o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$Add= erImpl.add(HadoopSkipList.java:417) at o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$Add= erImpl.write(HadoopSkipList.java:300) at o.a.i.i.processors.hadoop.v2.HadoopV2Context.write(HadoopV2Conte= xt.java:134) at org.apache.hadoop.mapreduce.lib.map.WrappedMapper$Context.write(= WrappedMapper.java:112) at o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(Ha= doopWordCount2Mapper.java:53) at o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(Ha= doopWordCount2Mapper.java:30) at org.apache.hadoop.mapreduce.Mapper.run(Mapper.java:145) at o.a.i.i.processors.hadoop.v2.HadoopV2MapTask.run0(HadoopV2MapTas= k.java:84) at o.a.i.i.processors.hadoop.v2.HadoopV2Task.run(HadoopV2Task.java:= 50) at o.a.i.i.processors.hadoop.v2.HadoopV2TaskContext.run(HadoopV2Tas= kContext.java:193) at o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.runTas= k(HadoopRunnableTask.java:176) at o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(H= adoopRunnableTask.java:120) at o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(H= adoopRunnableTask.java:36) at o.a.i.i.processors.hadoop.taskexecutor.HadoopExecutorService$2.b= ody(HadoopExecutorService.java:183) at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:107) at java.lang.Thread.run(Thread.java:745) ...... -- This message was sent by Atlassian JIRA (v6.3.4#6332)