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
selectchannelconnec...@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
selectchannelconnec...@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
selectchannelconnec...@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...
