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 #857
Date Fri, 05 Jun 2009 17:42:22 GMT
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/857/changes

Changes:

[gkesavan] HADOOP-5457. Fix to continue to run builds even if contrib test fails.

[yhemanth] HADOOP-5884. Fixes accounting in capacity scheduler so that high RAM jobs take
more slots. Contributed by Vinod Kumar Vavilapalli.

[szetszwo] HADOOP-5859. Fix "wait() or sleep() with locks held" findbugs warnings in DFSClient.
 Contributed by Kan Zhang

[sharad] HADOOP-2838. Add mapred.child.env to pass environment variables to tasktracker's
child processes. Contributed by Amar Kamat.

------------------------------------------
[...truncated 436549 lines...]
    [junit] 2009-06-05 18:00:32,208 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 49203: starting
    [junit] 2009-06-05 18:00:32,208 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 49203: starting
    [junit] 2009-06-05 18:00:32,208 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 49203: starting
    [junit] 2009-06-05 18:00:32,210 INFO  net.NetworkTopology (NetworkTopology.java:add(327))
- Adding a new node: /default-rack/127.0.0.1:38974
    [junit] Generating rack names for tasktrackers
    [junit] Generating host names for tasktrackers
    [junit] 2009-06-05 18:00:32,385 INFO  mapred.JobTracker (JobTracker.java:<init>(1558))
- Starting jobtracker with owner as hudson and supergroup as supergroup
    [junit] 2009-06-05 18:00:32,386 INFO  mapred.JobTracker (JobTracker.java:initializeTaskMemoryRelatedConfig(3855))
- Scheduler configured with (memSizeForMapSlotOnJT, memSizeForReduceSlotOnJT, limitMaxMemForMapTasks,
limitMaxMemForReduceTasks) (-1, -1, -1, -1)
    [junit] 2009-06-05 18:00:32,386 INFO  util.HostsFileReader (HostsFileReader.java:refresh(76))
- Refreshing hosts (include/exclude) list
    [junit] 2009-06-05 18:00:32,437 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58))
- Initializing RPC Metrics with hostName=JobTracker, port=48057
    [junit] 2009-06-05 18:00:32,450 INFO  http.HttpServer (HttpServer.java:start(425)) - Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener
on 0
    [junit] 2009-06-05 18:00:32,450 INFO  http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort()
returned 41222 webServer.getConnectors()[0].getLocalPort() returned 41222
    [junit] 2009-06-05 18:00:32,450 INFO  http.HttpServer (HttpServer.java:start(463)) - Jetty
bound to port 41222
    [junit] 2009-06-05 18:00:32,450 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-06-05 18:00:32,538 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:41222
    [junit] 2009-06-05 18:00:32,540 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot
initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
    [junit] 2009-06-05 18:00:32,540 INFO  mapred.JobTracker (JobTracker.java:<init>(1671))
- JobTracker up at: 48057
    [junit] 2009-06-05 18:00:32,541 INFO  mapred.JobTracker (JobTracker.java:<init>(1675))
- JobTracker webserver: 41222
    [junit] 2009-06-05 18:00:32,549 INFO  mapred.JobTracker (JobTracker.java:<init>(1713))
- Cleaning up the system directory
    [junit] 2009-06-05 18:00:32,766 INFO  mapred.JobTracker (JobTracker.java:refreshHosts(3688))
- Refreshing hosts information
    [junit] 2009-06-05 18:00:32,790 INFO  util.HostsFileReader (HostsFileReader.java:setIncludesFile(100))
- Setting the includes file to 
    [junit] 2009-06-05 18:00:32,790 INFO  util.HostsFileReader (HostsFileReader.java:setExcludesFile(105))
- Setting the excludes file to hosts.exclude
    [junit] 2009-06-05 18:00:32,790 INFO  util.HostsFileReader (HostsFileReader.java:refresh(76))
- Refreshing hosts (include/exclude) list
    [junit] 2009-06-05 18:00:32,790 INFO  mapred.JobTracker (JobTracker.java:decommissionNodes(3710))
- Decommissioning 0 nodes
    [junit] 2009-06-05 18:00:32,791 INFO  ipc.Server (Server.java:run(474)) - IPC Server Responder:
