hadoop-common-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: Hadoop-trunk #851
Date Sat, 30 May 2009 15:27:15 GMT
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/851/changes

Changes:

[hairong] HADOOP-5864. Fix DMI and OBL findbugs in packages hdfs and metrics. Contributed
by Hairong Kuang.

[szetszwo] Revert HADOOP-5700.

[gkesavan] HADOOP-5940. Fix trunk eclipse-plugin build fails while trying to copy commons-cli
jar from the lib dir. Contributed by Giridharan Kesavan

[sharad] HADOOP-5698. Change org.apache.hadoop.examples.MultiFileWordCount to use new mapreduce
api. Contributed by Amareshwari Sriramadasu.

------------------------------------------
[...truncated 468592 lines...]
    [junit] 2009-05-30 15:44:34,718 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 49839: starting
    [junit] 2009-05-30 15:44:34,718 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 49839: starting
    [junit] 2009-05-30 15:44:34,718 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 49839: starting
    [junit] 2009-05-30 15:44:34,720 INFO  net.NetworkTopology (NetworkTopology.java:add(327))
- Adding a new node: /default-rack/127.0.0.1:43202
    [junit] 2009-05-30 15:44:34,718 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 49839: starting
    [junit] Generating rack names for tasktrackers
    [junit] Generating host names for tasktrackers
    [junit] 2009-05-30 15:44:34,922 INFO  mapred.JobTracker (JobTracker.java:<init>(1551))
- Starting jobtracker with owner as hudson and supergroup as supergroup
    [junit] 2009-05-30 15:44:34,922 INFO  mapred.JobTracker (JobTracker.java:initializeTaskMemoryRelatedConfig(3821))
- Scheduler configured with (memSizeForMapSlotOnJT, memSizeForReduceSlotOnJT, limitMaxMemForMapTasks,
limitMaxMemForReduceTasks) (-1, -1, -1, -1)
    [junit] 2009-05-30 15:44:34,923 INFO  util.HostsFileReader (HostsFileReader.java:refresh(76))
- Refreshing hosts (include/exclude) list
    [junit] 2009-05-30 15:44:34,972 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58))
- Initializing RPC Metrics with hostName=JobTracker, port=37908
    [junit] 2009-05-30 15:44:34,986 INFO  http.HttpServer (HttpServer.java:start(425)) - Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener
on 0
    [junit] 2009-05-30 15:44:34,986 INFO  http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort()
returned 45234 webServer.getConnectors()[0].getLocalPort() returned 45234
    [junit] 2009-05-30 15:44:34,987 INFO  http.HttpServer (HttpServer.java:start(463)) - Jetty
bound to port 45234
    [junit] 2009-05-30 15:44:34,987 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-05-30 15:44:35,075 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:45234
    [junit] 2009-05-30 15:44:35,077 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot
initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
    [junit] 2009-05-30 15:44:35,078 INFO  mapred.JobTracker (JobTracker.java:<init>(1662))
- JobTracker up at: 37908
    [junit] 2009-05-30 15:44:35,078 INFO  mapred.JobTracker (JobTracker.java:<init>(1666))
- JobTracker webserver: 45234
    [junit] 2009-05-30 15:44:35,087 INFO  mapred.JobTracker (JobTracker.java:<init>(1704))
- Cleaning up the system directory
    [junit] 2009-05-30 15:44:35,338 INFO  mapred.JobTracker (JobTracker.java:refreshHosts(3654))
- Refreshing hosts information
    [junit] 2009-05-30 15:44:35,362 INFO  util.HostsFileReader (HostsFileReader.java:setIncludesFile(100))
- Setting the includes file to 
    [junit] 2009-05-30 15:44:35,362 INFO  util.HostsFileReader (HostsFileReader.java:setExcludesFile(105))
- Setting the excludes file to hosts.exclude
    [junit] 2009-05-30 15:44:35,362 INFO  util.HostsFileReader (HostsFileReader.java:refresh(76))
- Refreshing hosts (include/exclude) list
    [junit] 2009-05-30 15:44:35,363 INFO  mapred.JobTracker (JobTracker.java:decommissionNodes(3676))
