[ https://issues.apache.org/jira/browse/IGNITE-633?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Vladimir Ozerov updated IGNITE-633: ----------------------------------- Assignee: Ivan Veselovsky (was: Vladimir Ozerov) > Test HadoopMapReduceEmbeddedSelfTest.testWholeMapReduceExecution sometimes > 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-6 > > > Test HadoopMapReduceEmbeddedSelfTest.testWholeMapReduceExecution > java.util.concurrent.TimeoutException: Test has been timed out > [test=testWholeMapReduceExecution, timeout=300000] > at > org.apache.ignite.testframework.junits.GridAbstractTest.runTest(GridAbstractTest.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(JUnit4Provider.java:264) > at > org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) > at > org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at > org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:208) > at > org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:156) > at > org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:82) > at > org.apache.maven.plugin.surefire.InPluginVMSurefireStarter.runSuitesInProcess(InPluginVMSurefireStarter.java:82) > at > org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:951) > at > org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:831) > at > org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:729) > at > org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:101) > at > org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:209) > at > org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:153) > at > org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:145) > at > org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:84) > at > org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:59) > at > org.apache.maven.lifecycle.internal.LifecycleStarter.singleThreadedBuild(LifecycleStarter.java:183) > at > org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:161) > at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:320) > at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:156) > at org.apache.maven.cli.MavenCli.execute(MavenCli.java:537) > at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:196) > at org.apache.maven.cli.MavenCli.main(MavenCli.java:141) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at > org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:290) > at > org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:230) > at > org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:409) > at > org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:352) > ------- Stdout: ------- > [16:37:40,133][INFO ][main][root] >>> Starting test: > testWholeMapReduceExecution <<< > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] > >>> __________ ________________ > >>> / _/ ___/ |/ / _/_ __/ __/ > >>> _/ // (7 7 // / / / / _/ > >>> /___/\___/_/|_/___/ /_/ /___/ > >>> > >>> 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: > teamcity > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Language > runtime: Java Platform API Specification ver. 1.7 > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] VM information: > Java(TM) SE Runtime Environment 1.7.0_67-b01 Oracle Corporation Java > HotSpot(TM) 64-Bit Server VM 24.65-b04 > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] VM total > memory: 2.9GB > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Remote > Management [restart: off, REST: off, JMX (remote: off)] > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] > IGNITE_HOME=/usr/local/TeamCityAgent/work/c326f6bf1927b309 > [16:37:40,161][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] VM arguments: > [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.17.1.2, > -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-7, > -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, > -Dbuild.number=1560, > -Dbuild.vcs.number=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -Dbuild.vcs.number.1=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -Dbuild.vcs.number.IgniteApache=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -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, > -Dhadoop.version=2.5.2, > -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=663, > -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, > -Dteamcity.agent.dotnet.build_id=400410, > -Dteamcity.auth.password=nu1IIi3SWEb8NNhLDhghzpZjQSPWkYV3, > -Dteamcity.auth.userId=TeamCityBuildId=400410, > -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles6192942218181221816.txt, > -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -Dteamcity.build.id=400410, > -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build1936164090676629649.properties, > -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, > -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -Dteamcity.buildConfName=Ignite Hadoop, > -Dteamcity.buildType.id=Ignite_Hadoop, > -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config1651692759705343482.properties, > -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, > -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, > -Dteamcity.projectName=Ignite, > -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner4645183226190514392.properties, > > -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6297047454049666673.txt, > -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=2g, -Xms1g, > -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:+AggressiveOpts, > -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, > -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, > -XX:+PrintGCTimeStamps] > [16:37:40,162][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Configured > caches ['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 > licenses 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] > [16:37:40,189][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP > port [port=45190, locHost=/127.0.0.1] > [16:37:40,221][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Security status > [authentication=off] > [16:37:40,241][INFO ][main][IpcSharedMemoryServerEndpoint] IPC shared memory > server endpoint started [port=10500, > tokDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309/work/ipc/shmem/954f246d-c071-4378-b678-3d34f5e19eb0-21398] > [16:37:40,242][INFO ][main][IpcServerTcpEndpoint] IPC server loopback > endpoint started [port=11400] > [16:37:40,242][INFO ][main][HadoopProcessor] HADOOP_HOME is set to > /usr/local/TeamCityAgent/temp/buildTmp/__hadoop/hadoop-2.5.2 > [16:37:40,263][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port > [port=47500, localHost=/127.0.0.1] > [16:37:40,273][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-marshaller-sys-cache, mode=REPLICATED] > [16:37:40,278][INFO ][main][GridCacheProcessor] Started cache [name=meta, > mode=REPLICATED] > [16:37:40,284][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-hadoop-mr-sys-cache, mode=REPLICATED] > [16:37:40,292][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-atomics-sys-cache, mode=PARTITIONED] > [16:37:40,299][INFO ][main][GridCacheProcessor] Started cache [name=data, > mode=PARTITIONED] > [16:37:40,304][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-sys-cache, mode=REPLICATED] > [16:37:40,425][INFO ][main][GridDhtPreloader] <ignite-marshaller-sys-cache> > Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [16:37:40,425][INFO ][main][GridDhtPreloader] <ignite-hadoop-mr-sys-cache> > Starting rebalancing in SYNC mode: ignite-hadoop-mr-sys-cache > [16:37:40,425][INFO ][main][GridDhtPreloader] <meta> Starting rebalancing in > ASYNC mode: meta > [16:37:40,425][INFO ][main][GridDhtPreloader] <ignite-atomics-sys-cache> > Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [16:37:40,426][INFO ][main][GridDhtPreloader] <data> Starting rebalancing in > ASYNC mode: data > [16:37:40,426][INFO ][main][GridDhtPreloader] <ignite-sys-cache> Starting > rebalancing in SYNC mode: ignite-sys-cache > [16:37:40,465][INFO > ][preloader-demand-worker-#19997%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDhtPreloader] > <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-marshaller-sys-cache, time=40 ms] > [16:37:40,465][INFO > ][preloader-demand-worker-#20001%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDhtPreloader] > <ignite-hadoop-mr-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-hadoop-mr-sys-cache, time=40 ms] > [16:37:40,465][INFO > ][preloader-demand-worker-#20006%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDhtPreloader] > <meta> Completed rebalancing in ASYNC mode [cache=meta, time=30 ms] > [16:37:40,465][INFO > ][preloader-demand-worker-#20017%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDhtPreloader] > <data> Completed rebalancing in ASYNC mode [cache=data, time=30 ms] > [16:37:40,465][INFO > ][preloader-demand-worker-#20011%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDhtPreloader] > <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-atomics-sys-cache, time=30 ms] > [16:37:40,465][INFO > ][preloader-demand-worker-#20022%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDhtPreloader] > <ignite-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-sys-cache, time=30 ms] > [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] Performance > suggestions for grid 'hadoop.HadoopMapReduceEmbeddedSelfTest0' (fix if > possible) > [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] To disable, set > -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true > [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] > [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] To start > Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [16:37:40,505][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] > [16:37:40,506][INFO ][main][HadoopMapReduceEmbeddedSelfTest0] > >>> +---------------------------------+ > >>> 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=954F246D-C071-4378-B678-3D34F5E19EB0, order=1] > >>> Local node addresses: [/127.0.0.1] > >>> Local ports: TCP:10500 TCP:11400 TCP:45190 TCP:47500 > >>> Ignite documentation: http://ignite.incubator.apache.org > [16:37:40,506][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=1, > nodes=1, CPUs=4, heap=2.9GB] > [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] > >>> __________ ________________ > >>> / _/ ___/ |/ / _/_ __/ __/ > >>> _/ // (7 7 // / / / / _/ > >>> /___/\___/_/|_/___/ /_/ /___/ > >>> > >>> 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: > teamcity > [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Language > runtime: Java Platform API Specification ver. 1.7 > [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] VM information: > Java(TM) SE Runtime Environment 1.7.0_67-b01 Oracle Corporation Java > HotSpot(TM) 64-Bit Server VM 24.65-b04 > [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] VM total > memory: 2.9GB > [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Remote > Management [restart: off, REST: off, JMX (remote: off)] > [16:37:40,522][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] > IGNITE_HOME=/usr/local/TeamCityAgent/work/c326f6bf1927b309 > [16:37:40,523][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] VM arguments: > [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.17.1.2, > -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-7, > -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, > -Dbuild.number=1560, > -Dbuild.vcs.number=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -Dbuild.vcs.number.1=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -Dbuild.vcs.number.IgniteApache=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -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, > -Dhadoop.version=2.5.2, > -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=663, > -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, > -Dteamcity.agent.dotnet.build_id=400410, > -Dteamcity.auth.password=nu1IIi3SWEb8NNhLDhghzpZjQSPWkYV3, > -Dteamcity.auth.userId=TeamCityBuildId=400410, > -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles6192942218181221816.txt, > -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -Dteamcity.build.id=400410, > -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build1936164090676629649.properties, > -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, > -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -Dteamcity.buildConfName=Ignite Hadoop, > -Dteamcity.buildType.id=Ignite_Hadoop, > -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config1651692759705343482.properties, > -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, > -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, > -Dteamcity.projectName=Ignite, > -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner4645183226190514392.properties, > > -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6297047454049666673.txt, > -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=2g, -Xms1g, > -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:+AggressiveOpts, > -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, > -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, > -XX:+PrintGCTimeStamps] > [16:37:40,523][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Configured > caches ['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 > licenses 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] > [16:37:40,551][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP > port [port=45191, locHost=/127.0.0.1] > [16:37:40,582][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Security status > [authentication=off] > [16:37:40,602][INFO ][main][HadoopProcessor] HADOOP_HOME is set to > /usr/local/TeamCityAgent/temp/buildTmp/__hadoop/hadoop-2.5.2 > [16:37:40,624][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port > [port=47501, localHost=/127.0.0.1] > [16:37:40,701][INFO > ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDiscoveryManager] > Added new node to topology: TcpDiscoveryNode > [id=7a924c9a-3284-476f-ad7c-6ddc7d6b3edd, addrs=[127.0.0.1], > sockAddrs=[/127.0.0.1:47501], discPort=47501, order=2, intOrder=2, loc=false, > ver=1.0.0#19700101-sha1:00000000, isClient=false] > [16:37:40,702][INFO > ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDiscoveryManager] > Topology snapshot [ver=2, nodes=2, CPUs=4, heap=2.9GB] > [16:37:40,715][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-marshaller-sys-cache, mode=REPLICATED] > [16:37:40,721][INFO ][main][GridCacheProcessor] Started cache [name=meta, > mode=REPLICATED] > [16:37:40,726][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-hadoop-mr-sys-cache, mode=REPLICATED] > [16:37:40,735][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-atomics-sys-cache, mode=PARTITIONED] > [16:37:40,741][INFO ][main][GridCacheProcessor] Started cache [name=data, > mode=PARTITIONED] > [16:37:40,747][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-sys-cache, mode=REPLICATED] > [16:37:42,075][INFO ][main][GridDhtPreloader] <ignite-marshaller-sys-cache> > Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [16:37:42,075][INFO ][main][GridDhtPreloader] <ignite-hadoop-mr-sys-cache> > Starting rebalancing in SYNC mode: ignite-hadoop-mr-sys-cache > [16:37:42,076][INFO ][main][GridDhtPreloader] <meta> Starting rebalancing in > ASYNC mode: meta > [16:37:42,076][INFO ][main][GridDhtPreloader] <ignite-atomics-sys-cache> > Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [16:37:42,076][INFO ][main][GridDhtPreloader] <data> Starting rebalancing in > ASYNC mode: data > [16:37:42,076][INFO ][main][GridDhtPreloader] <ignite-sys-cache> Starting > rebalancing in SYNC mode: ignite-sys-cache > [16:37:42,237][INFO > ][preloader-demand-worker-#20074%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDhtPreloader] > <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-marshaller-sys-cache, time=171 ms] > [16:37:42,255][INFO > ][preloader-demand-worker-#20102%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDhtPreloader] > <ignite-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-sys-cache, time=171 ms] > [16:37:42,298][INFO > ][preloader-demand-worker-#20090%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDhtPreloader] > <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-atomics-sys-cache, time=231 ms] > [16:37:42,302][INFO > ][preloader-demand-worker-#20087%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDhtPreloader] > <meta> Completed rebalancing in ASYNC mode [cache=meta, time=231 ms] > [16:37:42,305][INFO > ][preloader-demand-worker-#20080%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDhtPreloader] > <ignite-hadoop-mr-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-hadoop-mr-sys-cache, time=231 ms] > [16:37:42,307][INFO > ][preloader-demand-worker-#20097%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDhtPreloader] > <data> Completed rebalancing in ASYNC mode [cache=data, time=231 ms] > [16:37:42,331][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] Performance > suggestions for grid 'hadoop.HadoopMapReduceEmbeddedSelfTest1' (fix if > possible) > [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] To disable, set > -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true > [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] > [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] To start > Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] > [16:37:42,332][INFO ][main][HadoopMapReduceEmbeddedSelfTest1] > >>> +---------------------------------+ > >>> 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=7A924C9A-3284-476F-AD7C-6DDC7D6B3EDD, order=2] > >>> Local node addresses: [/127.0.0.1] > >>> Local ports: TCP:45191 TCP:47501 > >>> Ignite documentation: http://ignite.incubator.apache.org > [16:37:42,333][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=2, > nodes=2, CPUs=4, heap=2.8GB] > [16:37:42,348][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] > >>> __________ ________________ > >>> / _/ ___/ |/ / _/_ __/ __/ > >>> _/ // (7 7 // / / / / _/ > >>> /___/\___/_/|_/___/ /_/ /___/ > >>> > >>> 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: > teamcity > [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Language > runtime: Java Platform API Specification ver. 1.7 > [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] VM information: > Java(TM) SE Runtime Environment 1.7.0_67-b01 Oracle Corporation Java > HotSpot(TM) 64-Bit Server VM 24.65-b04 > [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] VM total > memory: 2.8GB > [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Remote > Management [restart: off, REST: off, JMX (remote: off)] > [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] > IGNITE_HOME=/usr/local/TeamCityAgent/work/c326f6bf1927b309 > [16:37:42,350][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] VM arguments: > [-DJAVA_HOME=/usr/lib/jvm/java-7-oracle, -DMCAST_GRP=229.17.1.2, > -Dagent.home.dir=/usr/local/TeamCityAgent, -Dagent.name=teamcity-7, > -Dagent.ownPort=9090, -Dagent.work.dir=/usr/local/TeamCityAgent/work, > -Dbuild.number=1560, > -Dbuild.vcs.number=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -Dbuild.vcs.number.1=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -Dbuild.vcs.number.IgniteApache=cb074fe98614907b0dd20fca71a44beb2dc5d8f4, > -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, > -Dhadoop.version=2.5.2, > -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=663, > -Dteamcity.agent.dotnet.agent_url=http://localhost:9090/RPC2, > -Dteamcity.agent.dotnet.build_id=400410, > -Dteamcity.auth.password=nu1IIi3SWEb8NNhLDhghzpZjQSPWkYV3, > -Dteamcity.auth.userId=TeamCityBuildId=400410, > -Dteamcity.build.changedFiles.file=/usr/local/TeamCityAgent/temp/buildTmp/changedFiles6192942218181221816.txt, > -Dteamcity.build.checkoutDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -Dteamcity.build.id=400410, > -Dteamcity.build.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.build1936164090676629649.properties, > -Dteamcity.build.tempDir=/usr/local/TeamCityAgent/temp/buildTmp, > -Dteamcity.build.workingDir=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -Dteamcity.buildConfName=Ignite Hadoop, > -Dteamcity.buildType.id=Ignite_Hadoop, > -Dteamcity.configuration.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.config1651692759705343482.properties, > -Dteamcity.idea.home=/usr/local/TeamCityAgent/plugins/idea, > -Dteamcity.maven.watcher.home=/usr/local/TeamCityAgent/plugins/mavenPlugin/maven-watcher, > -Dteamcity.projectName=Ignite, > -Dteamcity.runner.properties.file=/usr/local/TeamCityAgent/temp/buildTmp/teamcity.runner4645183226190514392.properties, > > -Dteamcity.tests.recentlyFailedTests.file=/usr/local/TeamCityAgent/temp/buildTmp/testsToRunFirst6297047454049666673.txt, > -Dteamcity.version=8.1.4 (build 30168), -ea, -XX:MaxPermSize=2g, -Xms1g, > -Xmx3g, -XX:+HeapDumpOnOutOfMemoryError, -XX:+AggressiveOpts, > -DIGNITE_TEST_HOME=/usr/local/TeamCityAgent/work/c326f6bf1927b309, > -DIGNITE_UPDATE_NOTIFIER=false, -DIGNITE_NO_DISCO_ORDER=true, > -DIGNITE_QUIET=false, -XX:+PrintGC, -XX:+PrintGCDetails, > -XX:+PrintGCTimeStamps] > [16:37:42,351][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Configured > caches ['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 > licenses 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] > [16:37:42,379][INFO ][main][TcpCommunicationSpi] Successfully bound to TCP > port [port=45192, locHost=/127.0.0.1] > [16:37:42,411][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Security status > [authentication=off] > [16:37:42,432][INFO ][main][HadoopProcessor] HADOOP_HOME is set to > /usr/local/TeamCityAgent/temp/buildTmp/__hadoop/hadoop-2.5.2 > [16:37:42,453][INFO ][main][TcpDiscoverySpi] Successfully bound to TCP port > [port=47502, localHost=/127.0.0.1] > [16:37:42,598][INFO > ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDiscoveryManager] > Added new node to topology: TcpDiscoveryNode > [id=d313c493-0cbc-4ada-a03e-e48802166818, addrs=[127.0.0.1], > sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, loc=false, > ver=1.0.0#19700101-sha1:00000000, isClient=false] > [16:37:42,599][INFO > ][disco-event-worker-#19989%hadoop.HadoopMapReduceEmbeddedSelfTest0%][GridDiscoveryManager] > Topology snapshot [ver=3, nodes=3, CPUs=4, heap=2.8GB] > [16:37:42,600][INFO > ][disco-event-worker-#20066%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDiscoveryManager] > Added new node to topology: TcpDiscoveryNode > [id=d313c493-0cbc-4ada-a03e-e48802166818, addrs=[127.0.0.1], > sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, loc=false, > ver=1.0.0#19700101-sha1:00000000, isClient=false] > [16:37:42,600][INFO > ][disco-event-worker-#20066%hadoop.HadoopMapReduceEmbeddedSelfTest1%][GridDiscoveryManager] > Topology snapshot [ver=3, nodes=3, CPUs=4, heap=2.8GB] > [16:37:42,612][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-marshaller-sys-cache, mode=REPLICATED] > [16:37:42,617][INFO ][main][GridCacheProcessor] Started cache [name=meta, > mode=REPLICATED] > [16:37:42,623][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-hadoop-mr-sys-cache, mode=REPLICATED] > [16:37:42,632][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-atomics-sys-cache, mode=PARTITIONED] > [16:37:42,639][INFO ][main][GridCacheProcessor] Started cache [name=data, > mode=PARTITIONED] > [16:37:42,644][INFO ][main][GridCacheProcessor] Started cache > [name=ignite-sys-cache, mode=REPLICATED] > [16:37:43,306][INFO ][main][GridDhtPreloader] <ignite-marshaller-sys-cache> > Starting rebalancing in SYNC mode: ignite-marshaller-sys-cache > [16:37:43,306][INFO ][main][GridDhtPreloader] <ignite-hadoop-mr-sys-cache> > Starting rebalancing in SYNC mode: ignite-hadoop-mr-sys-cache > [16:37:43,306][INFO ][main][GridDhtPreloader] <meta> Starting rebalancing in > ASYNC mode: meta > [16:37:43,307][INFO ][main][GridDhtPreloader] <ignite-atomics-sys-cache> > Starting rebalancing in SYNC mode: ignite-atomics-sys-cache > [16:37:43,308][INFO ][main][GridDhtPreloader] <data> Starting rebalancing in > ASYNC mode: data > [16:37:43,308][INFO ][main][GridDhtPreloader] <ignite-sys-cache> Starting > rebalancing in SYNC mode: ignite-sys-cache > [16:37:43,476][INFO > ][preloader-demand-worker-#20167%hadoop.HadoopMapReduceEmbeddedSelfTest2%][GridDhtPreloader] > <ignite-marshaller-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-marshaller-sys-cache, time=171 ms] > [16:37:43,496][INFO > ][preloader-demand-worker-#20181%hadoop.HadoopMapReduceEmbeddedSelfTest2%][GridDhtPreloader] > <ignite-atomics-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-atomics-sys-cache, time=193 ms] > [16:37:43,500][INFO > ][preloader-demand-worker-#20193%hadoop.HadoopMapReduceEmbeddedSelfTest2%][GridDhtPreloader] > <ignite-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-sys-cache, time=193 ms] > [16:37:43,505][INFO > ][preloader-demand-worker-#20188%hadoop.HadoopMapReduceEmbeddedSelfTest2%][GridDhtPreloader] > <data> Completed rebalancing in ASYNC mode [cache=data, time=193 ms] > [16:37:43,520][INFO > ][preloader-demand-worker-#20173%hadoop.HadoopMapReduceEmbeddedSelfTest2%][GridDhtPreloader] > <ignite-hadoop-mr-sys-cache> Completed rebalancing in SYNC mode > [cache=ignite-hadoop-mr-sys-cache, time=213 ms] > [16:37:43,525][INFO > ][preloader-demand-worker-#20178%hadoop.HadoopMapReduceEmbeddedSelfTest2%][GridDhtPreloader] > <meta> Completed rebalancing in ASYNC mode [cache=meta, time=213 ms] > [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] Performance > suggestions for grid 'hadoop.HadoopMapReduceEmbeddedSelfTest2' (fix if > possible) > [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] To disable, set > -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true > [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] > [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] To start > Console Management & Monitoring run ignitevisorcmd.{sh|bat} > [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] > [16:37:43,548][INFO ][main][HadoopMapReduceEmbeddedSelfTest2] > >>> +---------------------------------+ > >>> 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=D313C493-0CBC-4ADA-A03E-E48802166818, order=3] > >>> Local node addresses: [/127.0.0.1] > >>> Local ports: TCP:45192 TCP:47502 > >>> Ignite documentation: http://ignite.incubator.apache.org > [16:37:43,549][INFO ][main][GridDiscoveryManager] Topology snapshot [ver=3, > nodes=3, CPUs=4, heap=2.8GB] > [16:37:44,371][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:38:34,058][INFO > ][Hadoop-task-3642bd08-a856-457f-b7de-1eaabc4de4da_1-REDUCE-0-0-#20618%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitter] > Saved output of task > 'attempt_3642bd08-a856-457f-b7de-1eaabc4de4da_0001_r_000000_0' to > igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_temporary/0/task_3642bd08-a856-457f-b7de-1eaabc4de4da_0001_r_000000 > [16:38:35,397][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:39:25,057][INFO > ][Hadoop-task-afecac89-ef45-4a3d-988c-c5c16f231d52_1-REDUCE-0-0-#20969%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitter] > Saved output of task > 'attempt_afecac89-ef45-4a3d-988c-c5c16f231d52_0001_r_000000_0' to > igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_temporary/0/task_afecac89-ef45-4a3d-988c-c5c16f231d52_0001_r_000000 > [16:39:26,206][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:40:16,204][INFO > ][Hadoop-task-5a132c34-b235-4468-8c4c-70e4bc047897_1-REDUCE-0-0-#21316%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitter] > Saved output of task > 'attempt_5a132c34-b235-4468-8c4c-70e4bc047897_0001_r_000000_0' to > igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_temporary/0/task_5a132c34-b235-4468-8c4c-70e4bc047897_0001_r_000000 > [16:40:17,783][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:41:07,878][INFO > ][Hadoop-task-37d89f9d-7bf4-4bfc-acfe-8bdf2dfec0c1_1-REDUCE-0-0-#21663%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitter] > Saved output of task > 'attempt_37d89f9d-7bf4-4bfc-acfe-8bdf2dfec0c1_0001_r_000000_0' to > igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_temporary/0/task_37d89f9d-7bf4-4bfc-acfe-8bdf2dfec0c1_0001_r_000000 > [16:41:09,319][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:41:59,352][INFO > ][Hadoop-task-e9089c34-7864-412e-9507-2038b6fb0d6e_1-REDUCE-0-0-#22010%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitter] > Saved output of task > 'attempt_e9089c34-7864-412e-9507-2038b6fb0d6e_0001_r_000000_0' to > igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_temporary/0/task_e9089c34-7864-412e-9507-2038b6fb0d6e_0001_r_000000 > [16:42:01,014][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:42:39,194][INFO > ][Hadoop-task-b42447f3-7f75-4639-864f-72854bb81982_1-REDUCE-0-0-#22357%hadoop.HadoopMapReduceEmbeddedSelfTest0%][FileOutputCommitter] > Saved output of task > 'attempt_b42447f3-7f75-4639-864f-72854bb81982_0001_r_000000_0' to > igfs://:hadoop.HadoopMapReduceEmbeddedSelfTest0@/output/_temporary/0/task_b42447f3-7f75-4639-864f-72854bb81982_0001_r_000000 > [16:42:40,250][INFO ][test-runner][FileInputFormat] Total input paths to > process : 1 > [16:42:43] (wrn) Thread dump at 2015/03/27 16:42:43 MSK > Thread > [name="Hadoop-task-ef8347bb-a9de-454a-8823-60e3b3c513b7_1-MAP-232-0-#22374%hadoop.HadoopMapReduceEmbeddedSelfTest2%", > id=30879, state=RUNNABLE, blockCnt=0, waitCnt=4] > at java.util.StringTokenizer.skipDelimiters(StringTokenizer.java:245) > at java.util.StringTokenizer.hasMoreTokens(StringTokenizer.java:323) > at > o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(HadoopWordCount2Mapper.java:50) > at > o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(HadoopWordCount2Mapper.java:30) > at org.apache.hadoop.mapreduce.Mapper.run(Mapper.java:145) > at > o.a.i.i.processors.hadoop.v2.HadoopV2MapTask.run0(HadoopV2MapTask.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(HadoopV2TaskContext.java:193) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.runTask(HadoopRunnableTask.java:176) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(HadoopRunnableTask.java:120) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(HadoopRunnableTask.java:36) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopExecutorService$2.body(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="hadoop-shuffle-ef8347bb-a9de-454a-8823-60e3b3c513b7_1-#22373%hadoop.HadoopMapReduceEmbeddedSelfTest2%", > id=30878, state=TIMED_WAITING, blockCnt=0, waitCnt=28] > at java.lang.Thread.sleep(Native Method) > at > o.a.i.i.processors.hadoop.shuffle.HadoopShuffleJob$1.body(HadoopShuffleJob.java:159) > at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:107) > at java.lang.Thread.run(Thread.java:745) > Thread > [name="Hadoop-task-ef8347bb-a9de-454a-8823-60e3b3c513b7_1-MAP-5-0-#22372%hadoop.HadoopMapReduceEmbeddedSelfTest0%", > id=30877, state=RUNNABLE, blockCnt=0, waitCnt=6] > at org.apache.hadoop.io.Text.readFields(Text.java:291) > at > o.a.i.i.processors.hadoop.v2.HadoopWritableSerialization.read(HadoopWritableSerialization.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$Reader.readKey(HadoopSkipList.java:260) > at > o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$AdderImpl.cmp(HadoopSkipList.java:471) > at > o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$AdderImpl.add(HadoopSkipList.java:417) > at > o.a.i.i.processors.hadoop.shuffle.collections.HadoopSkipList$AdderImpl.write(HadoopSkipList.java:300) > at > o.a.i.i.processors.hadoop.v2.HadoopV2Context.write(HadoopV2Context.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(HadoopWordCount2Mapper.java:53) > at > o.a.i.i.processors.hadoop.examples.HadoopWordCount2Mapper.map(HadoopWordCount2Mapper.java:30) > at org.apache.hadoop.mapreduce.Mapper.run(Mapper.java:145) > at > o.a.i.i.processors.hadoop.v2.HadoopV2MapTask.run0(HadoopV2MapTask.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(HadoopV2TaskContext.java:193) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.runTask(HadoopRunnableTask.java:176) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(HadoopRunnableTask.java:120) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopRunnableTask.call(HadoopRunnableTask.java:36) > at > o.a.i.i.processors.hadoop.taskexecutor.HadoopExecutorService$2.body(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)