starting
    [junit] 2009-06-05 18:00:32,791 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 48057: starting
    [junit] 2009-06-05 18:00:32,791 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 48057: starting
    [junit] 2009-06-05 18:00:32,792 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 48057: starting
    [junit] 2009-06-05 18:00:32,791 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 48057: starting
    [junit] 2009-06-05 18:00:32,791 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 48057: starting
    [junit] 2009-06-05 18:00:32,792 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
5 on 48057: starting
    [junit] 2009-06-05 18:00:32,792 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
4 on 48057: starting
    [junit] 2009-06-05 18:00:32,793 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
7 on 48057: starting
    [junit] 2009-06-05 18:00:32,793 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
8 on 48057: starting
    [junit] 2009-06-05 18:00:32,792 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
6 on 48057: starting
    [junit] 2009-06-05 18:00:32,793 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
9 on 48057: starting
    [junit] 2009-06-05 18:00:32,793 INFO  mapred.JobTracker (JobTracker.java:offerService(1894))
- Starting RUNNING
    [junit] 2009-06-05 18:00:33,347 INFO  mapred.MiniMRCluster (MiniMRCluster.java:<init>(176))
- mapred.local.dir is http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/0_0

    [junit] 2009-06-05 18:00:38,369 INFO  http.HttpServer (HttpServer.java:start(425)) - Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener
on 0
    [junit] 2009-06-05 18:00:38,369 INFO  http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort()
returned 60169 webServer.getConnectors()[0].getLocalPort() returned 60169
    [junit] 2009-06-05 18:00:38,370 INFO  http.HttpServer (HttpServer.java:start(463)) - Jetty
bound to port 60169
    [junit] 2009-06-05 18:00:38,370 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-06-05 18:00:38,479 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:60169
    [junit] 2009-06-05 18:00:38,482 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot
initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
    [junit] 2009-06-05 18:00:38,485 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58))
- Initializing RPC Metrics with hostName=TaskTracker, port=42117
    [junit] 2009-06-05 18:00:38,485 INFO  ipc.Server (Server.java:run(474)) - IPC Server Responder:
starting
    [junit] 2009-06-05 18:00:38,486 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 42117: starting
    [junit] 2009-06-05 18:00:38,490 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 42117: starting
    [junit] 2009-06-05 18:00:38,486 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 42117: starting
    [junit] 2009-06-05 18:00:38,486 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 42117: starting
    [junit] 2009-06-05 18:00:38,486 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 42117: starting
    [junit] 2009-06-05 18:00:38,525 INFO  util.ProcessTree (ProcessTree.java:isSetsidSupported(51))
- setsid exited with exit code 0
    [junit] 2009-06-05 18:00:38,528 INFO  mapred.IndexCache (IndexCache.java:<init>(46))
- IndexCache created with max memory = 10485760
    [junit] 2009-06-05 18:00:38,564 INFO  net.NetworkTopology (NetworkTopology.java:add(327))
- Adding a new node: /default-rack/host0.foo.com
    [junit] 2009-06-05 18:00:38,565 INFO  mapred.JobTracker (JobTracker.java:addNewTracker(2426))
- Adding tracker tracker_host0.foo.com:localhost/127.0.0.1:42117 to host host0.foo.com
    [junit] 2009-06-05 18:00:38,567 INFO  mapred.MiniMRCluster (MiniMRCluster.java:<init>(176))
- mapred.local.dir is http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/1_0

    [junit] 2009-06-05 18:00:43,587 INFO  http.HttpServer (HttpServer.java:start(425)) - Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener
on 0
    [junit] 2009-06-05 18:00:43,588 INFO  http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort()
returned 58456 webServer.getConnectors()[0].getLocalPort() returned 58456
    [junit] 2009-06-05 18:00:43,588 INFO  http.HttpServer (HttpServer.java:start(463)) - Jetty
bound to port 58456
    [junit] 2009-06-05 18:00:43,588 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-06-05 18:00:43,650 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:58456
    [junit] 2009-06-05 18:00:43,654 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot
initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
    [junit] 2009-06-05 18:00:43,656 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58))
