You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2009/06/08 20:00:52 UTC
Build failed in Hudson: Hadoop-trunk #860
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/860/
------------------------------------------
[...truncated 411016 lines...]
[junit] 09/06/08 18:18:59 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:18:59 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:18:59 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:18:59 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:18:59 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:18:59 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:00 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:00 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:01 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:01 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:01 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:01 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:01 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:01 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:02 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:02 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:02 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:02 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:02 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:02 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:03 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:03 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:04 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:04 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:04 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:04 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:04 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:05 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:05 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:05 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:05 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:05 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:05 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:06 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:06 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:07 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:08 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:09 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:09 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:09 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:10 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:10 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:10 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:10 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:10 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:10 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:11 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:11 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:11 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:11 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:11 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:11 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:12 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:12 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:13 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:13 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:13 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:13 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:13 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:14 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:14 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:14 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:14 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:14 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:14 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:15 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:15 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:16 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:16 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:16 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:16 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:16 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:16 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:17 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:17 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:17 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:17 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:17 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:17 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:18 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:18 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:19 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:19 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:19 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:19 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:19 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:20 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:20 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:20 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:20 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:20 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:20 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:21 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:21 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:22 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:23 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:24 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:24 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:24 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:25 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:25 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:25 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:25 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:25 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:25 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:26 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:26 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:26 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:26 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:26 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:26 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:27 INFO mapred.TaskTracker: attempt_200906081809_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:27 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:28 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:28 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:28 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:28 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:28 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:29 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:29 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:29 INFO mapred.TaskTracker: attempt_200906081809_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:29 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000001_0 0.0% map > map
[junit] 09/06/08 18:19:29 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:29 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000002_0 0.0% map > map
[junit] 09/06/08 18:19:30 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:30 INFO mapred.TaskTracker: attempt_200906081809_0005_m_000003_0 0.0% map > map
[junit] 09/06/08 18:19:31 INFO mapred.TaskTracker: attempt_200906081809_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/08 18:19:31 INFO mapred.TaskTracker: attempt_200906081809_0003_m_000000_0 0.0% map > map
[junit] 09/06/08 18:19:31 INFO mapred.TaskTracker: attempt_200906081809_0004_m_000000_0 0.0% map > map
[junit] Running org.apache.hadoop.mapred.TestQueueCapacities
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestQueueCapacities FAILED (timeout)
checkfailure:
check-contrib:
init:
[echo] contrib: mrunit
init-contrib:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.0.0-rc2/ivy-2.0.0-rc2.jar
[get] To: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivy-2.0.0-rc2.jar
[get] Not modified - so not downloaded
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivysettings.xml
ivy-resolve-common:
[ivy:resolve] :: resolving dependencies :: org.apache.hadoop#mrunit;working@vesta.apache.org
[ivy:resolve] confs: [common]
[ivy:resolve] found commons-logging#commons-logging;1.0.4 in maven2
[ivy:resolve] found log4j#log4j;1.2.15 in maven2
[ivy:resolve] found junit#junit;4.5 in maven2
[ivy:resolve] :: resolution report :: resolve 18ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| common | 3 | 0 | 0 | 0 || 3 | 0 |
---------------------------------------------------------------------
ivy-retrieve-common:
[ivy:retrieve] :: retrieving :: org.apache.hadoop#mrunit [sync]
[ivy:retrieve] confs: [common]
[ivy:retrieve] 0 artifacts copied, 3 already retrieved (0kB/1ms)
No ivy:settings found for the default reference 'ivy.instance'. A default instance will be used
DEPRECATED: 'ivy.conf.file' is deprecated, use 'ivy.settings.file' instead
:: loading settings :: file = http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivysettings.xml
compile:
[echo] contrib: mrunit
compile-examples:
compile-test:
[echo] contrib: mrunit
[javac] Compiling 9 source files to http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/mrunit/test
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
test:
[echo] contrib: mrunit
[delete] Deleting directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/mrunit/test/logs
[mkdir] Created dir: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/mrunit/test/logs
[junit] Running org.apache.hadoop.mrunit.TestExample
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.2 sec
[junit] Running org.apache.hadoop.mrunit.TestMapDriver
[junit] 09/06/08 18:19:33 ERROR mrunit.TestDriver: Expected no outputs; got 1 outputs.
[junit] 09/06/08 18:19:33 ERROR mrunit.TestDriver: Received unexpected output (null, null)
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.211 sec
[junit] Running org.apache.hadoop.mrunit.TestMapReduceDriver
[junit] 09/06/08 18:19:33 ERROR mrunit.TestDriver: Missing expected output (foo, 52) at position 0
[junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.213 sec
[junit] Running org.apache.hadoop.mrunit.TestReduceDriver
[junit] 09/06/08 18:19:34 ERROR mrunit.TestDriver: Expected no outputs; got 1 outputs.
[junit] 09/06/08 18:19:34 ERROR mrunit.TestDriver: Received unexpected output (null, 0)
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.212 sec
[junit] Running org.apache.hadoop.mrunit.TestTestDriver
[junit] Tests run: 19, Failures: 0, Errors: 0, Time elapsed: 0.196 sec
[junit] Running org.apache.hadoop.mrunit.mock.TestMockReporter
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.107 sec
[junit] Running org.apache.hadoop.mrunit.types.TestPair
[junit] Tests run: 20, Failures: 0, Errors: 0, Time elapsed: 0.069 sec
checkfailure:
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1047: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1028: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/src/contrib/build.xml :60: Tests failed!
Total time: 243 minutes 9 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...
Build failed in Hudson: Hadoop-trunk #862
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/862/changes
Changes:
[gkesavan] HADOOP-5989. Fix streaming test failure. (gkesavan)
[stevel] HADOOP-4768 Dynamic Priority Scheduler that allows queue shares to be controlled dynamically by a currency
------------------------------------------
[...truncated 458957 lines...]
[junit] 09/06/11 11:33:13 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:13 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:13 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000003_0 0.0% map > map
[junit] 09/06/11 11:33:13 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:14 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:14 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:14 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:14 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:14 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:14 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:15 INFO mapred.TaskTracker: attempt_200906111123_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:15 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:15 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:16 INFO mapred.TaskTracker: attempt_200906111123_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:16 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:16 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:16 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000003_0 0.0% map > map
[junit] 09/06/11 11:33:16 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:17 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:17 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:17 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:17 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:17 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:17 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:18 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:18 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:18 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:19 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:19 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:19 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000003_0 0.0% map > map
[junit] 09/06/11 11:33:19 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:20 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:20 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:20 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:20 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:20 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:20 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:21 INFO mapred.TaskTracker: attempt_200906111123_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:21 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:21 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:22 INFO mapred.TaskTracker: attempt_200906111123_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:22 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:22 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:22 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000003_0 0.0% map > map
[junit] 09/06/11 11:33:22 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:23 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:24 INFO mapred.TaskTracker: attempt_200906111123_0003_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:24 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:24 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:24 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:25 INFO mapred.TaskTracker: attempt_200906111123_0003_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:25 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000000_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:25 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:25 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000003_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:25 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000003_0 0.0% map > map
[junit] 09/06/11 11:33:25 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:26 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:26 INFO mapred.TaskTracker: attempt_200906111123_0004_r_000002_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:26 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:26 INFO mapred.TaskTracker: attempt_200906111123_0003_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:26 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000001_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:26 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:27 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000000_0 0.0% map > map
[junit] 09/06/11 11:33:27 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000002_0 0.0% reduce > copy >
[junit] 09/06/11 11:33:27 INFO mapred.TaskTracker: attempt_200906111123_0005_m_000002_0 0.0% map > map
[junit] 09/06/11 11:33:28 INFO mapred.TaskTracker: attempt_200906111123_0004_m_000001_0 0.0% map > map
[junit] 09/06/11 11:33:28 INFO mapred.TaskTracker: attempt_200906111123_0005_r_000003_0 0.0% reduce > copy >
[junit] Running org.apache.hadoop.mapred.TestQueueCapacities
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestQueueCapacities FAILED (timeout)
checkfailure:
[touch] Creating http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/testsfailed
check-contrib:
init:
[echo] contrib: mrunit
init-contrib:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.0.0-rc2/ivy-2.0.0-rc2.jar
[get] To: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivy-2.0.0-rc2.jar
[get] Not modified - so not downloaded
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivysettings.xml
ivy-resolve-common:
[ivy:resolve] :: resolving dependencies :: org.apache.hadoop#mrunit;working@vesta.apache.org
[ivy:resolve] confs: [common]
[ivy:resolve] found commons-logging#commons-logging;1.0.4 in maven2
[ivy:resolve] found log4j#log4j;1.2.15 in maven2
[ivy:resolve] found junit#junit;4.5 in maven2
[ivy:resolve] :: resolution report :: resolve 19ms :: artifacts dl 2ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| common | 3 | 0 | 0 | 0 || 3 | 0 |
---------------------------------------------------------------------
ivy-retrieve-common:
[ivy:retrieve] :: retrieving :: org.apache.hadoop#mrunit [sync]
[ivy:retrieve] confs: [common]
[ivy:retrieve] 0 artifacts copied, 3 already retrieved (0kB/2ms)
No ivy:settings found for the default reference 'ivy.instance'. A default instance will be used
DEPRECATED: 'ivy.conf.file' is deprecated, use 'ivy.settings.file' instead
:: loading settings :: file = http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivysettings.xml
compile:
[echo] contrib: mrunit
compile-examples:
compile-test:
[echo] contrib: mrunit
[javac] Compiling 9 source files to http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/mrunit/test
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
test:
[echo] contrib: mrunit
[delete] Deleting directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/mrunit/test/logs
[mkdir] Created dir: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/mrunit/test/logs
[junit] Running org.apache.hadoop.mrunit.TestExample
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.203 sec
[junit] Running org.apache.hadoop.mrunit.TestMapDriver
[junit] 09/06/11 11:33:30 ERROR mrunit.TestDriver: Expected no outputs; got 1 outputs.
[junit] 09/06/11 11:33:30 ERROR mrunit.TestDriver: Received unexpected output (null, null)
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.206 sec
[junit] Running org.apache.hadoop.mrunit.TestMapReduceDriver
[junit] 09/06/11 11:33:30 ERROR mrunit.TestDriver: Missing expected output (foo, 52) at position 0
[junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.211 sec
[junit] Running org.apache.hadoop.mrunit.TestReduceDriver
[junit] 09/06/11 11:33:31 ERROR mrunit.TestDriver: Expected no outputs; got 1 outputs.
[junit] 09/06/11 11:33:31 ERROR mrunit.TestDriver: Received unexpected output (null, 0)
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.203 sec
[junit] Running org.apache.hadoop.mrunit.TestTestDriver
[junit] Tests run: 19, Failures: 0, Errors: 0, Time elapsed: 0.202 sec
[junit] Running org.apache.hadoop.mrunit.mock.TestMockReporter
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.105 sec
[junit] Running org.apache.hadoop.mrunit.types.TestPair
[junit] Tests run: 20, Failures: 0, Errors: 0, Time elapsed: 0.066 sec
checkfailure:
check-contrib:
init:
[echo] contrib: dynamic-priority
init-contrib:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.0.0-rc2/ivy-2.0.0-rc2.jar
[get] To: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivy-2.0.0-rc2.jar
[get] Not modified - so not downloaded
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivysettings.xml
ivy-resolve-common:
[ivy:resolve] :: resolving dependencies :: org.apache.hadoop#dynamic-priority;working@vesta.apache.org
[ivy:resolve] confs: [common]
[ivy:resolve] found commons-logging#commons-logging;1.0.4 in maven2
[ivy:resolve] found log4j#log4j;1.2.15 in maven2
[ivy:resolve] found commons-httpclient#commons-httpclient;3.0.1 in maven2
[ivy:resolve] found commons-codec#commons-codec;1.3 in maven2
[ivy:resolve] found xmlenc#xmlenc;0.52 in maven2
[ivy:resolve] found net.java.dev.jets3t#jets3t;0.6.1 in maven2
[ivy:resolve] found commons-net#commons-net;1.4.1 in maven2
[ivy:resolve] found org.mortbay.jetty#servlet-api-2.5;6.1.14 in maven2
[ivy:resolve] found oro#oro;2.0.8 in maven2
[ivy:resolve] found org.mortbay.jetty#jetty;6.1.14 in maven2
[ivy:resolve] found org.mortbay.jetty#jetty-util;6.1.14 in maven2
[ivy:resolve] found tomcat#jasper-runtime;5.5.12 in maven2
[ivy:resolve] found tomcat#jasper-compiler;5.5.12 in maven2
[ivy:resolve] found commons-el#commons-el;1.0 in maven2
[ivy:resolve] found junit#junit;4.5 in maven2
[ivy:resolve] found commons-logging#commons-logging-api;1.0.4 in maven2
[ivy:resolve] found org.slf4j#slf4j-api;1.4.3 in maven2
[ivy:resolve] found org.slf4j#slf4j-log4j12;1.4.3 in maven2
[ivy:resolve] :: resolution report :: resolve 116ms :: artifacts dl 7ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| common | 18 | 0 | 0 | 0 || 18 | 0 |
---------------------------------------------------------------------
ivy-retrieve-common:
[ivy:retrieve] :: retrieving :: org.apache.hadoop#dynamic-priority [sync]
[ivy:retrieve] confs: [common]
[ivy:retrieve] 0 artifacts copied, 18 already retrieved (0kB/5ms)
No ivy:settings found for the default reference 'ivy.instance'. A default instance will be used
DEPRECATED: 'ivy.conf.file' is deprecated, use 'ivy.settings.file' instead
:: loading settings :: file = http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/ivy/ivysettings.xml
compile:
[echo] contrib: dynamic-priority
compile-examples:
compile-test:
[echo] contrib: dynamic-priority
[javac] Compiling 4 source files to http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/dynamic-priority/test
[javac] Note: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/src/contrib/dynamic-scheduler/src/test/org/apache/hadoop/mapred/BaseSchedulerTest.java uses or overrides a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
test:
[echo] contrib: dynamic-priority
[delete] Deleting directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/dynamic-priority/test/logs
[mkdir] Created dir: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/dynamic-priority/test/logs
[junit] 09/06/11 11:33:33 WARN conf.Configuration: DEPRECATED: hadoop-site.xml found in the classpath. Usage of hadoop-site.xml is deprecated. Instead use core-site.xml, mapred-site.xml and hdfs-site.xml to override properties of core-default.xml, mapred-default.xml and hdfs-default.xml respectively
[junit] Running org.apache.hadoop.mapred.TestDynamicScheduler
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.177 sec
[junit] 09/06/11 11:33:34 WARN conf.Configuration: DEPRECATED: hadoop-site.xml found in the classpath. Usage of hadoop-site.xml is deprecated. Instead use core-site.xml, mapred-site.xml and hdfs-site.xml to override properties of core-default.xml, mapred-default.xml and hdfs-default.xml respectively
[junit] Running org.apache.hadoop.mapred.TestPriorityScheduler
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.161 sec
checkfailure:
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1047: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1028: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/src/contrib/build.xml :61: Tests failed!
Total time: 281 minutes 46 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...
Build failed in Hudson: Hadoop-trunk #861
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/861/changes
Changes:
[sharad] HADOOP-5694. Change org.apache.hadoop.examples.dancing to use new mapreduce api. Contributed by Amareshwari Sriramadasu.
[szetszwo] HADOOP-5963. Remove an unnecessary exception catch in NNBench. Contributed by Boris Shkolnik
------------------------------------------
[...truncated 439840 lines...]
[junit] Generating rack names for tasktrackers
[junit] Generating host names for tasktrackers
[junit] 2009-06-09 18:46:24,973 INFO mapred.JobTracker (JobTracker.java:<init>(1558)) - Starting jobtracker with owner as hudson and supergroup as supergroup
[junit] 2009-06-09 18:46:24,974 INFO mapred.JobTracker (JobTracker.java:initializeTaskMemoryRelatedConfig(3855)) - Scheduler configured with (memSizeForMapSlotOnJT, memSizeForReduceSlotOnJT, limitMaxMemForMapTasks, limitMaxMemForReduceTasks) (-1, -1, -1, -1)
[junit] 2009-06-09 18:46:24,974 INFO util.HostsFileReader (HostsFileReader.java:refresh(76)) - Refreshing hosts (include/exclude) list
[junit] 2009-06-09 18:46:25,025 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=JobTracker, port=37582
[junit] 2009-06-09 18:46:25,039 INFO http.HttpServer (HttpServer.java:start(425)) - Port returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener on 0
[junit] 2009-06-09 18:46:25,039 INFO http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort() returned 48005 webServer.getConnectors()[0].getLocalPort() returned 48005
[junit] 2009-06-09 18:46:25,040 INFO http.HttpServer (HttpServer.java:start(463)) - Jetty bound to port 48005
[junit] 2009-06-09 18:46:25,040 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2009-06-09 18:46:25,123 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:48005
[junit] 2009-06-09 18:46:25,127 INFO jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
[junit] 2009-06-09 18:46:25,128 INFO mapred.JobTracker (JobTracker.java:<init>(1671)) - JobTracker up at: 37582
[junit] 2009-06-09 18:46:25,128 INFO mapred.JobTracker (JobTracker.java:<init>(1675)) - JobTracker webserver: 48005
[junit] 2009-06-09 18:46:25,139 INFO mapred.JobTracker (JobTracker.java:<init>(1713)) - Cleaning up the system directory
[junit] 2009-06-09 18:46:25,386 INFO mapred.JobTracker (JobTracker.java:refreshHosts(3688)) - Refreshing hosts information
[junit] 2009-06-09 18:46:25,410 INFO util.HostsFileReader (HostsFileReader.java:setIncludesFile(100)) - Setting the includes file to
[junit] 2009-06-09 18:46:25,410 INFO util.HostsFileReader (HostsFileReader.java:setExcludesFile(105)) - Setting the excludes file to hosts.exclude
[junit] 2009-06-09 18:46:25,410 INFO util.HostsFileReader (HostsFileReader.java:refresh(76)) - Refreshing hosts (include/exclude) list
[junit] 2009-06-09 18:46:25,411 INFO mapred.JobTracker (JobTracker.java:decommissionNodes(3710)) - Decommissioning 0 nodes
[junit] 2009-06-09 18:46:25,411 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-09 18:46:25,412 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 37582: starting
[junit] 2009-06-09 18:46:25,413 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 37582: starting
[junit] 2009-06-09 18:46:25,412 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 37582: starting
[junit] 2009-06-09 18:46:25,411 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 37582: starting
[junit] 2009-06-09 18:46:25,458 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 9 on 37582: starting
[junit] 2009-06-09 18:46:25,458 INFO mapred.JobTracker (JobTracker.java:offerService(1894)) - Starting RUNNING
[junit] 2009-06-09 18:46:25,457 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 7 on 37582: starting
[junit] 2009-06-09 18:46:25,457 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 6 on 37582: starting
[junit] 2009-06-09 18:46:25,456 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 5 on 37582: starting
[junit] 2009-06-09 18:46:25,422 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 4 on 37582: starting
[junit] 2009-06-09 18:46:25,422 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 3 on 37582: starting
[junit] 2009-06-09 18:46:25,459 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 8 on 37582: starting
[junit] 2009-06-09 18:46:25,923 INFO mapred.MiniMRCluster (MiniMRCluster.java:<init>(176)) - mapred.local.dir is http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/0_0
[junit] 2009-06-09 18:46:30,939 INFO http.HttpServer (HttpServer.java:start(425)) - Port returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener on 0
[junit] 2009-06-09 18:46:30,940 INFO http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort() returned 34095 webServer.getConnectors()[0].getLocalPort() returned 34095
[junit] 2009-06-09 18:46:30,940 INFO http.HttpServer (HttpServer.java:start(463)) - Jetty bound to port 34095
[junit] 2009-06-09 18:46:30,940 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2009-06-09 18:46:31,003 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:34095
[junit] 2009-06-09 18:46:31,005 INFO jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
[junit] 2009-06-09 18:46:31,008 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=TaskTracker, port=49599
[junit] 2009-06-09 18:46:31,009 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-09 18:46:31,009 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 49599: starting
[junit] 2009-06-09 18:46:31,010 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 49599: starting
[junit] 2009-06-09 18:46:31,010 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 3 on 49599: starting
[junit] 2009-06-09 18:46:31,010 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 49599: starting
[junit] 2009-06-09 18:46:31,009 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 49599: starting
[junit] 2009-06-09 18:46:31,070 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(51)) - setsid exited with exit code 0
[junit] 2009-06-09 18:46:31,073 INFO mapred.IndexCache (IndexCache.java:<init>(46)) - IndexCache created with max memory = 10485760
[junit] 2009-06-09 18:46:31,107 INFO net.NetworkTopology (NetworkTopology.java:add(327)) - Adding a new node: /default-rack/host0.foo.com
[junit] 2009-06-09 18:46:31,109 INFO mapred.MiniMRCluster (MiniMRCluster.java:<init>(176)) - mapred.local.dir is http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0
[junit] 2009-06-09 18:46:31,109 INFO mapred.JobTracker (JobTracker.java:addNewTracker(2426)) - Adding tracker tracker_host0.foo.com:localhost/127.0.0.1:49599 to host host0.foo.com
[junit] 2009-06-09 18:46:36,127 INFO http.HttpServer (HttpServer.java:start(425)) - Port returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener on 0
[junit] 2009-06-09 18:46:36,128 INFO http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort() returned 44569 webServer.getConnectors()[0].getLocalPort() returned 44569
[junit] 2009-06-09 18:46:36,128 INFO http.HttpServer (HttpServer.java:start(463)) - Jetty bound to port 44569
[junit] 2009-06-09 18:46:36,128 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2009-06-09 18:46:36,229 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:44569
[junit] 2009-06-09 18:46:36,231 INFO jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
[junit] 2009-06-09 18:46:36,232 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=TaskTracker, port=60626
[junit] 2009-06-09 18:46:36,233 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-09 18:46:36,233 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 60626: starting
[junit] 2009-06-09 18:46:36,233 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 60626: starting
[junit] 2009-06-09 18:46:36,235 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 3 on 60626: starting
[junit] 2009-06-09 18:46:36,235 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 60626: starting
[junit] 2009-06-09 18:46:36,233 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 60626: starting
[junit] 2009-06-09 18:46:36,250 INFO mapred.IndexCache (IndexCache.java:<init>(46)) - IndexCache created with max memory = 10485760
[junit] 2009-06-09 18:46:36,362 INFO net.NetworkTopology (NetworkTopology.java:add(327)) - Adding a new node: /default-rack/host1.foo.com
[junit] 2009-06-09 18:46:36,363 INFO mapred.JobTracker (JobTracker.java:addNewTracker(2426)) - Adding tracker tracker_host1.foo.com:localhost/127.0.0.1:60626 to host host1.foo.com
[junit] rootdir = /test/testDistCh
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-09 18:46 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-09 18:46 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-09 18:46 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub0
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub0/f4
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub1/f5
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub1/f6
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub2
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub2/f7
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub2/f8
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-06-09 18:46 /test/testDistCh/sub3/f10
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub3/f9
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub4
[junit]
[junit] args=[/test/testDistCh/sub2:::030]
[junit] newstatus=[hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:----wx---,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x]
[junit] 2009-06-09 18:46:37,111 INFO tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub2:null:null:----wx---]
[junit] 2009-06-09 18:46:37,112 INFO tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
[junit] 2009-06-09 18:46:37,156 INFO tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:43516/user/hudson/build/test/mapred/system/distch_gehcbr
[junit] 2009-06-09 18:46:37,159 INFO tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:43516/user/hudson/build/test/mapred/system/distch_gehcbr/_logs
[junit] 2009-06-09 18:46:37,419 INFO tools.DistTool (DistCh.java:setup(476)) - distch.op.count=3
[junit] 2009-06-09 18:46:37,425 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(551)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2009-06-09 18:46:37,471 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(673)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2009-06-09 18:46:37,483 INFO tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1, splits.size()=1
[junit] 2009-06-09 18:46:37,689 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/jobTracker/job_200906091846_0001.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-06-09 18:46:37,700 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1287)) - Running job: job_200906091846_0001
[junit] 2009-06-09 18:46:37,700 INFO mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:run(80)) - Initializing job_200906091846_0001
[junit] 2009-06-09 18:46:37,701 INFO mapred.JobInProgress (JobInProgress.java:initTasks(395)) - Initializing job_200906091846_0001
[junit] 2009-06-09 18:46:37,868 INFO mapred.JobInProgress (JobInProgress.java:initTasks(439)) - Input size for job job_200906091846_0001 = 368. Number of splits = 1
[junit] 2009-06-09 18:46:38,706 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1300)) - map 0% reduce 0%
[junit] 2009-06-09 18:46:39,389 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1962)) - Adding task 'attempt_200906091846_0001_m_000002_0' to tip task_200906091846_0001_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:60626'
[junit] 2009-06-09 18:46:39,468 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-06-09 18:46:39,533 INFO mapred.JvmManager (JvmManager.java:<init>(383)) - In JvmRunner constructed JVM ID: jvm_200906091846_0001_m_864244286
[junit] 2009-06-09 18:46:39,534 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(351)) - JVM Runner jvm_200906091846_0001_m_864244286 spawned.
[junit] 2009-06-09 18:46:40,649 WARN util.ProcessTree (ProcessTree.java:destroyProcessGroup(166)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-09 18:46:40,650 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(168)) - Killing all processes in the process group 3810 with SIGTERM. Exit code 1
[junit] 2009-06-09 18:46:42,398 INFO mapred.JobInProgress (JobInProgress.java:completedTask(2022)) - Task 'attempt_200906091846_0001_m_000002_0' has completed task_200906091846_0001_m_000002 successfully.
[junit] 2009-06-09 18:46:42,403 INFO mapred.JobInProgress (JobInProgress.java:findNewMapTask(1815)) - Choosing a non-local task task_200906091846_0001_m_000000
[junit] 2009-06-09 18:46:42,404 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1962)) - Adding task 'attempt_200906091846_0001_m_000000_0' to tip task_200906091846_0001_m_000000, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:60626'
[junit] 2009-06-09 18:46:42,409 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200906091846_0001_m_000002_0 done; removing files.
[junit] 2009-06-09 18:46:42,410 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200906091846_0001_m_000002_0 not found in cache
[junit] 2009-06-09 18:46:42,458 INFO mapred.JvmManager (JvmManager.java:<init>(383)) - In JvmRunner constructed JVM ID: jvm_200906091846_0001_m_789000441
[junit] 2009-06-09 18:46:42,460 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(351)) - JVM Runner jvm_200906091846_0001_m_789000441 spawned.
[junit] 2009-06-09 18:46:42,720 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1349)) - Task Id : attempt_200906091846_0001_m_000002_0, Status : SUCCEEDED
[junit] attempt_200906091846_0001_m_000002_0: 2009-06-09 18:46:40,239 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/attempt_200906091846_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906091846_0001_m_000002_0: 2009-06-09 18:46:40,256 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200906091846_0001_m_000002_0: 2009-06-09 18:46:40,366 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/attempt_200906091846_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906091846_0001_m_000002_0: 2009-06-09 18:46:40,431 INFO mapred.Task (Task.java:done(620)) - Task:attempt_200906091846_0001_m_000002_0 is done. And is in the process of commiting
[junit] attempt_200906091846_0001_m_000002_0: 2009-06-09 18:46:40,447 INFO mapred.Task (Task.java:sendDone(691)) - Task 'attempt_200906091846_0001_m_000002_0' done.
[junit] 2009-06-09 18:46:45,661 WARN util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(91)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-09 18:46:45,662 INFO util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(94)) - Killing process group3810 with SIGKILL. Exit code 1
[junit] 2009-06-09 18:46:45,662 INFO mapred.DefaultTaskController (DefaultTaskController.java:killTaskJVM(87)) - Process exited with exit code:0
[junit] 2009-06-09 18:46:45,662 INFO mapred.JvmManager (JvmManager.java:runChild(411)) - JVM : jvm_200906091846_0001_m_864244286 exited. Number of tasks it ran: 1
[junit] 2009-06-09 18:46:45,795 WARN util.ProcessTree (ProcessTree.java:destroyProcessGroup(166)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-09 18:46:45,795 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(168)) - Killing all processes in the process group 3859 with SIGTERM. Exit code 1
[junit] 2009-06-09 18:46:48,415 INFO mapred.JobInProgress (JobInProgress.java:completedTask(2022)) - Task 'attempt_200906091846_0001_m_000000_0' has completed task_200906091846_0001_m_000000 successfully.
[junit] 2009-06-09 18:46:48,419 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1962)) - Adding task 'attempt_200906091846_0001_m_000001_0' to tip task_200906091846_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:60626'
[junit] 2009-06-09 18:46:48,447 INFO mapred.JvmManager (JvmManager.java:<init>(383)) - In JvmRunner constructed JVM ID: jvm_200906091846_0001_m_-1309676680
[junit] 2009-06-09 18:46:48,448 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(351)) - JVM Runner jvm_200906091846_0001_m_-1309676680 spawned.
[junit] 2009-06-09 18:46:48,795 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1349)) - Task Id : attempt_200906091846_0001_m_000000_0, Status : SUCCEEDED
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:43,166 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/attempt_200906091846_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:43,183 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:43,277 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/attempt_200906091846_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:43,369 INFO mapred.MapTask (MapTask.java:runOldMapper(352)) - numReduceTasks: 0
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:43,499 INFO mapred.Task (Task.java:done(620)) - Task:attempt_200906091846_0001_m_000000_0 is done. And is in the process of commiting
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:45,508 INFO mapred.Task (Task.java:commit(720)) - Task attempt_200906091846_0001_m_000000_0 is allowed to commit now
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:45,593 INFO mapred.FileOutputCommitter (FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200906091846_0001_m_000000_0' to hdfs://localhost:43516/user/hudson/build/test/mapred/system/distch_gehcbr/_logs
[junit] attempt_200906091846_0001_m_000000_0: 2009-06-09 18:46:45,600 INFO mapred.Task (Task.java:sendDone(691)) - Task 'attempt_200906091846_0001_m_000000_0' done.
[junit] 2009-06-09 18:46:49,578 WARN util.ProcessTree (ProcessTree.java:destroyProcessGroup(166)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-09 18:46:49,578 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(168)) - Killing all processes in the process group 3914 with SIGTERM. Exit code 1
[junit] 2009-06-09 18:46:49,805 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1300)) - map 100% reduce 0%
[junit] 2009-06-09 18:46:50,813 WARN util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(91)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-09 18:46:50,813 INFO util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(94)) - Killing process group3859 with SIGKILL. Exit code 1
[junit] 2009-06-09 18:46:50,813 INFO mapred.DefaultTaskController (DefaultTaskController.java:killTaskJVM(87)) - Process exited with exit code:0
[junit] 2009-06-09 18:46:50,813 INFO mapred.JvmManager (JvmManager.java:runChild(411)) - JVM : jvm_200906091846_0001_m_789000441 exited. Number of tasks it ran: 1
[junit] 2009-06-09 18:46:51,424 INFO mapred.JobInProgress (JobInProgress.java:completedTask(2022)) - Task 'attempt_200906091846_0001_m_000001_0' has completed task_200906091846_0001_m_000001 successfully.
[junit] 2009-06-09 18:46:51,425 INFO mapred.JobInProgress (JobInProgress.java:jobComplete(2146)) - Job job_200906091846_0001 has completed successfully.
[junit] 2009-06-09 18:46:51,463 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077)) - Removed completed task 'attempt_200906091846_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:60626'
[junit] 2009-06-09 18:46:51,463 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077)) - Removed completed task 'attempt_200906091846_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:60626'
[junit] 2009-06-09 18:46:51,464 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077)) - Removed completed task 'attempt_200906091846_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:60626'
[junit] 2009-06-09 18:46:51,464 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200906091846_0001_m_000001_0 done; removing files.
[junit] 2009-06-09 18:46:51,465 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200906091846_0001_m_000001_0 not found in cache
[junit] 2009-06-09 18:46:51,466 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200906091846_0001_m_000000_0 done; removing files.
[junit] 2009-06-09 18:46:51,466 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200906091846_0001_m_000000_0 not found in cache
[junit] 2009-06-09 18:46:51,812 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1349)) - Task Id : attempt_200906091846_0001_m_000001_0, Status : SUCCEEDED
[junit] attempt_200906091846_0001_m_000001_0: 2009-06-09 18:46:49,150 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/attempt_200906091846_0001_m_000001_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906091846_0001_m_000001_0: 2009-06-09 18:46:49,167 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200906091846_0001_m_000001_0: 2009-06-09 18:46:49,264 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200906091846_0001/attempt_200906091846_0001_m_000001_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906091846_0001_m_000001_0: 2009-06-09 18:46:49,373 INFO mapred.Task (Task.java:done(620)) - Task:attempt_200906091846_0001_m_000001_0 is done. And is in the process of commiting
[junit] attempt_200906091846_0001_m_000001_0: 2009-06-09 18:46:49,380 INFO mapred.Task (Task.java:sendDone(691)) - Task 'attempt_200906091846_0001_m_000001_0' done.
[junit] 2009-06-09 18:46:51,816 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1355)) - Job complete: job_200906091846_0001
[junit] 2009-06-09 18:46:51,819 INFO mapred.JobClient (Counters.java:log(534)) - Counters: 7
[junit] 2009-06-09 18:46:51,819 INFO mapred.JobClient (Counters.java:log(536)) - Job Counters
[junit] 2009-06-09 18:46:51,820 INFO mapred.JobClient (Counters.java:log(538)) - Launched map tasks=1
[junit] 2009-06-09 18:46:51,820 INFO mapred.JobClient (Counters.java:log(536)) - FileInputFormatCounters
[junit] 2009-06-09 18:46:51,820 INFO mapred.JobClient (Counters.java:log(538)) - BYTES_READ=271
[junit] 2009-06-09 18:46:51,821 INFO mapred.JobClient (Counters.java:log(536)) - org.apache.hadoop.tools.DistCh$Counter
[junit] 2009-06-09 18:46:51,821 INFO mapred.JobClient (Counters.java:log(538)) - SUCCEED=3
[junit] 2009-06-09 18:46:51,821 INFO mapred.JobClient (Counters.java:log(536)) - FileSystemCounters
[junit] 2009-06-09 18:46:51,822 INFO mapred.JobClient (Counters.java:log(538)) - HDFS_BYTES_READ=368
[junit] 2009-06-09 18:46:51,822 INFO mapred.JobClient (Counters.java:log(536)) - Map-Reduce Framework
[junit] 2009-06-09 18:46:51,822 INFO mapred.JobClient (Counters.java:log(538)) - Map input records=3
[junit] 2009-06-09 18:46:51,823 INFO mapred.JobClient (Counters.java:log(538)) - Spilled Records=0
[junit] 2009-06-09 18:46:51,823 INFO mapred.JobClient (Counters.java:log(538)) - Map output records=0
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-09 18:46 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-09 18:46 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-09 18:46 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub0
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub0/f4
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub1/f5
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub1/f6
[junit] d----wx--- - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub2
[junit] -----w---- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub2/f7
[junit] -----w---- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub2/f8
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-06-09 18:46 /test/testDistCh/sub3/f10
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-09 18:46 /test/testDistCh/sub3/f9
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-09 18:46 /test/testDistCh/sub4
[junit]
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-09 18:46:51,866 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 34117
[junit] 2009-06-09 18:46:51,866 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 0 on 34117: exiting
[junit] 2009-06-09 18:46:51,867 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 2 on 34117: exiting
[junit] 2009-06-09 18:46:51,867 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 1 on 34117: exiting
[junit] 2009-06-09 18:46:51,868 INFO ipc.Server (Server.java:run(539)) - Stopping IPC Server Responder
[junit] 2009-06-09 18:46:51,868 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 34117
[junit] 2009-06-09 18:46:51,869 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-09 18:46:51,870 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 34117
[junit] Shutting down DataNode 0
[junit] 2009-06-09 18:46:51,908 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 44291
[junit] 2009-06-09 18:46:51,908 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 0 on 44291: exiting
[junit] 2009-06-09 18:46:51,909 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 44291
[junit] 2009-06-09 18:46:51,909 INFO ipc.Server (Server.java:run(539)) - Stopping IPC Server Responder
[junit] 2009-06-09 18:46:51,909 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 2 on 44291: exiting
[junit] 2009-06-09 18:46:51,909 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 1 on 44291: exiting
[junit] 2009-06-09 18:46:51,910 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-09 18:46:51,910 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 44291
[junit] 2009-06-09 18:46:52,063 WARN namenode.DecommissionManager (DecommissionManager.java:run(67)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2009-06-09 18:46:52,075 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 43516
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 0 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 4 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(539)) - Stopping IPC Server Responder
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 9 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 43516
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 8 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 7 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 3 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 5 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 2 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 1 on 43516: exiting
[junit] 2009-06-09 18:46:52,076 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 6 on 43516: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 58.948 sec
checkfailure:
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1043: Tests failed!
Total time: 217 minutes 1 second
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...