- Decommissioning 0 nodes
    [junit] 2009-05-30 15:44:35,363 INFO  ipc.Server (Server.java:run(474)) - IPC Server Responder:
starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 37908: starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 37908: starting
    [junit] 2009-05-30 15:44:35,363 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 37908: starting
    [junit] 2009-05-30 15:44:35,365 INFO  mapred.JobTracker (JobTracker.java:offerService(1885))
- Starting RUNNING
    [junit] 2009-05-30 15:44:35,365 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
8 on 37908: starting
    [junit] 2009-05-30 15:44:35,365 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
9 on 37908: starting
    [junit] 2009-05-30 15:44:35,365 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
7 on 37908: starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
6 on 37908: starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
5 on 37908: starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
4 on 37908: starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 37908: starting
    [junit] 2009-05-30 15:44:35,364 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 37908: starting
    [junit] 2009-05-30 15:44:35,884 INFO  mapred.MiniMRCluster (MiniMRCluster.java:<init>(176))
- mapred.local.dir is http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/0_0

    [junit] 2009-05-30 15:44:40,909 INFO  http.HttpServer (HttpServer.java:start(425)) - Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener
on 0
    [junit] 2009-05-30 15:44:40,909 INFO  http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort()
returned 60257 webServer.getConnectors()[0].getLocalPort() returned 60257
    [junit] 2009-05-30 15:44:40,910 INFO  http.HttpServer (HttpServer.java:start(463)) - Jetty
bound to port 60257
    [junit] 2009-05-30 15:44:40,910 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-05-30 15:44:40,972 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:60257
    [junit] 2009-05-30 15:44:40,977 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot
initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
    [junit] 2009-05-30 15:44:40,980 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58))
- Initializing RPC Metrics with hostName=TaskTracker, port=53531
    [junit] 2009-05-30 15:44:40,981 INFO  ipc.Server (Server.java:run(474)) - IPC Server Responder:
starting
    [junit] 2009-05-30 15:44:40,981 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 53531: starting
    [junit] 2009-05-30 15:44:40,981 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 53531: starting
    [junit] 2009-05-30 15:44:40,981 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 53531: starting
    [junit] 2009-05-30 15:44:40,982 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 53531: starting
    [junit] 2009-05-30 15:44:40,981 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 53531: starting
    [junit] 2009-05-30 15:44:41,015 INFO  util.ProcessTree (ProcessTree.java:isSetsidSupported(51))
- setsid exited with exit code 0
    [junit] 2009-05-30 15:44:41,018 INFO  mapred.IndexCache (IndexCache.java:<init>(46))
- IndexCache created with max memory = 10485760
    [junit] 2009-05-30 15:44:41,054 INFO  net.NetworkTopology (NetworkTopology.java:add(327))
- Adding a new node: /default-rack/host0.foo.com
    [junit] 2009-05-30 15:44:41,055 INFO  mapred.JobTracker (JobTracker.java:addNewTracker(2417))
- Adding tracker tracker_host0.foo.com:localhost/127.0.0.1:53531 to host host0.foo.com
    [junit] 2009-05-30 15:44:41,056 INFO  mapred.MiniMRCluster (MiniMRCluster.java:<init>(176))
- mapred.local.dir is http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0

    [junit] 2009-05-30 15:44:46,077 INFO  http.HttpServer (HttpServer.java:start(425)) - Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener
on 0
    [junit] 2009-05-30 15:44:46,078 INFO  http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort()
returned 41761 webServer.getConnectors()[0].getLocalPort() returned 41761
    [junit] 2009-05-30 15:44:46,078 INFO  http.HttpServer (HttpServer.java:start(463)) - Jetty
bound to port 41761
    [junit] 2009-05-30 15:44:46,078 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-05-30 15:44:46,177 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:41761
    [junit] 2009-05-30 15:44:46,179 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot
initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
    [junit] 2009-05-30 15:44:46,180 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58))
- Initializing RPC Metrics with hostName=TaskTracker, port=50031
    [junit] 2009-05-30 15:44:46,181 INFO  ipc.Server (Server.java:run(474)) - IPC Server Responder:
starting
    [junit] 2009-05-30 15:44:46,181 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 50031: starting
    [junit] 2009-05-30 15:44:46,181 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 50031: starting
    [junit] 2009-05-30 15:44:46,223 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 50031: starting
    [junit] 2009-05-30 15:44:46,223 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 50031: starting
    [junit] 2009-05-30 15:44:46,223 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 50031: starting
    [junit] 2009-05-30 15:44:46,252 INFO  mapred.IndexCache (IndexCache.java:<init>(46))
- IndexCache created with max memory = 10485760
    [junit] 2009-05-30 15:44:46,272 INFO  net.NetworkTopology (NetworkTopology.java:add(327))
- Adding a new node: /default-rack/host1.foo.com
    [junit] 2009-05-30 15:44:46,273 INFO  mapred.JobTracker (JobTracker.java:addNewTracker(2417))
- Adding tracker tracker_host1.foo.com:localhost/127.0.0.1:50031 to host host1.foo.com
    [junit] rootdir = /test/testDistCh
    [junit] root=/test/testDistCh, returnvalue=0
    [junit] results:
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-05-30 15:44 /test/testDistCh/f1
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-05-30 15:44 /test/testDistCh/f2
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-05-30 15:44 /test/testDistCh/f3
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub0
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub1
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-05-30 15:44 /test/testDistCh/sub1/f4
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub2
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-05-30 15:44 /test/testDistCh/sub2/f5
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-05-30 15:44 /test/testDistCh/sub2/f6
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub3
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub4
    [junit] 
    [junit] args=[/test/testDistCh/sub4:::773,
    [junit]    /test/testDistCh/sub3:::752,
    [junit]    /test/testDistCh/sub2:sub2:sub2:,
    [junit]    /test/testDistCh/sub1:::436]
    [junit] newstatus=[hudson:supergroup:rwxr-xr-x,
    [junit]    hudson:supergroup:r---wxrw-,
    [junit]    sub2:sub2:rwxr-xr-x,
    [junit]    hudson:supergroup:rwxr-x-w-,
    [junit]    hudson:supergroup:rwxrwx-wx]
    [junit] 2009-05-30 15:44:46,843 INFO  tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub4:null:null:rwxrwx-wx,
/test/testDistCh/sub3:null:null:rwxr-x-w-, /test/testDistCh/sub2:sub2:sub2:null, /test/testDistCh/sub1:null:null:r---wxrw-]
    [junit] 2009-05-30 15:44:46,844 INFO  tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
    [junit] 2009-05-30 15:44:46,865 INFO  tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:52905/user/hudson/build/test/mapred/system/distch_raqwi0
    [junit] 2009-05-30 15:44:46,869 INFO  tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:52905/user/hudson/build/test/mapred/system/distch_raqwi0/_logs
    [junit] 2009-05-30 15:44:47,016 INFO  tools.DistTool (DistCh.java:setup(476)) - distch.op.count=7
    [junit] 2009-05-30 15:44:47,022 WARN  mapred.JobClient (JobClient.java:configureCommandLineOptions(551))
- Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for
the same.
    [junit] 2009-05-30 15:44:47,055 WARN  mapred.JobClient (JobClient.java:configureCommandLineOptions(673))
- No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2009-05-30 15:44:47,069 INFO  tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1,
splits.size()=1
    [junit] 2009-05-30 15:44:47,364 WARN  conf.Configuration (Configuration.java:loadResource(1179))
- http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/jobTracker/job_200905301544_0001.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2009-05-30 15:44:47,372 INFO  mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:run(80))
- Initializing job_200905301544_0001
    [junit] 2009-05-30 15:44:47,373 INFO  mapred.JobInProgress (JobInProgress.java:initTasks(384))
- Initializing job_200905301544_0001
    [junit] 2009-05-30 15:44:47,375 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1290))
- Running job: job_200905301544_0001
    [junit] 2009-05-30 15:44:47,561 INFO  mapred.JobInProgress (JobInProgress.java:initTasks(428))
- Input size for job job_200905301544_0001 = 670. Number of splits = 1
    [junit] 2009-05-30 15:44:48,379 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1303))
-  map 0% reduce 0%
    [junit] 2009-05-30 15:44:49,299 INFO  mapred.JobTracker (JobTracker.java:createTaskEntry(1953))