- Initializing RPC Metrics with hostName=TaskTracker, port=39536
    [junit] 2009-06-05 18:00:43,656 INFO  ipc.Server (Server.java:run(474)) - IPC Server Responder:
starting
    [junit] 2009-06-05 18:00:43,657 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 39536: starting
    [junit] 2009-06-05 18:00:43,659 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 39536: starting
    [junit] 2009-06-05 18:00:43,659 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 39536: starting
    [junit] 2009-06-05 18:00:43,656 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener
on 39536: starting
    [junit] 2009-06-05 18:00:43,659 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 39536: starting
    [junit] 2009-06-05 18:00:43,666 INFO  mapred.IndexCache (IndexCache.java:<init>(46))
- IndexCache created with max memory = 10485760
    [junit] 2009-06-05 18:00:43,729 INFO  net.NetworkTopology (NetworkTopology.java:add(327))
- Adding a new node: /default-rack/host1.foo.com
    [junit] 2009-06-05 18:00:43,729 INFO  mapred.JobTracker (JobTracker.java:addNewTracker(2426))
- Adding tracker tracker_host1.foo.com:localhost/127.0.0.1:39536 to host host1.foo.com
    [junit] rootdir = /test/testDistCh
    [junit] root=/test/testDistCh, returnvalue=0
    [junit] results:
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-06-05 18:00 /test/testDistCh/f1
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-06-05 18:00 /test/testDistCh/f2
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-06-05 18:00 /test/testDistCh/f3
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub0
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub1
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub1/f4
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub1/f5
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub2
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub3
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub3/f6
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub4
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub4/f7
    [junit] 
    [junit] args=[/test/testDistCh/sub4:sub4:sub4:,
    [junit]    /test/testDistCh/sub2::sub2:,
    [junit]    /test/testDistCh/sub0::sub0:074]
    [junit] newstatus=[hudson:sub0:---rwxr--,
    [junit]    hudson:supergroup:rwxr-xr-x,
    [junit]    hudson:sub2:rwxr-xr-x,
    [junit]    hudson:supergroup:rwxr-xr-x,
    [junit]    sub4:sub4:rwxr-xr-x]
    [junit] 2009-06-05 18:00:44,287 INFO  tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub4:sub4:sub4:null,
/test/testDistCh/sub2:null:sub2:null, /test/testDistCh/sub0:null:sub0:---rwxr--]
    [junit] 2009-06-05 18:00:44,288 INFO  tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
    [junit] 2009-06-05 18:00:44,305 INFO  tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:42333/user/hudson/build/test/mapred/system/distch_bzp1to
    [junit] 2009-06-05 18:00:44,320 INFO  tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:42333/user/hudson/build/test/mapred/system/distch_bzp1to/_logs
    [junit] 2009-06-05 18:00:44,572 INFO  tools.DistTool (DistCh.java:setup(476)) - distch.op.count=4
    [junit] 2009-06-05 18:00:44,578 WARN  mapred.JobClient (JobClient.java:configureCommandLineOptions(551))
- Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for
the same.
    [junit] 2009-06-05 18:00:44,607 WARN  mapred.JobClient (JobClient.java:configureCommandLineOptions(673))
- No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2009-06-05 18:00:44,620 INFO  tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1,
splits.size()=1
    [junit] 2009-06-05 18:00:44,965 WARN  conf.Configuration (Configuration.java:loadResource(1179))
- http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/jobTracker/job_200906051800_0001.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2009-06-05 18:00:44,974 INFO  mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:run(80))
- Initializing job_200906051800_0001
    [junit] 2009-06-05 18:00:44,975 INFO  mapred.JobInProgress (JobInProgress.java:initTasks(395))
- Initializing job_200906051800_0001
    [junit] 2009-06-05 18:00:44,975 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1287))
- Running job: job_200906051800_0001
    [junit] 2009-06-05 18:00:45,123 INFO  mapred.JobInProgress (JobInProgress.java:initTasks(439))
- Input size for job job_200906051800_0001 = 399. Number of splits = 1
    [junit] 2009-06-05 18:00:45,980 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1300))
