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/02/19 15:35:06 UTC
Build failed in Hudson: Hadoop-trunk #759
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/759/changes
Changes:
[cdouglas] HADOOP-5227. Fix distcp so -update and -delete can be meaningfully
combined. Contributed by Tsz Wo (Nicholas), SZE
[szetszwo] HADOOP-5209. Update year to 2009 for javadoc. (szetszwo)
------------------------------------------
[...truncated 282902 lines...]
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-19 14:41 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-19 14:41 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-19 14:41 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub0
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub0/f4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub0/f5
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub1/f6
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub2
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub2/f7
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub4/f8
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub4/f9
[junit]
[junit] args=[/test/testDistCh/sub3:::575,
[junit] /test/testDistCh/sub4::sub4:,
[junit] /test/testDistCh/sub0:sub0:sub0:,
[junit] /test/testDistCh/sub2:sub2:sub2:662]
[junit] newstatus=[sub0:sub0:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] sub2:sub2:rw-rw--w-,
[junit] hudson:supergroup:r-xrwxr-x,
[junit] hudson:sub4:rwxr-xr-x]
[junit] 2009-02-19 14:41:08,754 INFO tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub3:null:null:r-xrwxr-x, /test/testDistCh/sub4:null:sub4:null, /test/testDistCh/sub0:sub0:sub0:null, /test/testDistCh/sub2:sub2:sub2:rw-rw--w-]
[junit] 2009-02-19 14:41:08,754 INFO tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
[junit] 2009-02-19 14:41:08,778 INFO tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:40684/user/hudson/build/test/mapred/system/distch_908ydt
[junit] 2009-02-19 14:41:08,781 INFO tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:40684/user/hudson/build/test/mapred/system/distch_908ydt/_logs
[junit] 2009-02-19 14:41:08,856 INFO tools.DistTool (DistCh.java:setup(476)) - distch.op.count=9
[junit] 2009-02-19 14:41:08,862 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(539)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2009-02-19 14:41:08,874 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(661)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2009-02-19 14:41:08,887 INFO tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1, splits.size()=1
[junit] 2009-02-19 14:41:09,024 WARN conf.Configuration (Configuration.java:loadResource(1153)) - build/test/mapred/local/jobTracker/job_200902191440_0001.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-19 14:41:09,034 INFO mapred.JobClient (JobClient.java:runJob(1268)) - Running job: job_200902191440_0001
[junit] 2009-02-19 14:41:09,278 INFO mapred.JobInProgress (JobInProgress.java:initTasks(426)) - Input size for job job_200902191440_0001 = 913
[junit] 2009-02-19 14:41:09,278 INFO mapred.JobInProgress (JobInProgress.java:initTasks(428)) - Split info for job:job_200902191440_0001 with 1 splits:
[junit] 2009-02-19 14:41:10,041 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 0% reduce 0%
[junit] 2009-02-19 14:41:11,188 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1657)) - Adding task 'attempt_200902191440_0001_m_000002_0' to tip task_200902191440_0001_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:60265'
[junit] 2009-02-19 14:41:11,264 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902191440_0001/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-19 14:41:11,317 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902191440_0001_m_-1303483699
[junit] 2009-02-19 14:41:11,317 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902191440_0001_m_-1303483699 spawned.
[junit] 2009-02-19 14:41:12,274 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902191440_0001_m_-1303483699 exited. Number of tasks it ran: 1
[junit] 2009-02-19 14:41:14,197 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1982)) - Task 'attempt_200902191440_0001_m_000002_0' has completed task_200902191440_0001_m_000002 successfully.
[junit] 2009-02-19 14:41:14,202 INFO mapred.JobInProgress (JobInProgress.java:findNewMapTask(1817)) - Choosing a non-local task task_200902191440_0001_m_000000
[junit] 2009-02-19 14:41:14,202 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1657)) - Adding task 'attempt_200902191440_0001_m_000000_0' to tip task_200902191440_0001_m_000000, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:60265'
[junit] 2009-02-19 14:41:14,208 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902191440_0001_m_000002_0 done; removing files.
[junit] 2009-02-19 14:41:14,209 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902191440_0001_m_000002_0 not found in cache
[junit] 2009-02-19 14:41:14,258 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902191440_0001_m_-2032845609
[junit] 2009-02-19 14:41:14,259 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902191440_0001_m_-2032845609 spawned.
[junit] 2009-02-19 14:41:15,059 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902191440_0001_m_000002_0, Status : SUCCEEDED
[junit] attempt_200902191440_0001_m_000002_0: 2009-02-19 14:41:11,896 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902191440_0001/attempt_200902191440_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902191440_0001_m_000002_0: 2009-02-19 14:41:11,969 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902191440_0001_m_000002_0: 2009-02-19 14:41:12,057 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902191440_0001/attempt_200902191440_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902191440_0001_m_000002_0: 2009-02-19 14:41:12,111 INFO mapred.TaskRunner (Task.java:done(645)) - Task:attempt_200902191440_0001_m_000002_0 is done. And is in the process of commiting
[junit] attempt_200902191440_0001_m_000002_0: 2009-02-19 14:41:12,117 INFO mapred.TaskRunner (Task.java:sendDone(717)) - Task 'attempt_200902191440_0001_m_000002_0' done.
[junit] 2009-02-19 14:41:17,369 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902191440_0001_m_-2032845609 exited. Number of tasks it ran: 1
[junit] 2009-02-19 14:41:20,213 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1982)) - Task 'attempt_200902191440_0001_m_000000_0' has completed task_200902191440_0001_m_000000 successfully.
[junit] 2009-02-19 14:41:20,217 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1657)) - Adding task 'attempt_200902191440_0001_m_000001_0' to tip task_200902191440_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:60265'
[junit] 2009-02-19 14:41:20,247 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902191440_0001_m_-604449466
[junit] 2009-02-19 14:41:20,248 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902191440_0001_m_-604449466 spawned.
[junit] 2009-02-19 14:41:21,201 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 100% reduce 0%
[junit] 2009-02-19 14:41:21,202 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902191440_0001_m_000000_0, Status : SUCCEEDED
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:14,851 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902191440_0001/attempt_200902191440_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:14,926 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:15,014 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902191440_0001/attempt_200902191440_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:15,149 INFO mapred.MapTask (MapTask.java:runOldMapper(343)) - numReduceTasks: 0
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:15,204 INFO mapred.TaskRunner (Task.java:done(645)) - Task:attempt_200902191440_0001_m_000000_0 is done. And is in the process of commiting
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:17,212 INFO mapred.TaskRunner (Task.java:commit(746)) - Task attempt_200902191440_0001_m_000000_0 is allowed to commit now
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:17,225 INFO mapred.FileOutputCommitter (FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200902191440_0001_m_000000_0' to hdfs://localhost:40684/user/hudson/build/test/mapred/system/distch_908ydt/_logs
[junit] attempt_200902191440_0001_m_000000_0: 2009-02-19 14:41:17,231 INFO mapred.TaskRunner (Task.java:sendDone(717)) - Task 'attempt_200902191440_0001_m_000000_0' done.
[junit] 2009-02-19 14:41:21,229 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902191440_0001_m_-604449466 exited. Number of tasks it ran: 1
[junit] 2009-02-19 14:41:23,222 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1982)) - Task 'attempt_200902191440_0001_m_000001_0' has completed task_200902191440_0001_m_000001 successfully.
[junit] 2009-02-19 14:41:23,224 INFO mapred.JobInProgress (JobInProgress.java:jobComplete(2099)) - Job job_200902191440_0001 has completed successfully.
[junit] 2009-02-19 14:41:23,329 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1778)) - Removed completed task 'attempt_200902191440_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:60265'
[junit] 2009-02-19 14:41:23,330 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1778)) - Removed completed task 'attempt_200902191440_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:60265'
[junit] 2009-02-19 14:41:23,331 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1778)) - Removed completed task 'attempt_200902191440_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:60265'
[junit] 2009-02-19 14:41:23,332 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902191440_0001_m_000000_0 done; removing files.
[junit] 2009-02-19 14:41:23,333 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902191440_0001_m_000000_0 not found in cache
[junit] 2009-02-19 14:41:23,333 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902191440_0001_m_000001_0 done; removing files.
[junit] 2009-02-19 14:41:23,334 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902191440_0001_m_000001_0 not found in cache
[junit] 2009-02-19 14:41:24,220 INFO mapred.JobClient (JobClient.java:runJob(1358)) - Job complete: job_200902191440_0001
[junit] 2009-02-19 14:41:24,222 INFO mapred.JobClient (Counters.java:log(514)) - Counters: 7
[junit] 2009-02-19 14:41:24,222 INFO mapred.JobClient (Counters.java:log(516)) - Job Counters
[junit] 2009-02-19 14:41:24,223 INFO mapred.JobClient (Counters.java:log(518)) - Launched map tasks=1
[junit] 2009-02-19 14:41:24,223 INFO mapred.JobClient (Counters.java:log(516)) - org.apache.hadoop.tools.DistCh$Counter
[junit] 2009-02-19 14:41:24,223 INFO mapred.JobClient (Counters.java:log(518)) - SUCCEED=9
[junit] 2009-02-19 14:41:24,224 INFO mapred.JobClient (Counters.java:log(516)) - FileSystemCounters
[junit] 2009-02-19 14:41:24,225 INFO mapred.JobClient (Counters.java:log(518)) - HDFS_BYTES_READ=913
[junit] 2009-02-19 14:41:24,225 INFO mapred.JobClient (Counters.java:log(516)) - Map-Reduce Framework
[junit] 2009-02-19 14:41:24,226 INFO mapred.JobClient (Counters.java:log(518)) - Map input records=9
[junit] 2009-02-19 14:41:24,227 INFO mapred.JobClient (Counters.java:log(518)) - Spilled Records=0
[junit] 2009-02-19 14:41:24,227 INFO mapred.JobClient (Counters.java:log(518)) - Map input bytes=816
[junit] 2009-02-19 14:41:24,228 INFO mapred.JobClient (Counters.java:log(518)) - Map output records=0
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-19 14:41 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-19 14:41 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-19 14:41 /test/testDistCh/f3
[junit] drwxr-xr-x - sub0 sub0 0 2009-02-19 14:41 /test/testDistCh/sub0
[junit] -rw-r--r-- 2 sub0 sub0 43 2009-02-19 14:41 /test/testDistCh/sub0/f4
[junit] -rw-r--r-- 2 sub0 sub0 43 2009-02-19 14:41 /test/testDistCh/sub0/f5
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-19 14:41 /test/testDistCh/sub1/f6
[junit] drw-rw--w- - sub2 sub2 0 2009-02-19 14:41 /test/testDistCh/sub2
[junit] -rw-rw--w- 2 sub2 sub2 43 2009-02-19 14:41 /test/testDistCh/sub2/f7
[junit] dr-xrwxr-x - hudson supergroup 0 2009-02-19 14:41 /test/testDistCh/sub3
[junit] drwxr-xr-x - hudson sub4 0 2009-02-19 14:41 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson sub4 43 2009-02-19 14:41 /test/testDistCh/sub4/f8
[junit] -rw-r--r-- 2 hudson sub4 43 2009-02-19 14:41 /test/testDistCh/sub4/f9
[junit]
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-02-19 14:41:24,272 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 39756
[junit] 2009-02-19 14:41:24,272 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 39756: exiting
[junit] 2009-02-19 14:41:24,272 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-19 14:41:24,272 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 39756: exiting
[junit] 2009-02-19 14:41:24,272 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 39756: exiting
[junit] 2009-02-19 14:41:24,272 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 39756
[junit] 2009-02-19 14:41:24,818 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-19 14:41:25,273 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 39756
[junit] Shutting down DataNode 0
[junit] 2009-02-19 14:41:25,374 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 35784
[junit] 2009-02-19 14:41:25,375 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 35784: exiting
[junit] 2009-02-19 14:41:25,375 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 35784
[junit] 2009-02-19 14:41:25,375 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-19 14:41:25,375 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 35784: exiting
[junit] 2009-02-19 14:41:25,375 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-19 14:41:25,375 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 35784: exiting
[junit] 2009-02-19 14:41:25,376 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 35784
[junit] 2009-02-19 14:41:25,379 INFO namenode.DecommissionManager (DecommissionManager.java:run(67)) - Interrupted Monitor
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor.run(DecommissionManager.java:65)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2009-02-19 14:41:25,381 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 40684
[junit] 2009-02-19 14:41:25,381 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 40684: exiting
[junit] 2009-02-19 14:41:25,381 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 3 on 40684: exiting
[junit] 2009-02-19 14:41:25,381 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 4 on 40684: exiting
[junit] 2009-02-19 14:41:25,381 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 40684: exiting
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 8 on 40684: exiting
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 7 on 40684: exiting
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 6 on 40684: exiting
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 5 on 40684: exiting
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 40684: exiting
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 40684
[junit] 2009-02-19 14:41:25,382 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 9 on 40684: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 30.056 sec
[junit] Running org.apache.hadoop.util.TestCyclicIteration
[junit]
[junit]
[junit] integers=[]
[junit] map={}
[junit] start=-1, iteration=[]
[junit]
[junit]
[junit] integers=[0]
[junit] map={0=0}
[junit] start=-1, iteration=[0]
[junit] start=0, iteration=[0]
[junit] start=1, iteration=[0]
[junit]
[junit]
[junit] integers=[0, 2]
[junit] map={0=0, 2=2}
[junit] start=-1, iteration=[0, 2]
[junit] start=0, iteration=[2, 0]
[junit] start=1, iteration=[2, 0]
[junit] start=2, iteration=[0, 2]
[junit] start=3, iteration=[0, 2]
[junit]
[junit]
[junit] integers=[0, 2, 4]
[junit] map={0=0, 2=2, 4=4}
[junit] start=-1, iteration=[0, 2, 4]
[junit] start=0, iteration=[2, 4, 0]
[junit] start=1, iteration=[2, 4, 0]
[junit] start=2, iteration=[4, 0, 2]
[junit] start=3, iteration=[4, 0, 2]
[junit] start=4, iteration=[0, 2, 4]
[junit] start=5, iteration=[0, 2, 4]
[junit]
[junit]
[junit] integers=[0, 2, 4, 6]
[junit] map={0=0, 2=2, 4=4, 6=6}
[junit] start=-1, iteration=[0, 2, 4, 6]
[junit] start=0, iteration=[2, 4, 6, 0]
[junit] start=1, iteration=[2, 4, 6, 0]
[junit] start=2, iteration=[4, 6, 0, 2]
[junit] start=3, iteration=[4, 6, 0, 2]
[junit] start=4, iteration=[6, 0, 2, 4]
[junit] start=5, iteration=[6, 0, 2, 4]
[junit] start=6, iteration=[0, 2, 4, 6]
[junit] start=7, iteration=[0, 2, 4, 6]
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.088 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2009-02-19 14:41:26,458 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] 2009-02-19 14:41:26,470 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(377)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.186 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: 7113554759098092181(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: -5175192813045227448(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 212/12(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: -4588781239136967433(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: -6491201425896239515(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: -8452074593193434404(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 207/338(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: 6612049349704006193(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.897 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2009-02-19 14:41:28,037 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(54)) - setsid exited with exit code 0
[junit] 2009-02-19 14:41:28,543 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(141)) - Root process pid: 20289
[junit] 2009-02-19 14:41:28,616 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(146)) - ProcessTree: [ 20292 20291 20289 ]
[junit] 2009-02-19 14:41:35,143 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(159)) - ProcessTree: [ 20303 20301 20299 20297 20295 20293 20291 20305 20289 ]
[junit] 2009-02-19 14:41:35,155 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(64)) - Shell Command exit with a non-zero exit code. This is expected as we are killing the subprocesses of the task intentionally. org.apache.hadoop.util.Shell$ExitCodeException:
[junit] 2009-02-19 14:41:35,155 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(70)) - Exit code: 143
[junit] 2009-02-19 14:41:35,156 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(160)) - Killing all processes in the process group 20289 with SIGTERM. Exit code 0
[junit] 2009-02-19 14:41:35,204 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(173)) - RogueTaskThread successfully joined.
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 7.221 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] 2009-02-19 14:41:36,131 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.629 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.174 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.086 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :768: Tests failed!
Total time: 163 minutes 12 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Publishing Clover coverage report...
Hudson build is back to normal: Hadoop-trunk #763
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/763/changes
Build failed in Hudson: Hadoop-trunk #762
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/762/changes
------------------------------------------
[...truncated 289274 lines...]
[junit] 2009-02-22 14:39:31,455 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=TaskTracker, port=52755
[junit] 2009-02-22 14:39:31,456 INFO ipc.Server (Server.java:run(471)) - IPC Server Responder: starting
[junit] 2009-02-22 14:39:31,456 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 52755: starting
[junit] 2009-02-22 14:39:31,457 INFO ipc.Server (Server.java:run(934)) - IPC Server handler 0 on 52755: starting
[junit] 2009-02-22 14:39:31,457 INFO ipc.Server (Server.java:run(934)) - IPC Server handler 1 on 52755: starting
[junit] 2009-02-22 14:39:31,457 INFO ipc.Server (Server.java:run(934)) - IPC Server handler 2 on 52755: starting
[junit] 2009-02-22 14:39:31,457 INFO ipc.Server (Server.java:run(934)) - IPC Server handler 3 on 52755: starting
[junit] 2009-02-22 14:39:31,462 INFO mapred.IndexCache (IndexCache.java:<init>(46)) - IndexCache created with max memory = 10485760
[junit] 2009-02-22 14:39:31,518 INFO net.NetworkTopology (NetworkTopology.java:add(319)) - Adding a new node: /default-rack/host1.foo.com
[junit] 2009-02-22 14:39:31,538 INFO net.NetworkTopology (NetworkTopology.java:add(319)) - Adding a new node: /default-rack/host0.foo.com
[junit] rootdir = /test/testDistCh
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-22 14:39 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-22 14:39 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-22 14:39 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub0
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-22 14:39 /test/testDistCh/sub1/f4
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub2
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub4
[junit]
[junit] args=[/test/testDistCh/sub2:sub2:sub2:027,
[junit] /test/testDistCh/sub3:sub3:sub3:,
[junit] /test/testDistCh/sub0:sub0::]
[junit] newstatus=[sub0:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] sub2:sub2:----w-rwx,
[junit] sub3:sub3:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x]
[junit] 2009-02-22 14:39:31,885 INFO tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub2:sub2:sub2:----w-rwx, /test/testDistCh/sub3:sub3:sub3:null, /test/testDistCh/sub0:sub0:null:null]
[junit] 2009-02-22 14:39:31,886 INFO tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
[junit] 2009-02-22 14:39:31,910 INFO tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:50421/user/hudson/build/test/mapred/system/distch_y2m9ra
[junit] 2009-02-22 14:39:31,913 INFO tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:50421/user/hudson/build/test/mapred/system/distch_y2m9ra/_logs
[junit] 2009-02-22 14:39:32,024 INFO tools.DistTool (DistCh.java:setup(476)) - distch.op.count=3
[junit] 2009-02-22 14:39:32,030 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(539)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2009-02-22 14:39:32,034 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(661)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2009-02-22 14:39:32,046 INFO tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1, splits.size()=1
[junit] 2009-02-22 14:39:32,171 WARN conf.Configuration (Configuration.java:loadResource(1153)) - build/test/mapred/local/jobTracker/job_200902221439_0001.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-22 14:39:32,183 INFO mapred.JobClient (JobClient.java:runJob(1268)) - Running job: job_200902221439_0001
[junit] 2009-02-22 14:39:32,480 INFO mapred.JobInProgress (JobInProgress.java:initTasks(426)) - Input size for job job_200902221439_0001 = 289
[junit] 2009-02-22 14:39:32,481 INFO mapred.JobInProgress (JobInProgress.java:initTasks(428)) - Split info for job:job_200902221439_0001 with 1 splits:
[junit] 2009-02-22 14:39:33,187 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 0% reduce 0%
[junit] 2009-02-22 14:39:34,558 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1661)) - Adding task 'attempt_200902221439_0001_m_000002_0' to tip task_200902221439_0001_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:52755'
[junit] 2009-02-22 14:39:34,632 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902221439_0001/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-22 14:39:34,703 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902221439_0001_m_681018573
[junit] 2009-02-22 14:39:34,705 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902221439_0001_m_681018573 spawned.
[junit] 2009-02-22 14:39:35,570 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902221439_0001_m_681018573 exited. Number of tasks it ran: 1
[junit] 2009-02-22 14:39:37,567 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1984)) - Task 'attempt_200902221439_0001_m_000002_0' has completed task_200902221439_0001_m_000002 successfully.
[junit] 2009-02-22 14:39:37,571 INFO mapred.JobInProgress (JobInProgress.java:findNewMapTask(1819)) - Choosing a non-local task task_200902221439_0001_m_000000
[junit] 2009-02-22 14:39:37,572 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1661)) - Adding task 'attempt_200902221439_0001_m_000000_0' to tip task_200902221439_0001_m_000000, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:52755'
[junit] 2009-02-22 14:39:37,576 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902221439_0001_m_000002_0 done; removing files.
[junit] 2009-02-22 14:39:37,577 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902221439_0001_m_000002_0 not found in cache
[junit] 2009-02-22 14:39:37,708 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902221439_0001_m_432327244
[junit] 2009-02-22 14:39:37,708 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902221439_0001_m_432327244 spawned.
[junit] 2009-02-22 14:39:38,208 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902221439_0001_m_000002_0, Status : SUCCEEDED
[junit] attempt_200902221439_0001_m_000002_0: 2009-02-22 14:39:35,295 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902221439_0001/attempt_200902221439_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902221439_0001_m_000002_0: 2009-02-22 14:39:35,370 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902221439_0001_m_000002_0: 2009-02-22 14:39:35,459 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902221439_0001/attempt_200902221439_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902221439_0001_m_000002_0: 2009-02-22 14:39:35,509 INFO mapred.TaskRunner (Task.java:done(644)) - Task:attempt_200902221439_0001_m_000002_0 is done. And is in the process of commiting
[junit] attempt_200902221439_0001_m_000002_0: 2009-02-22 14:39:35,515 INFO mapred.TaskRunner (Task.java:sendDone(715)) - Task 'attempt_200902221439_0001_m_000002_0' done.
[junit] 2009-02-22 14:39:40,658 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902221439_0001_m_432327244 exited. Number of tasks it ran: 1
[junit] 2009-02-22 14:39:43,582 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1984)) - Task 'attempt_200902221439_0001_m_000000_0' has completed task_200902221439_0001_m_000000 successfully.
[junit] 2009-02-22 14:39:43,585 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1661)) - Adding task 'attempt_200902221439_0001_m_000001_0' to tip task_200902221439_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:52755'
[junit] 2009-02-22 14:39:43,612 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902221439_0001_m_-1174749672
[junit] 2009-02-22 14:39:43,613 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902221439_0001_m_-1174749672 spawned.
[junit] 2009-02-22 14:39:44,284 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 100% reduce 0%
[junit] 2009-02-22 14:39:44,285 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902221439_0001_m_000000_0, Status : SUCCEEDED
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:38,280 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902221439_0001/attempt_200902221439_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:38,361 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:38,449 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902221439_0001/attempt_200902221439_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:38,546 INFO mapred.MapTask (MapTask.java:runOldMapper(343)) - numReduceTasks: 0
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:38,594 INFO mapred.TaskRunner (Task.java:done(644)) - Task:attempt_200902221439_0001_m_000000_0 is done. And is in the process of commiting
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:40,603 INFO mapred.TaskRunner (Task.java:commit(744)) - Task attempt_200902221439_0001_m_000000_0 is allowed to commit now
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:40,615 INFO mapred.FileOutputCommitter (FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200902221439_0001_m_000000_0' to hdfs://localhost:50421/user/hudson/build/test/mapred/system/distch_y2m9ra/_logs
[junit] attempt_200902221439_0001_m_000000_0: 2009-02-22 14:39:40,621 INFO mapred.TaskRunner (Task.java:sendDone(715)) - Task 'attempt_200902221439_0001_m_000000_0' done.
[junit] 2009-02-22 14:39:44,477 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902221439_0001_m_-1174749672 exited. Number of tasks it ran: 1
[junit] 2009-02-22 14:39:46,588 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1984)) - Task 'attempt_200902221439_0001_m_000001_0' has completed task_200902221439_0001_m_000001 successfully.
[junit] 2009-02-22 14:39:46,589 INFO mapred.JobInProgress (JobInProgress.java:jobComplete(2098)) - Job job_200902221439_0001 has completed successfully.
[junit] 2009-02-22 14:39:46,698 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1782)) - Removed completed task 'attempt_200902221439_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:52755'
[junit] 2009-02-22 14:39:46,699 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1782)) - Removed completed task 'attempt_200902221439_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:52755'
[junit] 2009-02-22 14:39:46,699 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1782)) - Removed completed task 'attempt_200902221439_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:52755'
[junit] 2009-02-22 14:39:46,700 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902221439_0001_m_000000_0 done; removing files.
[junit] 2009-02-22 14:39:46,701 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902221439_0001_m_000000_0 not found in cache
[junit] 2009-02-22 14:39:46,701 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902221439_0001_m_000001_0 done; removing files.
[junit] 2009-02-22 14:39:46,702 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902221439_0001_m_000001_0 not found in cache
[junit] 2009-02-22 14:39:47,300 INFO mapred.JobClient (JobClient.java:runJob(1358)) - Job complete: job_200902221439_0001
[junit] 2009-02-22 14:39:47,303 INFO mapred.JobClient (Counters.java:log(514)) - Counters: 7
[junit] 2009-02-22 14:39:47,303 INFO mapred.JobClient (Counters.java:log(516)) - Job Counters
[junit] 2009-02-22 14:39:47,303 INFO mapred.JobClient (Counters.java:log(518)) - Launched map tasks=1
[junit] 2009-02-22 14:39:47,304 INFO mapred.JobClient (Counters.java:log(516)) - org.apache.hadoop.tools.DistCh$Counter
[junit] 2009-02-22 14:39:47,304 INFO mapred.JobClient (Counters.java:log(518)) - SUCCEED=3
[junit] 2009-02-22 14:39:47,304 INFO mapred.JobClient (Counters.java:log(516)) - FileSystemCounters
[junit] 2009-02-22 14:39:47,305 INFO mapred.JobClient (Counters.java:log(518)) - HDFS_BYTES_READ=289
[junit] 2009-02-22 14:39:47,305 INFO mapred.JobClient (Counters.java:log(516)) - Map-Reduce Framework
[junit] 2009-02-22 14:39:47,305 INFO mapred.JobClient (Counters.java:log(518)) - Map input records=3
[junit] 2009-02-22 14:39:47,306 INFO mapred.JobClient (Counters.java:log(518)) - Spilled Records=0
[junit] 2009-02-22 14:39:47,306 INFO mapred.JobClient (Counters.java:log(518)) - Map input bytes=192
[junit] 2009-02-22 14:39:47,306 INFO mapred.JobClient (Counters.java:log(518)) - Map output records=0
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-22 14:39 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-22 14:39 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-22 14:39 /test/testDistCh/f3
[junit] drwxr-xr-x - sub0 supergroup 0 2009-02-22 14:39 /test/testDistCh/sub0
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-22 14:39 /test/testDistCh/sub1/f4
[junit] d----w-rwx - sub2 sub2 0 2009-02-22 14:39 /test/testDistCh/sub2
[junit] drwxr-xr-x - sub3 sub3 0 2009-02-22 14:39 /test/testDistCh/sub3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-22 14:39 /test/testDistCh/sub4
[junit]
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-02-22 14:39:47,429 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 46820
[junit] 2009-02-22 14:39:47,429 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 46820: exiting
[junit] 2009-02-22 14:39:47,429 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 46820: exiting
[junit] 2009-02-22 14:39:47,429 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-22 14:39:47,429 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 46820: exiting
[junit] 2009-02-22 14:39:47,429 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 46820
[junit] 2009-02-22 14:39:48,115 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-22 14:39:48,430 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 46820
[junit] Shutting down DataNode 0
[junit] 2009-02-22 14:39:48,431 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 53701
[junit] 2009-02-22 14:39:48,432 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 53701: exiting
[junit] 2009-02-22 14:39:48,432 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 53701: exiting
[junit] 2009-02-22 14:39:48,432 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-22 14:39:48,432 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 53701
[junit] 2009-02-22 14:39:48,432 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 53701: exiting
[junit] 2009-02-22 14:39:48,927 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-22 14:39:49,432 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 53701
[junit] 2009-02-22 14:39:49,534 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 50421
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 50421
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 7 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 50421: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 30.997 sec
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 8 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 6 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 3 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 4 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO namenode.DecommissionManager (DecommissionManager.java:run(67)) - Interrupted Monitor
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor.run(DecommissionManager.java:65)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 5 on 50421: exiting
[junit] 2009-02-22 14:39:49,535 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 9 on 50421: exiting
[junit] Running org.apache.hadoop.util.TestCyclicIteration
[junit]
[junit]
[junit] integers=[]
[junit] map={}
[junit] start=-1, iteration=[]
[junit]
[junit]
[junit] integers=[0]
[junit] map={0=0}
[junit] start=-1, iteration=[0]
[junit] start=0, iteration=[0]
[junit] start=1, iteration=[0]
[junit]
[junit]
[junit] integers=[0, 2]
[junit] map={0=0, 2=2}
[junit] start=-1, iteration=[0, 2]
[junit] start=0, iteration=[2, 0]
[junit] start=1, iteration=[2, 0]
[junit] start=2, iteration=[0, 2]
[junit] start=3, iteration=[0, 2]
[junit]
[junit]
[junit] integers=[0, 2, 4]
[junit] map={0=0, 2=2, 4=4}
[junit] start=-1, iteration=[0, 2, 4]
[junit] start=0, iteration=[2, 4, 0]
[junit] start=1, iteration=[2, 4, 0]
[junit] start=2, iteration=[4, 0, 2]
[junit] start=3, iteration=[4, 0, 2]
[junit] start=4, iteration=[0, 2, 4]
[junit] start=5, iteration=[0, 2, 4]
[junit]
[junit]
[junit] integers=[0, 2, 4, 6]
[junit] map={0=0, 2=2, 4=4, 6=6}
[junit] start=-1, iteration=[0, 2, 4, 6]
[junit] start=0, iteration=[2, 4, 6, 0]
[junit] start=1, iteration=[2, 4, 6, 0]
[junit] start=2, iteration=[4, 6, 0, 2]
[junit] start=3, iteration=[4, 6, 0, 2]
[junit] start=4, iteration=[6, 0, 2, 4]
[junit] start=5, iteration=[6, 0, 2, 4]
[junit] start=6, iteration=[0, 2, 4, 6]
[junit] start=7, iteration=[0, 2, 4, 6]
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.082 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2009-02-22 14:39:50,640 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] 2009-02-22 14:39:50,653 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(377)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.186 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -2536370101413575413(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: -6658259204507626745(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 210/303(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: 5552679210851322219(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: 5278021212377466251(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: 5105174079299332841(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 141/422(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: 3380299120558871472(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.904 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2009-02-22 14:39:52,265 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(54)) - setsid exited with exit code 0
[junit] 2009-02-22 14:39:52,770 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(141)) - Root process pid: 11466
[junit] 2009-02-22 14:39:52,817 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(146)) - ProcessTree: [ 11469 11468 11466 ]
[junit] 2009-02-22 14:39:59,341 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(159)) - ProcessTree: [ 11478 11476 11474 11472 11470 11468 11482 11466 11480 ]
[junit] 2009-02-22 14:39:59,350 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(160)) - Killing all processes in the process group 11466 with SIGTERM. Exit code 0
[junit] 2009-02-22 14:39:59,350 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(64)) - Shell Command exit with a non-zero exit code. This is expected as we are killing the subprocesses of the task intentionally. org.apache.hadoop.util.Shell$ExitCodeException:
[junit] 2009-02-22 14:39:59,351 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(70)) - Exit code: 143
[junit] 2009-02-22 14:39:59,424 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(173)) - RogueTaskThread successfully joined.
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 7.215 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] 2009-02-22 14:40:00,291 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.579 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.18 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.088 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :768: Tests failed!
Total time: 164 minutes 0 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Publishing Clover coverage report...
Build failed in Hudson: Hadoop-trunk #761
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/761/changes
Changes:
[szetszwo] HADOOP-5120. Remove the use of FSNamesystem.getFSNamesystem() from UpgradeManagerNamenode and UpgradeObjectNamenode. (szetszwo)
[nigel] HADOOP-5275. Add ivy directory and files to built tar. Contributed by Giridharan Kesavan
[yhemanth] HADOOP-5282. Fixed job history logs for task attempts that are failed by the JobTracker, say due to lost task trackers. Contributed by Amar Kamat.
[ddas] HADOOP-5247. Introduces a broadcast of KillJobAction to all trackers when a job finishes. This fixes a bunch of problems to do with NPE when a completed job is not in memory and a tasktracker comes to the jobtracker with a status report of a task belonging to that job. Contributed by Amar Kamat.
[ddas] HADOOP-5233. Addresses the three issues - Race condition in updating status, NPE in TaskTracker task localization when the conf file is missing (HADOOP-5234) and NPE in handling KillTaskAction of a cleanup task (HADOOP-5235). Contributed by Amareshwari Sriramadasu.
------------------------------------------
[...truncated 289208 lines...]
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-21 14:48 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-21 14:48 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-21 14:48 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub0
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub1/f4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub1/f5
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub2
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub2/f6
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub2/f7
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub3/f8
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub3/f9
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-02-21 14:48 /test/testDistCh/sub4/f10
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-02-21 14:48 /test/testDistCh/sub4/f11
[junit]
[junit] args=[/test/testDistCh/sub2::sub2:]
[junit] newstatus=[hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:sub2:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x]
[junit] 2009-02-21 14:48:29,973 INFO tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub2:null:sub2:null]
[junit] 2009-02-21 14:48:29,974 INFO tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
[junit] 2009-02-21 14:48:29,994 INFO tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:60188/user/hudson/build/test/mapred/system/distch_lf5rzx
[junit] 2009-02-21 14:48:29,998 INFO tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:60188/user/hudson/build/test/mapred/system/distch_lf5rzx/_logs
[junit] 2009-02-21 14:48:30,126 INFO tools.DistTool (DistCh.java:setup(476)) - distch.op.count=3
[junit] 2009-02-21 14:48:30,133 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(539)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2009-02-21 14:48:30,137 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(661)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2009-02-21 14:48:30,149 INFO tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1, splits.size()=1
[junit] 2009-02-21 14:48:30,265 WARN conf.Configuration (Configuration.java:loadResource(1153)) - build/test/mapred/local/jobTracker/job_200902211448_0001.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-21 14:48:30,345 INFO mapred.JobClient (JobClient.java:runJob(1268)) - Running job: job_200902211448_0001
[junit] 2009-02-21 14:48:30,510 INFO mapred.JobInProgress (JobInProgress.java:initTasks(426)) - Input size for job job_200902211448_0001 = 377
[junit] 2009-02-21 14:48:30,510 INFO mapred.JobInProgress (JobInProgress.java:initTasks(428)) - Split info for job:job_200902211448_0001 with 1 splits:
[junit] 2009-02-21 14:48:31,350 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 0% reduce 0%
[junit] 2009-02-21 14:48:32,457 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1661)) - Adding task 'attempt_200902211448_0001_m_000002_0' to tip task_200902211448_0001_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:42750'
[junit] 2009-02-21 14:48:32,544 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902211448_0001/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-21 14:48:32,597 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902211448_0001_m_-775643471
[junit] 2009-02-21 14:48:32,597 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902211448_0001_m_-775643471 spawned.
[junit] 2009-02-21 14:48:33,538 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902211448_0001_m_-775643471 exited. Number of tasks it ran: 1
[junit] 2009-02-21 14:48:35,464 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1984)) - Task 'attempt_200902211448_0001_m_000002_0' has completed task_200902211448_0001_m_000002 successfully.
[junit] 2009-02-21 14:48:35,469 INFO mapred.JobInProgress (JobInProgress.java:findNewMapTask(1819)) - Choosing a non-local task task_200902211448_0001_m_000000
[junit] 2009-02-21 14:48:35,469 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1661)) - Adding task 'attempt_200902211448_0001_m_000000_0' to tip task_200902211448_0001_m_000000, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:42750'
[junit] 2009-02-21 14:48:35,474 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902211448_0001_m_000002_0 done; removing files.
[junit] 2009-02-21 14:48:35,475 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902211448_0001_m_000002_0 not found in cache
[junit] 2009-02-21 14:48:35,521 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902211448_0001_m_1476404932
[junit] 2009-02-21 14:48:35,521 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902211448_0001_m_1476404932 spawned.
[junit] 2009-02-21 14:48:36,370 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902211448_0001_m_000002_0, Status : SUCCEEDED
[junit] attempt_200902211448_0001_m_000002_0: 2009-02-21 14:48:33,172 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902211448_0001/attempt_200902211448_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902211448_0001_m_000002_0: 2009-02-21 14:48:33,244 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902211448_0001_m_000002_0: 2009-02-21 14:48:33,333 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902211448_0001/attempt_200902211448_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902211448_0001_m_000002_0: 2009-02-21 14:48:33,384 INFO mapred.TaskRunner (Task.java:done(644)) - Task:attempt_200902211448_0001_m_000002_0 is done. And is in the process of commiting
[junit] attempt_200902211448_0001_m_000002_0: 2009-02-21 14:48:33,393 INFO mapred.TaskRunner (Task.java:sendDone(715)) - Task 'attempt_200902211448_0001_m_000002_0' done.
[junit] 2009-02-21 14:48:39,623 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902211448_0001_m_1476404932 exited. Number of tasks it ran: 1
[junit] 2009-02-21 14:48:41,478 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1984)) - Task 'attempt_200902211448_0001_m_000000_0' has completed task_200902211448_0001_m_000000 successfully.
[junit] 2009-02-21 14:48:41,482 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1661)) - Adding task 'attempt_200902211448_0001_m_000001_0' to tip task_200902211448_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:42750'
[junit] 2009-02-21 14:48:41,513 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902211448_0001_m_1274317701
[junit] 2009-02-21 14:48:41,513 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902211448_0001_m_1274317701 spawned.
[junit] 2009-02-21 14:48:42,464 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902211448_0001_m_1274317701 exited. Number of tasks it ran: 1
[junit] 2009-02-21 14:48:42,473 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 100% reduce 0%
[junit] 2009-02-21 14:48:42,474 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902211448_0001_m_000000_0, Status : SUCCEEDED
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:36,114 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902211448_0001/attempt_200902211448_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:36,189 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:36,277 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902211448_0001/attempt_200902211448_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:36,382 INFO mapred.MapTask (MapTask.java:runOldMapper(343)) - numReduceTasks: 0
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:36,462 INFO mapred.TaskRunner (Task.java:done(644)) - Task:attempt_200902211448_0001_m_000000_0 is done. And is in the process of commiting
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:39,472 INFO mapred.TaskRunner (Task.java:commit(744)) - Task attempt_200902211448_0001_m_000000_0 is allowed to commit now
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:39,484 INFO mapred.FileOutputCommitter (FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200902211448_0001_m_000000_0' to hdfs://localhost:60188/user/hudson/build/test/mapred/system/distch_lf5rzx/_logs
[junit] attempt_200902211448_0001_m_000000_0: 2009-02-21 14:48:39,490 INFO mapred.TaskRunner (Task.java:sendDone(715)) - Task 'attempt_200902211448_0001_m_000000_0' done.
[junit] 2009-02-21 14:48:44,485 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1984)) - Task 'attempt_200902211448_0001_m_000001_0' has completed task_200902211448_0001_m_000001 successfully.
[junit] 2009-02-21 14:48:44,488 INFO mapred.JobInProgress (JobInProgress.java:jobComplete(2098)) - Job job_200902211448_0001 has completed successfully.
[junit] 2009-02-21 14:48:44,620 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1782)) - Removed completed task 'attempt_200902211448_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:42750'
[junit] 2009-02-21 14:48:44,620 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1782)) - Removed completed task 'attempt_200902211448_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:42750'
[junit] 2009-02-21 14:48:44,621 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1782)) - Removed completed task 'attempt_200902211448_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:42750'
[junit] 2009-02-21 14:48:44,622 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902211448_0001_m_000000_0 done; removing files.
[junit] 2009-02-21 14:48:44,623 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902211448_0001_m_000000_0 not found in cache
[junit] 2009-02-21 14:48:44,623 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902211448_0001_m_000001_0 done; removing files.
[junit] 2009-02-21 14:48:44,624 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902211448_0001_m_000001_0 not found in cache
[junit] 2009-02-21 14:48:44,625 INFO mapred.JobClient (JobClient.java:runJob(1358)) - Job complete: job_200902211448_0001
[junit] 2009-02-21 14:48:44,627 INFO mapred.JobClient (Counters.java:log(514)) - Counters: 7
[junit] 2009-02-21 14:48:44,627 INFO mapred.JobClient (Counters.java:log(516)) - Job Counters
[junit] 2009-02-21 14:48:44,627 INFO mapred.JobClient (Counters.java:log(518)) - Launched map tasks=1
[junit] 2009-02-21 14:48:44,628 INFO mapred.JobClient (Counters.java:log(516)) - org.apache.hadoop.tools.DistCh$Counter
[junit] 2009-02-21 14:48:44,628 INFO mapred.JobClient (Counters.java:log(518)) - SUCCEED=3
[junit] 2009-02-21 14:48:44,628 INFO mapred.JobClient (Counters.java:log(516)) - FileSystemCounters
[junit] 2009-02-21 14:48:44,628 INFO mapred.JobClient (Counters.java:log(518)) - HDFS_BYTES_READ=377
[junit] 2009-02-21 14:48:44,629 INFO mapred.JobClient (Counters.java:log(516)) - Map-Reduce Framework
[junit] 2009-02-21 14:48:44,629 INFO mapred.JobClient (Counters.java:log(518)) - Map input records=3
[junit] 2009-02-21 14:48:44,629 INFO mapred.JobClient (Counters.java:log(518)) - Spilled Records=0
[junit] 2009-02-21 14:48:44,630 INFO mapred.JobClient (Counters.java:log(518)) - Map input bytes=280
[junit] 2009-02-21 14:48:44,630 INFO mapred.JobClient (Counters.java:log(518)) - Map output records=0
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-21 14:48 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-21 14:48 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-21 14:48 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub0
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub1/f4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub1/f5
[junit] drwxr-xr-x - hudson sub2 0 2009-02-21 14:48 /test/testDistCh/sub2
[junit] -rw-r--r-- 2 hudson sub2 43 2009-02-21 14:48 /test/testDistCh/sub2/f6
[junit] -rw-r--r-- 2 hudson sub2 43 2009-02-21 14:48 /test/testDistCh/sub2/f7
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub3/f8
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-21 14:48 /test/testDistCh/sub3/f9
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-21 14:48 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-02-21 14:48 /test/testDistCh/sub4/f10
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-02-21 14:48 /test/testDistCh/sub4/f11
[junit]
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-02-21 14:48:44,675 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 53825
[junit] 2009-02-21 14:48:44,676 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 53825: exiting
[junit] 2009-02-21 14:48:44,676 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 53825: exiting
[junit] 2009-02-21 14:48:44,676 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 53825: exiting
[junit] 2009-02-21 14:48:44,677 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 53825
[junit] 2009-02-21 14:48:44,677 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-21 14:48:44,678 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-21 14:48:44,678 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 53825
[junit] Shutting down DataNode 0
[junit] 2009-02-21 14:48:44,680 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 52600
[junit] 2009-02-21 14:48:44,681 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 52600: exiting
[junit] 2009-02-21 14:48:44,681 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 52600: exiting
[junit] 2009-02-21 14:48:44,681 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-21 14:48:44,681 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 52600
[junit] 2009-02-21 14:48:44,681 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 52600: exiting
[junit] 2009-02-21 14:48:44,734 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 52600
[junit] 2009-02-21 14:48:44,734 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 60188
[junit] 2009-02-21 14:48:45,783 INFO namenode.DecommissionManager (DecommissionManager.java:run(67)) - Interrupted Monitor
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor.run(DecommissionManager.java:65)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2009-02-21 14:48:45,784 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 5 on 60188: exiting
[junit] 2009-02-21 14:48:45,784 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 60188: exiting
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 4 on 60188: exiting
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 60188
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 60188: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 29.357 sec
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 60188: exiting
[junit] 2009-02-21 14:48:45,783 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 3 on 60188: exiting
[junit] 2009-02-21 14:48:45,784 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 9 on 60188: exiting
[junit] 2009-02-21 14:48:45,784 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 7 on 60188: exiting
[junit] 2009-02-21 14:48:45,784 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 8 on 60188: exiting
[junit] 2009-02-21 14:48:45,784 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 6 on 60188: exiting
[junit] Running org.apache.hadoop.util.TestCyclicIteration
[junit]
[junit]
[junit] integers=[]
[junit] map={}
[junit] start=-1, iteration=[]
[junit]
[junit]
[junit] integers=[0]
[junit] map={0=0}
[junit] start=-1, iteration=[0]
[junit] start=0, iteration=[0]
[junit] start=1, iteration=[0]
[junit]
[junit]
[junit] integers=[0, 2]
[junit] map={0=0, 2=2}
[junit] start=-1, iteration=[0, 2]
[junit] start=0, iteration=[2, 0]
[junit] start=1, iteration=[2, 0]
[junit] start=2, iteration=[0, 2]
[junit] start=3, iteration=[0, 2]
[junit]
[junit]
[junit] integers=[0, 2, 4]
[junit] map={0=0, 2=2, 4=4}
[junit] start=-1, iteration=[0, 2, 4]
[junit] start=0, iteration=[2, 4, 0]
[junit] start=1, iteration=[2, 4, 0]
[junit] start=2, iteration=[4, 0, 2]
[junit] start=3, iteration=[4, 0, 2]
[junit] start=4, iteration=[0, 2, 4]
[junit] start=5, iteration=[0, 2, 4]
[junit]
[junit]
[junit] integers=[0, 2, 4, 6]
[junit] map={0=0, 2=2, 4=4, 6=6}
[junit] start=-1, iteration=[0, 2, 4, 6]
[junit] start=0, iteration=[2, 4, 6, 0]
[junit] start=1, iteration=[2, 4, 6, 0]
[junit] start=2, iteration=[4, 6, 0, 2]
[junit] start=3, iteration=[4, 6, 0, 2]
[junit] start=4, iteration=[6, 0, 2, 4]
[junit] start=5, iteration=[6, 0, 2, 4]
[junit] start=6, iteration=[0, 2, 4, 6]
[junit] start=7, iteration=[0, 2, 4, 6]
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.091 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2009-02-21 14:48:46,906 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] 2009-02-21 14:48:46,918 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(377)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.179 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -5894779335503489242(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: 1787379181699531301(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 164/497(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: -6660231746477717747(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: -3308738001188700265(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: 7374946563590293511(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 242/299(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: -1128433868958545101(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.942 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2009-02-21 14:48:48,560 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(54)) - setsid exited with exit code 0
[junit] 2009-02-21 14:48:49,065 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(141)) - Root process pid: 10715
[junit] 2009-02-21 14:48:49,127 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(146)) - ProcessTree: [ 10717 10718 10715 ]
[junit] 2009-02-21 14:48:55,655 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(159)) - ProcessTree: [ 10717 10719 10731 10729 10715 10727 10725 10723 10721 ]
[junit] 2009-02-21 14:48:55,706 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(160)) - Killing all processes in the process group 10715 with SIGTERM. Exit code 0
[junit] 2009-02-21 14:48:55,706 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(64)) - Shell Command exit with a non-zero exit code. This is expected as we are killing the subprocesses of the task intentionally. org.apache.hadoop.util.Shell$ExitCodeException:
[junit] 2009-02-21 14:48:55,707 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(70)) - Exit code: 143
[junit] 2009-02-21 14:48:55,754 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(173)) - RogueTaskThread successfully joined.
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 7.252 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] 2009-02-21 14:48:56,589 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.569 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.186 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.089 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :768: Tests failed!
Total time: 169 minutes 45 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Publishing Clover coverage report...
Build failed in Hudson: Hadoop-trunk #760
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/760/changes
Changes:
[yhemanth] HADOOP-5214. Fixes a ConcurrentModificationException while the Fairshare Scheduler accesses the tasktrackers stored by the JobTracker. Contributed by Rahul Kumar Singh.
[ddas] HADOOP-5269. Fixes a problem to do with tasktracker holding on to FAILED_UNCLEAN or KILLED_UNCLEAN tasks forever. Contributed by Amareshwari Sriramadasu.
[cdouglas] HADOOP-5255. Fix use of Math.abs to avoid overflow. Contributed by Jonathan Ellis.
[hairong] HADOOP-4692. NameNode in infinite loop for replicating/deleting corrupt blocks. Constributed by Hairong Kuang.
[nigel] HADOOP-5279. Remove unnecessary targets from test-patch.sh. Contributed by Giridharan Kesavan.
------------------------------------------
[...truncated 288003 lines...]
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-20 14:38 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-20 14:38 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-20 14:38 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-20 14:38 /test/testDistCh/sub0
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub0/f4
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-20 14:38 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub1/f5
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub1/f6
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-20 14:38 /test/testDistCh/sub2
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-20 14:38 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub3/f7
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-20 14:38 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub4/f8
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub4/f9
[junit]
[junit] args=[/test/testDistCh/sub0:sub0::,
[junit] /test/testDistCh/sub1::sub1:223,
[junit] /test/testDistCh/sub4:sub4::,
[junit] /test/testDistCh/sub2::sub2:]
[junit] newstatus=[sub0:supergroup:rwxr-xr-x,
[junit] hudson:sub1:-w--w--wx,
[junit] hudson:sub2:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] sub4:supergroup:rwxr-xr-x]
[junit] 2009-02-20 14:38:15,688 INFO tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub0:sub0:null:null, /test/testDistCh/sub1:null:sub1:-w--w--wx, /test/testDistCh/sub4:sub4:null:null, /test/testDistCh/sub2:null:sub2:null]
[junit] 2009-02-20 14:38:15,689 INFO tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
[junit] 2009-02-20 14:38:15,710 INFO tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:39985/user/hudson/build/test/mapred/system/distch_svt0sg
[junit] 2009-02-20 14:38:15,713 INFO tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:39985/user/hudson/build/test/mapred/system/distch_svt0sg/_logs
[junit] 2009-02-20 14:38:15,793 INFO tools.DistTool (DistCh.java:setup(476)) - distch.op.count=9
[junit] 2009-02-20 14:38:15,800 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(539)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2009-02-20 14:38:15,821 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(661)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2009-02-20 14:38:15,835 INFO tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1, splits.size()=1
[junit] 2009-02-20 14:38:15,948 WARN conf.Configuration (Configuration.java:loadResource(1153)) - build/test/mapred/local/jobTracker/job_200902201438_0001.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-20 14:38:15,959 INFO mapred.JobClient (JobClient.java:runJob(1268)) - Running job: job_200902201438_0001
[junit] 2009-02-20 14:38:16,189 INFO mapred.JobInProgress (JobInProgress.java:initTasks(426)) - Input size for job job_200902201438_0001 = 893
[junit] 2009-02-20 14:38:16,190 INFO mapred.JobInProgress (JobInProgress.java:initTasks(428)) - Split info for job:job_200902201438_0001 with 1 splits:
[junit] 2009-02-20 14:38:16,965 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 0% reduce 0%
[junit] 2009-02-20 14:38:18,394 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1657)) - Adding task 'attempt_200902201438_0001_m_000002_0' to tip task_200902201438_0001_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:36063'
[junit] 2009-02-20 14:38:18,480 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902201438_0001/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-02-20 14:38:18,545 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902201438_0001_m_48785281
[junit] 2009-02-20 14:38:18,546 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902201438_0001_m_48785281 spawned.
[junit] 2009-02-20 14:38:19,496 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902201438_0001_m_48785281 exited. Number of tasks it ran: 1
[junit] 2009-02-20 14:38:21,404 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1982)) - Task 'attempt_200902201438_0001_m_000002_0' has completed task_200902201438_0001_m_000002 successfully.
[junit] 2009-02-20 14:38:21,408 INFO mapred.JobInProgress (JobInProgress.java:findNewMapTask(1817)) - Choosing a non-local task task_200902201438_0001_m_000000
[junit] 2009-02-20 14:38:21,408 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1657)) - Adding task 'attempt_200902201438_0001_m_000000_0' to tip task_200902201438_0001_m_000000, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:36063'
[junit] 2009-02-20 14:38:21,413 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902201438_0001_m_000002_0 done; removing files.
[junit] 2009-02-20 14:38:21,413 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902201438_0001_m_000002_0 not found in cache
[junit] 2009-02-20 14:38:21,461 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902201438_0001_m_298284637
[junit] 2009-02-20 14:38:21,462 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902201438_0001_m_298284637 spawned.
[junit] 2009-02-20 14:38:21,989 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902201438_0001_m_000002_0, Status : SUCCEEDED
[junit] attempt_200902201438_0001_m_000002_0: 2009-02-20 14:38:19,146 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902201438_0001/attempt_200902201438_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902201438_0001_m_000002_0: 2009-02-20 14:38:19,223 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902201438_0001_m_000002_0: 2009-02-20 14:38:19,311 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902201438_0001/attempt_200902201438_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902201438_0001_m_000002_0: 2009-02-20 14:38:19,361 INFO mapred.TaskRunner (Task.java:done(645)) - Task:attempt_200902201438_0001_m_000002_0 is done. And is in the process of commiting
[junit] attempt_200902201438_0001_m_000002_0: 2009-02-20 14:38:19,372 INFO mapred.TaskRunner (Task.java:sendDone(717)) - Task 'attempt_200902201438_0001_m_000002_0' done.
[junit] 2009-02-20 14:38:25,566 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902201438_0001_m_298284637 exited. Number of tasks it ran: 1
[junit] 2009-02-20 14:38:27,418 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1982)) - Task 'attempt_200902201438_0001_m_000000_0' has completed task_200902201438_0001_m_000000 successfully.
[junit] 2009-02-20 14:38:27,421 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1657)) - Adding task 'attempt_200902201438_0001_m_000001_0' to tip task_200902201438_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:36063'
[junit] 2009-02-20 14:38:27,452 INFO mapred.JvmManager (JvmManager.java:<init>(323)) - In JvmRunner constructed JVM ID: jvm_200902201438_0001_m_1156393185
[junit] 2009-02-20 14:38:27,452 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(294)) - JVM Runner jvm_200902201438_0001_m_1156393185 spawned.
[junit] 2009-02-20 14:38:28,089 INFO mapred.JobClient (JobClient.java:runJob(1291)) - map 100% reduce 0%
[junit] 2009-02-20 14:38:28,090 INFO mapred.JobClient (JobClient.java:runJob(1340)) - Task Id : attempt_200902201438_0001_m_000000_0, Status : SUCCEEDED
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:22,043 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902201438_0001/attempt_200902201438_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:22,123 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:22,212 WARN conf.Configuration (Configuration.java:loadResource(1153)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200902201438_0001/attempt_200902201438_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:22,310 INFO mapred.MapTask (MapTask.java:runOldMapper(343)) - numReduceTasks: 0
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:22,363 INFO mapred.TaskRunner (Task.java:done(645)) - Task:attempt_200902201438_0001_m_000000_0 is done. And is in the process of commiting
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:25,371 INFO mapred.TaskRunner (Task.java:commit(746)) - Task attempt_200902201438_0001_m_000000_0 is allowed to commit now
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:25,395 INFO mapred.FileOutputCommitter (FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200902201438_0001_m_000000_0' to hdfs://localhost:39985/user/hudson/build/test/mapred/system/distch_svt0sg/_logs
[junit] attempt_200902201438_0001_m_000000_0: 2009-02-20 14:38:25,402 INFO mapred.TaskRunner (Task.java:sendDone(717)) - Task 'attempt_200902201438_0001_m_000000_0' done.
[junit] 2009-02-20 14:38:28,404 INFO mapred.JvmManager (JvmManager.java:runChild(347)) - JVM : jvm_200902201438_0001_m_1156393185 exited. Number of tasks it ran: 1
[junit] 2009-02-20 14:38:30,424 INFO mapred.JobInProgress (JobInProgress.java:completedTask(1982)) - Task 'attempt_200902201438_0001_m_000001_0' has completed task_200902201438_0001_m_000001 successfully.
[junit] 2009-02-20 14:38:30,426 INFO mapred.JobInProgress (JobInProgress.java:jobComplete(2099)) - Job job_200902201438_0001 has completed successfully.
[junit] 2009-02-20 14:38:30,532 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1778)) - Removed completed task 'attempt_200902201438_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:36063'
[junit] 2009-02-20 14:38:30,534 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1778)) - Removed completed task 'attempt_200902201438_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:36063'
[junit] 2009-02-20 14:38:30,534 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(1778)) - Removed completed task 'attempt_200902201438_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:36063'
[junit] 2009-02-20 14:38:30,536 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902201438_0001_m_000001_0 done; removing files.
[junit] 2009-02-20 14:38:30,536 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902201438_0001_m_000001_0 not found in cache
[junit] 2009-02-20 14:38:30,537 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200902201438_0001_m_000000_0 done; removing files.
[junit] 2009-02-20 14:38:30,537 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200902201438_0001_m_000000_0 not found in cache
[junit] 2009-02-20 14:38:31,104 INFO mapred.JobClient (JobClient.java:runJob(1358)) - Job complete: job_200902201438_0001
[junit] 2009-02-20 14:38:31,106 INFO mapred.JobClient (Counters.java:log(514)) - Counters: 7
[junit] 2009-02-20 14:38:31,106 INFO mapred.JobClient (Counters.java:log(516)) - Job Counters
[junit] 2009-02-20 14:38:31,107 INFO mapred.JobClient (Counters.java:log(518)) - Launched map tasks=1
[junit] 2009-02-20 14:38:31,107 INFO mapred.JobClient (Counters.java:log(516)) - org.apache.hadoop.tools.DistCh$Counter
[junit] 2009-02-20 14:38:31,107 INFO mapred.JobClient (Counters.java:log(518)) - SUCCEED=9
[junit] 2009-02-20 14:38:31,108 INFO mapred.JobClient (Counters.java:log(516)) - FileSystemCounters
[junit] 2009-02-20 14:38:31,108 INFO mapred.JobClient (Counters.java:log(518)) - HDFS_BYTES_READ=893
[junit] 2009-02-20 14:38:31,108 INFO mapred.JobClient (Counters.java:log(516)) - Map-Reduce Framework
[junit] 2009-02-20 14:38:31,109 INFO mapred.JobClient (Counters.java:log(518)) - Map input records=9
[junit] 2009-02-20 14:38:31,109 INFO mapred.JobClient (Counters.java:log(518)) - Spilled Records=0
[junit] 2009-02-20 14:38:31,109 INFO mapred.JobClient (Counters.java:log(518)) - Map input bytes=796
[junit] 2009-02-20 14:38:31,110 INFO mapred.JobClient (Counters.java:log(518)) - Map output records=0
[junit] root=/test/testDistCh, returnvalue=0
[junit] results:
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-20 14:38 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-20 14:38 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-02-20 14:38 /test/testDistCh/f3
[junit] drwxr-xr-x - sub0 supergroup 0 2009-02-20 14:38 /test/testDistCh/sub0
[junit] -rw-r--r-- 2 sub0 supergroup 43 2009-02-20 14:38 /test/testDistCh/sub0/f4
[junit] d-w--w--wx - hudson sub1 0 2009-02-20 14:38 /test/testDistCh/sub1
[junit] --w--w--w- 2 hudson sub1 43 2009-02-20 14:38 /test/testDistCh/sub1/f5
[junit] --w--w--w- 2 hudson sub1 43 2009-02-20 14:38 /test/testDistCh/sub1/f6
[junit] drwxr-xr-x - hudson sub2 0 2009-02-20 14:38 /test/testDistCh/sub2
[junit] drwxr-xr-x - hudson supergroup 0 2009-02-20 14:38 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-02-20 14:38 /test/testDistCh/sub3/f7
[junit] drwxr-xr-x - sub4 supergroup 0 2009-02-20 14:38 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 sub4 supergroup 43 2009-02-20 14:38 /test/testDistCh/sub4/f8
[junit] -rw-r--r-- 2 sub4 supergroup 43 2009-02-20 14:38 /test/testDistCh/sub4/f9
[junit]
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-02-20 14:38:31,235 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 35419
[junit] 2009-02-20 14:38:31,235 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 35419: exiting
[junit] 2009-02-20 14:38:31,236 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 35419: exiting
[junit] 2009-02-20 14:38:31,236 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 35419: exiting
[junit] 2009-02-20 14:38:31,236 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-20 14:38:31,235 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 35419
[junit] 2009-02-20 14:38:31,923 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-20 14:38:32,237 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 35419
[junit] Shutting down DataNode 0
[junit] 2009-02-20 14:38:32,261 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 52678
[junit] 2009-02-20 14:38:32,262 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 52678: exiting
[junit] 2009-02-20 14:38:32,262 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 52678: exiting
[junit] 2009-02-20 14:38:32,262 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 52678: exiting
[junit] 2009-02-20 14:38:32,262 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
[junit] 2009-02-20 14:38:32,262 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-20 14:38:32,262 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 52678
[junit] 2009-02-20 14:38:32,263 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 52678
[junit] 2009-02-20 14:38:32,266 INFO namenode.DecommissionManager (DecommissionManager.java:run(67)) - Interrupted Monitor
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor.run(DecommissionManager.java:65)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:stop(1098)) - Stopping server on 39985
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 39985: exiting
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 39985
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 3 on 39985: exiting
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 39985: exiting
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 5 on 39985: exiting
[junit] 2009-02-20 14:38:32,268 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 9 on 39985: exiting
[junit] 2009-02-20 14:38:32,268 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 7 on 39985: exiting
[junit] 2009-02-20 14:38:32,268 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 8 on 39985: exiting
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 39985: exiting
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 6 on 39985: exiting
[junit] 2009-02-20 14:38:32,267 INFO ipc.Server (Server.java:run(992)) - IPC Server handler 4 on 39985: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 29.927 sec
[junit] Running org.apache.hadoop.util.TestCyclicIteration
[junit]
[junit]
[junit] integers=[]
[junit] map={}
[junit] start=-1, iteration=[]
[junit]
[junit]
[junit] integers=[0]
[junit] map={0=0}
[junit] start=-1, iteration=[0]
[junit] start=0, iteration=[0]
[junit] start=1, iteration=[0]
[junit]
[junit]
[junit] integers=[0, 2]
[junit] map={0=0, 2=2}
[junit] start=-1, iteration=[0, 2]
[junit] start=0, iteration=[2, 0]
[junit] start=1, iteration=[2, 0]
[junit] start=2, iteration=[0, 2]
[junit] start=3, iteration=[0, 2]
[junit]
[junit]
[junit] integers=[0, 2, 4]
[junit] map={0=0, 2=2, 4=4}
[junit] start=-1, iteration=[0, 2, 4]
[junit] start=0, iteration=[2, 4, 0]
[junit] start=1, iteration=[2, 4, 0]
[junit] start=2, iteration=[4, 0, 2]
[junit] start=3, iteration=[4, 0, 2]
[junit] start=4, iteration=[0, 2, 4]
[junit] start=5, iteration=[0, 2, 4]
[junit]
[junit]
[junit] integers=[0, 2, 4, 6]
[junit] map={0=0, 2=2, 4=4, 6=6}
[junit] start=-1, iteration=[0, 2, 4, 6]
[junit] start=0, iteration=[2, 4, 6, 0]
[junit] start=1, iteration=[2, 4, 6, 0]
[junit] start=2, iteration=[4, 6, 0, 2]
[junit] start=3, iteration=[4, 6, 0, 2]
[junit] start=4, iteration=[6, 0, 2, 4]
[junit] start=5, iteration=[6, 0, 2, 4]
[junit] start=6, iteration=[0, 2, 4, 6]
[junit] start=7, iteration=[0, 2, 4, 6]
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.089 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2009-02-20 14:38:33,356 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] 2009-02-20 14:38:33,369 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(377)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.182 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -3478691782628213688(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: 7971807591902186613(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 93/480(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: 7155440397355306951(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: 5078785408904660146(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: -3498426390266898501(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 349/36(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: 9004327206485671549(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.938 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2009-02-20 14:38:34,944 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(54)) - setsid exited with exit code 0
[junit] 2009-02-20 14:38:35,449 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(141)) - Root process pid: 26595
[junit] 2009-02-20 14:38:35,513 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(146)) - ProcessTree: [ 26598 26597 26595 ]
[junit] 2009-02-20 14:38:42,040 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(159)) - ProcessTree: [ 26599 26597 26595 26611 26609 26607 26605 26603 26601 ]
[junit] 2009-02-20 14:38:42,053 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(64)) - Shell Command exit with a non-zero exit code. This is expected as we are killing the subprocesses of the task intentionally. org.apache.hadoop.util.Shell$ExitCodeException:
[junit] 2009-02-20 14:38:42,054 INFO util.ProcessTree (ProcessTree.java:destroyProcessGroup(160)) - Killing all processes in the process group 26595 with SIGTERM. Exit code 0
[junit] 2009-02-20 14:38:42,054 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(70)) - Exit code: 143
[junit] 2009-02-20 14:38:42,103 INFO util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(173)) - RogueTaskThread successfully joined.
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 7.216 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] 2009-02-20 14:38:42,973 WARN conf.Configuration (Configuration.java:<clinit>(175)) - 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] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.567 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.179 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.087 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :768: Tests failed!
Total time: 161 minutes 20 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Publishing Clover coverage report...