- Adding task 'attempt_200905301544_0001_m_000002_0' to tip task_200905301544_0001_m_000002,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:50031'
    [junit] 2009-05-30 15:44:49,380 WARN  conf.Configuration (Configuration.java:loadResource(1179))
- http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2009-05-30 15:44:49,446 INFO  mapred.JvmManager (JvmManager.java:<init>(383))
- In JvmRunner constructed JVM ID: jvm_200905301544_0001_m_-2042027410
    [junit] 2009-05-30 15:44:49,446 INFO  mapred.JvmManager (JvmManager.java:spawnNewJvm(351))
- JVM Runner jvm_200905301544_0001_m_-2042027410 spawned.
    [junit] 2009-05-30 15:44:50,576 WARN  util.ProcessTree (ProcessTree.java:destroyProcessGroup(166))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-05-30 15:44:50,576 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(168))
- Killing all processes in the process group 24155 with SIGTERM. Exit code 1
    [junit] 2009-05-30 15:44:52,311 INFO  mapred.JobInProgress (JobInProgress.java:completedTask(1949))
- Task 'attempt_200905301544_0001_m_000002_0' has completed task_200905301544_0001_m_000002
successfully.
    [junit] 2009-05-30 15:44:52,316 INFO  mapred.JobInProgress (JobInProgress.java:findNewMapTask(1787))
- Choosing a non-local task task_200905301544_0001_m_000000
    [junit] 2009-05-30 15:44:52,317 INFO  mapred.JobTracker (JobTracker.java:createTaskEntry(1953))
- Adding task 'attempt_200905301544_0001_m_000000_0' to tip task_200905301544_0001_m_000000,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:50031'
    [junit] 2009-05-30 15:44:52,321 INFO  mapred.TaskRunner (MapTaskRunner.java:close(43))
- attempt_200905301544_0001_m_000002_0 done; removing files.
    [junit] 2009-05-30 15:44:52,322 INFO  mapred.IndexCache (IndexCache.java:removeMap(140))
- Map ID attempt_200905301544_0001_m_000002_0 not found in cache
    [junit] 2009-05-30 15:44:52,368 INFO  mapred.JvmManager (JvmManager.java:<init>(383))
- In JvmRunner constructed JVM ID: jvm_200905301544_0001_m_-900065837
    [junit] 2009-05-30 15:44:52,369 INFO  mapred.JvmManager (JvmManager.java:spawnNewJvm(351))
- JVM Runner jvm_200905301544_0001_m_-900065837 spawned.
    [junit] 2009-05-30 15:44:52,393 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1352))
- Task Id : attempt_200905301544_0001_m_000002_0, Status : SUCCEEDED
    [junit] attempt_200905301544_0001_m_000002_0: 2009-05-30 15:44:50,189 WARN  conf.Configuration
(Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/attempt_200905301544_0001_m_000002_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200905301544_0001_m_000002_0: 2009-05-30 15:44:50,206 INFO  jvm.JvmMetrics
(JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
    [junit] attempt_200905301544_0001_m_000002_0: 2009-05-30 15:44:50,299 WARN  conf.Configuration
(Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/attempt_200905301544_0001_m_000002_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200905301544_0001_m_000002_0: 2009-05-30 15:44:50,360 INFO  mapred.Task
(Task.java:done(620)) - Task:attempt_200905301544_0001_m_000002_0 is done. And is in the process
of commiting
    [junit] attempt_200905301544_0001_m_000002_0: 2009-05-30 15:44:50,374 INFO  mapred.Task
(Task.java:sendDone(691)) - Task 'attempt_200905301544_0001_m_000002_0' done.
    [junit] 2009-05-30 15:44:55,591 WARN  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(91))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-05-30 15:44:55,592 INFO  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(94))
- Killing process group24155 with SIGKILL. Exit code 1
    [junit] 2009-05-30 15:44:55,592 INFO  mapred.DefaultTaskController (DefaultTaskController.java:killTaskJVM(87))
- Process exited with exit code:0
    [junit] 2009-05-30 15:44:55,592 INFO  mapred.JvmManager (JvmManager.java:runChild(411))
- JVM : jvm_200905301544_0001_m_-2042027410 exited. Number of tasks it ran: 1
    [junit] 2009-05-30 15:44:55,721 WARN  util.ProcessTree (ProcessTree.java:destroyProcessGroup(166))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-05-30 15:44:55,721 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(168))