-  map 0% reduce 0%
    [junit] 2009-06-05 18:00:46,755 INFO  mapred.JobTracker (JobTracker.java:createTaskEntry(1962))
- Adding task 'attempt_200906051800_0001_m_000002_0' to tip task_200906051800_0001_m_000002,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:39536'
    [junit] 2009-06-05 18:00:46,835 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_200906051800_0001/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2009-06-05 18:00:46,900 INFO  mapred.JvmManager (JvmManager.java:<init>(383))
- In JvmRunner constructed JVM ID: jvm_200906051800_0001_m_888394226
    [junit] 2009-06-05 18:00:46,900 INFO  mapred.JvmManager (JvmManager.java:spawnNewJvm(351))
- JVM Runner jvm_200906051800_0001_m_888394226 spawned.
    [junit] 2009-06-05 18:00:48,012 WARN  util.ProcessTree (ProcessTree.java:destroyProcessGroup(166))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-06-05 18:00:48,012 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(168))
- Killing all processes in the process group 20504 with SIGTERM. Exit code 1
    [junit] 2009-06-05 18:00:49,764 INFO  mapred.JobInProgress (JobInProgress.java:completedTask(2022))
- Task 'attempt_200906051800_0001_m_000002_0' has completed task_200906051800_0001_m_000002
successfully.
    [junit] 2009-06-05 18:00:49,769 INFO  mapred.JobInProgress (JobInProgress.java:findNewMapTask(1815))
- Choosing a non-local task task_200906051800_0001_m_000000
    [junit] 2009-06-05 18:00:49,771 INFO  mapred.JobTracker (JobTracker.java:createTaskEntry(1962))
- Adding task 'attempt_200906051800_0001_m_000000_0' to tip task_200906051800_0001_m_000000,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:39536'
    [junit] 2009-06-05 18:00:49,776 INFO  mapred.TaskRunner (MapTaskRunner.java:close(43))
- attempt_200906051800_0001_m_000002_0 done; removing files.
    [junit] 2009-06-05 18:00:49,777 INFO  mapred.IndexCache (IndexCache.java:removeMap(140))
- Map ID attempt_200906051800_0001_m_000002_0 not found in cache
    [junit] 2009-06-05 18:00:49,831 INFO  mapred.JvmManager (JvmManager.java:<init>(383))
- In JvmRunner constructed JVM ID: jvm_200906051800_0001_m_-1644609087
    [junit] 2009-06-05 18:00:49,831 INFO  mapred.JvmManager (JvmManager.java:spawnNewJvm(351))
- JVM Runner jvm_200906051800_0001_m_-1644609087 spawned.
    [junit] 2009-06-05 18:00:49,994 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1349))
- Task Id : attempt_200906051800_0001_m_000002_0, Status : SUCCEEDED
    [junit] attempt_200906051800_0001_m_000002_0: 2009-06-05 18:00:47,663 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_200906051800_0001/attempt_200906051800_0001_m_000002_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200906051800_0001_m_000002_0: 2009-06-05 18:00:47,680 INFO  jvm.JvmMetrics
(JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
    [junit] attempt_200906051800_0001_m_000002_0: 2009-06-05 18:00:47,779 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_200906051800_0001/attempt_200906051800_0001_m_000002_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200906051800_0001_m_000002_0: 2009-06-05 18:00:47,839 INFO  mapred.Task
(Task.java:done(620)) - Task:attempt_200906051800_0001_m_000002_0 is done. And is in the process
of commiting
    [junit] attempt_200906051800_0001_m_000002_0: 2009-06-05 18:00:47,845 INFO  mapred.Task
(Task.java:sendDone(691)) - Task 'attempt_200906051800_0001_m_000002_0' done.
    [junit] 2009-06-05 18:00:53,032 WARN  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(91))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-06-05 18:00:53,033 INFO  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(94))
- Killing process group20504 with SIGKILL. Exit code 1
    [junit] 2009-06-05 18:00:53,033 INFO  mapred.DefaultTaskController (DefaultTaskController.java:killTaskJVM(87))
- Process exited with exit code:0
    [junit] 2009-06-05 18:00:53,033 INFO  mapred.JvmManager (JvmManager.java:runChild(411))
- JVM : jvm_200906051800_0001_m_888394226 exited. Number of tasks it ran: 1
    [junit] 2009-06-05 18:00:53,189 WARN  util.ProcessTree (ProcessTree.java:destroyProcessGroup(166))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-06-05 18:00:53,190 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(168))
- Killing all processes in the process group 20553 with SIGTERM. Exit code 1
    [junit] 2009-06-05 18:00:55,782 INFO  mapred.JobInProgress (JobInProgress.java:completedTask(2022))
- Task 'attempt_200906051800_0001_m_000000_0' has completed task_200906051800_0001_m_000000
successfully.
    [junit] 2009-06-05 18:00:55,786 INFO  mapred.JobTracker (JobTracker.java:createTaskEntry(1962))
- Adding task 'attempt_200906051800_0001_m_000001_0' to tip task_200906051800_0001_m_000001,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:39536'
    [junit] 2009-06-05 18:00:55,819 INFO  mapred.JvmManager (JvmManager.java:<init>(383))
- In JvmRunner constructed JVM ID: jvm_200906051800_0001_m_-1036348605
    [junit] 2009-06-05 18:00:55,819 INFO  mapred.JvmManager (JvmManager.java:spawnNewJvm(351))
- JVM Runner jvm_200906051800_0001_m_-1036348605 spawned.
    [junit] 2009-06-05 18:00:56,068 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1349))
- Task Id : attempt_200906051800_0001_m_000000_0, Status : SUCCEEDED
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:50,561 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_200906051800_0001/attempt_200906051800_0001_m_000000_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:50,578 INFO  jvm.JvmMetrics
(JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:50,674 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_200906051800_0001/attempt_200906051800_0001_m_000000_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:50,767 INFO  mapred.MapTask
(MapTask.java:runOldMapper(352)) - numReduceTasks: 0
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:50,913 INFO  mapred.Task
(Task.java:done(620)) - Task:attempt_200906051800_0001_m_000000_0 is done. And is in the process
of commiting
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:52,921 INFO  mapred.Task
(Task.java:commit(720)) - Task attempt_200906051800_0001_m_000000_0 is allowed to commit now
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:52,960 INFO  mapred.FileOutputCommitter
(FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200906051800_0001_m_000000_0'
to hdfs://localhost:42333/user/hudson/build/test/mapred/system/distch_bzp1to/_logs
    [junit] attempt_200906051800_0001_m_000000_0: 2009-06-05 18:00:52,967 INFO  mapred.Task
(Task.java:sendDone(691)) - Task 'attempt_200906051800_0001_m_000000_0' done.
    [junit] 2009-06-05 18:00:56,894 WARN  util.ProcessTree (ProcessTree.java:destroyProcessGroup(166))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-06-05 18:00:56,895 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(168))
- Killing all processes in the process group 20608 with SIGTERM. Exit code 1
    [junit] 2009-06-05 18:00:57,078 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1300))
-  map 100% reduce 0%
    [junit] 2009-06-05 18:00:58,205 WARN  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(91))
- Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such
process
    [junit] 
    [junit] 2009-06-05 18:00:58,205 INFO  util.ProcessTree (ProcessTree.java:sigKillInCurrentThread(94))
- Killing process group20553 with SIGKILL. Exit code 1
    [junit] 2009-06-05 18:00:58,205 INFO  mapred.DefaultTaskController (DefaultTaskController.java:killTaskJVM(87))
- Process exited with exit code:0
    [junit] 2009-06-05 18:00:58,206 INFO  mapred.JvmManager (JvmManager.java:runChild(411))
- JVM : jvm_200906051800_0001_m_-1644609087 exited. Number of tasks it ran: 1
    [junit] 2009-06-05 18:00:58,789 INFO  mapred.JobInProgress (JobInProgress.java:completedTask(2022))
- Task 'attempt_200906051800_0001_m_000001_0' has completed task_200906051800_0001_m_000001
successfully.
    [junit] 2009-06-05 18:00:58,790 INFO  mapred.JobInProgress (JobInProgress.java:jobComplete(2146))