- Killing all processes in the process group 24204 with SIGTERM. Exit code 1
    [junit] 2009-05-30 15:44:58,327 INFO  mapred.JobInProgress (JobInProgress.java:completedTask(1949))
- Task 'attempt_200905301544_0001_m_000000_0' has completed task_200905301544_0001_m_000000
successfully.
    [junit] 2009-05-30 15:44:58,331 INFO  mapred.JobTracker (JobTracker.java:createTaskEntry(1953))
- Adding task 'attempt_200905301544_0001_m_000001_0' to tip task_200905301544_0001_m_000001,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:50031'
    [junit] 2009-05-30 15:44:58,360 INFO  mapred.JvmManager (JvmManager.java:<init>(383))
- In JvmRunner constructed JVM ID: jvm_200905301544_0001_m_615920812
    [junit] 2009-05-30 15:44:58,361 INFO  mapred.JvmManager (JvmManager.java:spawnNewJvm(351))
- JVM Runner jvm_200905301544_0001_m_615920812 spawned.
    [junit] 2009-05-30 15:44:58,469 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1352))
- Task Id : attempt_200905301544_0001_m_000000_0, Status : SUCCEEDED
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:53,104 WARN  conf.Configuration
(Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/attempt_200905301544_0001_m_000000_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:53,121 INFO  jvm.JvmMetrics
(JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:53,217 WARN  conf.Configuration
(Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/attempt_200905301544_0001_m_000000_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:53,310 INFO  mapred.MapTask
(MapTask.java:runOldMapper(352)) - numReduceTasks: 0
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:53,489 INFO  mapred.Task
(Task.java:done(620)) - Task:attempt_200905301544_0001_m_000000_0 is done. And is in the process
of commiting
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:55,497 INFO  mapred.Task
(Task.java:commit(720)) - Task attempt_200905301544_0001_m_000000_0 is allowed to commit now
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:55,543 INFO  mapred.FileOutputCommitter
(FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200905301544_0001_m_000000_0'
to hdfs://localhost:52905/user/hudson/build/test/mapred/system/distch_raqwi0/_logs
    [junit] attempt_200905301544_0001_m_000000_0: 2009-05-30 15:44:55,551 INFO  mapred.Task
(Task.java:sendDone(691)) - Task 'attempt_200905301544_0001_m_000000_0' done.
    [junit] 2009-05-30 15:44:59,415 WARN  util.ProcessTree (ProcessTree.java:destroyProcessGroup(166))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-05-30 15:44:59,415 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(168))
- Killing all processes in the process group 24259 with SIGTERM. Exit code 1
    [junit] 2009-05-30 15:44:59,490 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1303))
-  map 100% reduce 0%
    [junit] 2009-05-30 15:45:00,735 WARN  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(91))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-05-30 15:45:00,736 INFO  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(94))
- Killing process group24204 with SIGKILL. Exit code 1
    [junit] 2009-05-30 15:45:00,736 INFO  mapred.DefaultTaskController (DefaultTaskController.java:killTaskJVM(87))
- Process exited with exit code:0
    [junit] 2009-05-30 15:45:00,736 INFO  mapred.JvmManager (JvmManager.java:runChild(411))
- JVM : jvm_200905301544_0001_m_-900065837 exited. Number of tasks it ran: 1
    [junit] 2009-05-30 15:45:01,335 INFO  mapred.JobInProgress (JobInProgress.java:completedTask(1949))
- Task 'attempt_200905301544_0001_m_000001_0' has completed task_200905301544_0001_m_000001
successfully.
    [junit] 2009-05-30 15:45:01,336 INFO  mapred.JobInProgress (JobInProgress.java:jobComplete(2073))
- Job job_200905301544_0001 has completed successfully.
    [junit] 2009-05-30 15:45:01,403 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(2068))
- Removed completed task 'attempt_200905301544_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:50031'
    [junit] 2009-05-30 15:45:01,404 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(2068))