- Job job_200906051800_0001 has completed successfully.
    [junit] 2009-06-05 18:00:58,865 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077))
- Removed completed task 'attempt_200906051800_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:39536'
    [junit] 2009-06-05 18:00:58,865 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077))
- Removed completed task 'attempt_200906051800_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:39536'
    [junit] 2009-06-05 18:00:58,866 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077))
- Removed completed task 'attempt_200906051800_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:39536'
    [junit] 2009-06-05 18:00:58,867 INFO  mapred.TaskRunner (MapTaskRunner.java:close(43))
- attempt_200906051800_0001_m_000001_0 done; removing files.
    [junit] 2009-06-05 18:00:58,868 INFO  mapred.IndexCache (IndexCache.java:removeMap(140))
- Map ID attempt_200906051800_0001_m_000001_0 not found in cache
    [junit] 2009-06-05 18:00:58,868 INFO  mapred.TaskRunner (MapTaskRunner.java:close(43))
- attempt_200906051800_0001_m_000000_0 done; removing files.
    [junit] 2009-06-05 18:00:58,869 INFO  mapred.IndexCache (IndexCache.java:removeMap(140))
- Map ID attempt_200906051800_0001_m_000000_0 not found in cache
    [junit] 2009-06-05 18:00:59,083 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1349))
- Task Id : attempt_200906051800_0001_m_000001_0, Status : SUCCEEDED
    [junit] attempt_200906051800_0001_m_000001_0: 2009-06-05 18:00:56,527 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_200906051800_0001/attempt_200906051800_0001_m_000001_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200906051800_0001_m_000001_0: 2009-06-05 18:00:56,544 INFO  jvm.JvmMetrics
(JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
    [junit] attempt_200906051800_0001_m_000001_0: 2009-06-05 18:00:56,640 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_200906051800_0001/attempt_200906051800_0001_m_000001_0/job.xml
:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] attempt_200906051800_0001_m_000001_0: 2009-06-05 18:00:56,702 INFO  mapred.Task
(Task.java:done(620)) - Task:attempt_200906051800_0001_m_000001_0 is done. And is in the process
of commiting
    [junit] attempt_200906051800_0001_m_000001_0: 2009-06-05 18:00:56,707 INFO  mapred.Task
(Task.java:sendDone(691)) - Task 'attempt_200906051800_0001_m_000001_0' done.
    [junit] 2009-06-05 18:00:59,087 INFO  mapred.JobClient (JobClient.java:monitorAndPrintJob(1355))
- Job complete: job_200906051800_0001
    [junit] 2009-06-05 18:00:59,090 INFO  mapred.JobClient (Counters.java:log(534)) - Counters:
7
    [junit] 2009-06-05 18:00:59,090 INFO  mapred.JobClient (Counters.java:log(536)) -   Job
Counters 
    [junit] 2009-06-05 18:00:59,091 INFO  mapred.JobClient (Counters.java:log(538)) -    
Launched map tasks=1
    [junit] 2009-06-05 18:00:59,091 INFO  mapred.JobClient (Counters.java:log(536)) -   FileInputFormatCounters
    [junit] 2009-06-05 18:00:59,091 INFO  mapred.JobClient (Counters.java:log(538)) -    
BYTES_READ=302
    [junit] 2009-06-05 18:00:59,092 INFO  mapred.JobClient (Counters.java:log(536)) -   org.apache.hadoop.tools.DistCh$Counter
    [junit] 2009-06-05 18:00:59,092 INFO  mapred.JobClient (Counters.java:log(538)) -    
SUCCEED=4
    [junit] 2009-06-05 18:00:59,092 INFO  mapred.JobClient (Counters.java:log(536)) -   FileSystemCounters
    [junit] 2009-06-05 18:00:59,093 INFO  mapred.JobClient (Counters.java:log(538)) -    
HDFS_BYTES_READ=399
    [junit] 2009-06-05 18:00:59,093 INFO  mapred.JobClient (Counters.java:log(536)) -   Map-Reduce
Framework
    [junit] 2009-06-05 18:00:59,093 INFO  mapred.JobClient (Counters.java:log(538)) -    
Map input records=4
    [junit] 2009-06-05 18:00:59,094 INFO  mapred.JobClient (Counters.java:log(538)) -    
Spilled Records=0
    [junit] 2009-06-05 18:00:59,094 INFO  mapred.JobClient (Counters.java:log(538)) -    
Map output records=0
    [junit] root=/test/testDistCh, returnvalue=0
    [junit] results:
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-06-05 18:00 /test/testDistCh/f1
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-06-05 18:00 /test/testDistCh/f2
    [junit] -rw-r--r--   2 hudson supergroup         38 2009-06-05 18:00 /test/testDistCh/f3
    [junit] d---rwxr--   - hudson sub0                0 2009-06-05 18:00 /test/testDistCh/sub0
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub1
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub1/f4
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub1/f5
    [junit] drwxr-xr-x   - hudson sub2                0 2009-06-05 18:00 /test/testDistCh/sub2
    [junit] drwxr-xr-x   - hudson supergroup          0 2009-06-05 18:00 /test/testDistCh/sub3
    [junit] -rw-r--r--   2 hudson supergroup         43 2009-06-05 18:00 /test/testDistCh/sub3/f6
    [junit] drwxr-xr-x   - sub4   sub4                0 2009-06-05 18:00 /test/testDistCh/sub4
    [junit] -rw-r--r--   2 sub4 sub4         43 2009-06-05 18:00 /test/testDistCh/sub4/f7
    [junit] 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-05 18:00:59,232 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 49203
    [junit] 2009-06-05 18:00:59,233 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
1 on 49203: exiting
    [junit] 2009-06-05 18:00:59,233 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
2 on 49203: exiting
    [junit] 2009-06-05 18:00:59,233 INFO  ipc.Server (Server.java:run(539)) - Stopping IPC
Server Responder
    [junit] 2009-06-05 18:00:59,233 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
0 on 49203: exiting
    [junit] 2009-06-05 18:00:59,233 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC
Server listener on 49203
    [junit] 2009-06-05 18:00:59,235 INFO  datanode.DataBlockScanner (DataBlockScanner.java:run(616))
- Exiting DataBlockScanner thread.
    [junit] 2009-06-05 18:00:59,236 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 49203
    [junit] Shutting down DataNode 0
    [junit] 2009-06-05 18:00:59,337 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 45669
    [junit] 2009-06-05 18:00:59,338 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
0 on 45669: exiting
    [junit] 2009-06-05 18:00:59,338 INFO  ipc.Server (Server.java:run(539)) - Stopping IPC
Server Responder
    [junit] 2009-06-05 18:00:59,338 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC
Server listener on 45669
    [junit] 2009-06-05 18:00:59,338 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
2 on 45669: exiting
    [junit] 2009-06-05 18:00:59,338 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
1 on 45669: exiting
    [junit] 2009-06-05 18:00:59,340 INFO  datanode.DataBlockScanner (DataBlockScanner.java:run(616))
- Exiting DataBlockScanner thread.
    [junit] 2009-06-05 18:00:59,340 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 45669
    [junit] 2009-06-05 18:00:59,442 WARN  namenode.DecommissionManager (DecommissionManager.java:run(67))
- Monitor interrupted: java.lang.InterruptedException: sleep interrupted
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:stop(1103)) - Stopping server
on 42333
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
0 on 42333: exiting
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
2 on 42333: exiting
    [junit] 2009-06-05 18:00:59,456 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
8 on 42333: exiting
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
4 on 42333: exiting
    [junit] 2009-06-05 18:00:59,456 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
9 on 42333: exiting
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
3 on 42333: exiting
    [junit] 2009-06-05 18:00:59,456 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
5 on 42333: exiting
    [junit] 2009-06-05 18:00:59,456 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
1 on 42333: exiting
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
6 on 42333: exiting
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(997)) - IPC Server handler
7 on 42333: exiting
    [junit] 2009-06-05 18:00:59,455 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC
Server listener on 42333
    [junit] 2009-06-05 18:00:59,458 INFO  ipc.Server (Server.java:run(539)) - Stopping IPC
Server Responder
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 29.939 sec

checkfailure:

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

Total time: 230 minutes 23 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Mime
View raw message