- Removed completed task 'attempt_200905301544_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:50031'
    [junit] 2009-05-30 15:45:01,404 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(2068))
- Removed completed task 'attempt_200905301544_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:50031'
    [junit] 2009-05-30 15:45:01,406 INFO  mapred.TaskRunner (MapTaskRunner.java:close(43))
- attempt_200905301544_0001_m_000001_0 done; removing files.
    [junit] 2009-05-30 15:45:01,407 INFO  mapred.IndexCache (IndexCache.java:removeMap(140))
- Map ID attempt_200905301544_0001_m_000001_0 not found in cache
    [junit] 2009-05-30 15:45:01,407 INFO  mapred.TaskRunner (MapTaskRunner.java:close(43))
- attempt_200905301544_0001_m_000000_0 done; removing files.
    [junit] 2009-05-30 15:45:01,408 INFO  mapred.IndexCache (IndexCache.java:removeMap(140))
- Map ID attempt_200905301544_0001_m_000000_0 not found in cache
    [junit] 2009-05-30 15:45:01,496 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1352))
- Task Id : attempt_200905301544_0001_m_000001_0, Status : SUCCEEDED
    [junit] attempt_200905301544_0001_m_000001_0: 2009-05-30 15:44:59,065 WARN  conf.Configuration
(Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/attempt_200905301544_0001_m_000001_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200905301544_0001_m_000001_0: 2009-05-30 15:44:59,082 INFO  jvm.JvmMetrics
(JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
    [junit] attempt_200905301544_0001_m_000001_0: 2009-05-30 15:44:59,180 WARN  conf.Configuration
(Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0/taskTracker/jobcache/job_200905301544_0001/attempt_200905301544_0001_m_000001_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200905301544_0001_m_000001_0: 2009-05-30 15:44:59,250 INFO  mapred.Task
(Task.java:done(620)) - Task:attempt_200905301544_0001_m_000001_0 is done. And is in the process
of commiting
    [junit] attempt_200905301544_0001_m_000001_0: 2009-05-30 15:44:59,255 INFO  mapred.Task
(Task.java:sendDone(691)) - Task 'attempt_200905301544_0001_m_000001_0' done.
    [junit] 2009-05-30 15:45:01,499 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1358))
- Job complete: job_200905301544_0001
    [junit] 2009-05-30 15:45:01,502 INFO  mapred.JobClient (Counters.java:log(534)) - Counters:
7
    [junit] 2009-05-30 15:45:01,502 INFO  mapred.JobClient (Counters.java:log(536)) -   Job
Counters 
    [junit] 2009-05-30 15:45:01,503 INFO  mapred.JobClient (Counters.java:log(538)) -    
Launched map tasks=1
    [junit] 2009-05-30 15:45:01,503 INFO  mapred.JobClient (Counters.java:log(536)) -   FileInputFormatCounters
    [junit] 2009-05-30 15:45:01,503 INFO  mapred.JobClient (Counters.java:log(538)) -    
BYTES_READ=573
    [junit] 2009-05-30 15:45:01,504 INFO  mapred.JobClient (Counters.java:log(536)) -   org.apache.hadoop.tools.DistCh$Counter
    [junit] 2009-05-30 15:45:01,505 INFO  mapred.JobClient (Counters.java:log(538)) -    
SUCCEED=7
    [junit] 2009-05-30 15:45:01,506 INFO  mapred.JobClient (Counters.java:log(536)) -   FileSystemCounters
    [junit] 2009-05-30 15:45:01,507 INFO  mapred.JobClient (Counters.java:log(538)) -    
HDFS_BYTES_READ=670
    [junit] 2009-05-30 15:45:01,508 INFO  mapred.JobClient (Counters.java:log(536)) -   Map-Reduce
Framework
    [junit] 2009-05-30 15:45:01,508 INFO  mapred.JobClient (Counters.java:log(538)) -    
Map input records=7
    [junit] 2009-05-30 15:45:01,508 INFO  mapred.JobClient (Counters.java:log(538)) -    
Spilled Records=0
    [junit] 2009-05-30 15:45:01,509 INFO  mapred.JobClient (Counters.java:log(538)) -    
Map output records=0
    [junit] root=/test/testDistCh, returnvalue=0
    [junit] results:
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-05-30 15:44 /test/testDistCh/f1
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-05-30 15:44 /test/testDistCh/f2
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-05-30 15:44 /test/testDistCh/f3
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub0
    [junit] dr---wxrw-   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub1
    [junit] -r---w-rw-   2 hudson supergroup         43 2009-05-30 15:44 /test/testDistCh/sub1/f4
    [junit] drwxr-xr-x   - sub2   sub2                0 2009-05-30 15:44 /test/testDistCh/sub2
    [junit] -rw-r--r--   2 sub2 sub2         43 2009-05-30 15:44 /test/testDistCh/sub2/f5
    [junit] -rw-r--r--   2 sub2 sub2         43 2009-05-30 15:44 /test/testDistCh/sub2/f6
    [junit] drwxr-x-w-   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub3
    [junit] drwxrwx-wx   - hudson supergroup          0 2009-05-30 15:44 /test/testDistCh/sub4
    [junit] 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-05-30 15:45:01,703 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 49839
    [junit] 2009-05-30 15:45:01,704 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
0 on 49839: exiting
    [junit] 2009-05-30 15:45:01,705 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
2 on 49839: exiting
    [junit] 2009-05-30 15:45:01,705 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
1 on 49839: exiting
    [junit] 2009-05-30 15:45:01,706 INFO  ipc.Server (Server.java:run(539)) - Stopping IPC
Server Responder
    [junit] 2009-05-30 15:45:01,705 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC
Server listener on 49839
    [junit] 2009-05-30 15:45:01,708 INFO  datanode.DataBlockScanner (DataBlockScanner.java:run(616))
- Exiting DataBlockScanner thread.
    [junit] 2009-05-30 15:45:01,708 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 49839
    [junit] Shutting down DataNode 0
    [junit] 2009-05-30 15:45:01,710 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 42137
    [junit] 2009-05-30 15:45:01,710 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
0 on 42137: exiting
    [junit] 2009-05-30 15:45:01,711 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
2 on 42137: exiting
    [junit] 2009-05-30 15:45:01,711 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
1 on 42137: exiting
    [junit] 2009-05-30 15:45:01,711 INFO  ipc.Server (Server.java:run(539)) - Stopping IPC
Server Responder
    [junit] 2009-05-30 15:45:01,711 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC
Server listener on 42137
    [junit] 2009-05-30 15:45:01,713 INFO  datanode.DataBlockScanner (DataBlockScanner.java:run(616))
- Exiting DataBlockScanner thread.
    [junit] 2009-05-30 15:45:01,714 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 42137
    [junit] 2009-05-30 15:45:01,826 WARN  namenode.DecommissionManager (DecommissionManager.java:run(67))
- Monitor interrupted: java.lang.InterruptedException: sleep interrupted
    [junit] 2009-05-30 15:45:01,838 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 52905
    [junit] 2009-05-30 15:45:01,838 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
0 on 52905: exiting
    [junit] 2009-05-30 15:45:01,838 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
7 on 52905: exiting
    [junit] 2009-05-30 15:45:01,839 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC
Server listener on 52905
    [junit] 2009-05-30 15:45:01,839 INFO  ipc.Server (Server.java:run(539)) - Stopping IPC
Server Responder
    [junit] 2009-05-30 15:45:01,838 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
3 on 52905: exiting
    [junit] 2009-05-30 15:45:01,838 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
1 on 52905: exiting
    [junit] 2009-05-30 15:45:01,838 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
2 on 52905: exiting
    [junit] 2009-05-30 15:45:01,839 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
9 on 52905: exiting
    [junit] 2009-05-30 15:45:01,839 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
6 on 52905: exiting
    [junit] 2009-05-30 15:45:01,839 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
5 on 52905: exiting
    [junit] 2009-05-30 15:45:01,840 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
8 on 52905: exiting
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 29.767 sec
    [junit] 2009-05-30 15:45:01,840 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
4 on 52905: exiting

checkfailure:

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1043: Tests failed!

Total time: 213 minutes 1 second
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Mime
View raw message