You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2009/06/14 16:59:02 UTC
Build failed in Hudson: Hadoop-trunk #866
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/866/
------------------------------------------
[...truncated 460892 lines...]
[junit] 2009-06-14 15:17:57,557 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-14 15:17:57,558 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 56913: starting
[junit] 2009-06-14 15:17:57,558 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 56913: starting
[junit] 2009-06-14 15:17:57,557 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 56913: starting
[junit] 2009-06-14 15:17:57,559 INFO net.NetworkTopology (NetworkTopology.java:add(327)) - Adding a new node: /default-rack/127.0.0.1:53624
[junit] 2009-06-14 15:17:57,559 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 56913: starting
[junit] Generating rack names for tasktrackers
[junit] Generating host names for tasktrackers
[junit] 2009-06-14 15:17:57,733 INFO mapred.JobTracker (JobTracker.java:<init>(1558)) - Starting jobtracker with owner as hudson and supergroup as supergroup
[junit] 2009-06-14 15:17:57,734 INFO mapred.JobTracker (JobTracker.java:initializeTaskMemoryRelatedConfig(3855)) - Scheduler configured with (memSizeForMapSlotOnJT, memSizeForReduceSlotOnJT, limitMaxMemForMapTasks, limitMaxMemForReduceTasks) (-1, -1, -1, -1)
[junit] 2009-06-14 15:17:57,734 INFO util.HostsFileReader (HostsFileReader.java:refresh(76)) - Refreshing hosts (include/exclude) list
[junit] 2009-06-14 15:17:57,785 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=JobTracker, port=39991
[junit] 2009-06-14 15:17:57,798 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-14 15:17:57,798 INFO http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort() returned 46734 webServer.getConnectors()[0].getLocalPort() returned 46734
[junit] 2009-06-14 15:17:57,799 INFO http.HttpServer (HttpServer.java:start(463)) - Jetty bound to port 46734
[junit] 2009-06-14 15:17:57,799 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2009-06-14 15:17:57,887 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:46734
[junit] 2009-06-14 15:17:57,889 INFO jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
[junit] 2009-06-14 15:17:57,889 INFO mapred.JobTracker (JobTracker.java:<init>(1671)) - JobTracker up at: 39991
[junit] 2009-06-14 15:17:57,890 INFO mapred.JobTracker (JobTracker.java:<init>(1675)) - JobTracker webserver: 46734
[junit] 2009-06-14 15:17:57,898 INFO mapred.JobTracker (JobTracker.java:<init>(1713)) - Cleaning up the system directory
[junit] 2009-06-14 15:17:58,122 INFO mapred.JobTracker (JobTracker.java:refreshHosts(3688)) - Refreshing hosts information
[junit] 2009-06-14 15:17:58,145 INFO util.HostsFileReader (HostsFileReader.java:setIncludesFile(100)) - Setting the includes file to
[junit] 2009-06-14 15:17:58,146 INFO util.HostsFileReader (HostsFileReader.java:setExcludesFile(105)) - Setting the excludes file to hosts.exclude
[junit] 2009-06-14 15:17:58,146 INFO util.HostsFileReader (HostsFileReader.java:refresh(76)) - Refreshing hosts (include/exclude) list
[junit] 2009-06-14 15:17:58,146 INFO mapred.JobTracker (JobTracker.java:decommissionNodes(3710)) - Decommissioning 0 nodes
[junit] 2009-06-14 15:17:58,147 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-14 15:17:58,147 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 39991: starting
[junit] 2009-06-14 15:17:58,147 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 39991: starting
[junit] 2009-06-14 15:17:58,147 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 3 on 39991: starting
[junit] 2009-06-14 15:17:58,147 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 39991: starting
[junit] 2009-06-14 15:17:58,147 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 39991: starting
[junit] 2009-06-14 15:17:58,148 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 5 on 39991: starting
[junit] 2009-06-14 15:17:58,148 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 6 on 39991: starting
[junit] 2009-06-14 15:17:58,148 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 4 on 39991: starting
[junit] 2009-06-14 15:17:58,149 INFO mapred.JobTracker (JobTracker.java:offerService(1894)) - Starting RUNNING
[junit] 2009-06-14 15:17:58,148 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 8 on 39991: starting
[junit] 2009-06-14 15:17:58,148 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 7 on 39991: starting
[junit] 2009-06-14 15:17:58,149 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 9 on 39991: starting
[junit] 2009-06-14 15:17:58,708 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-14 15:18:03,728 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-14 15:18:03,729 INFO http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort() returned 41096 webServer.getConnectors()[0].getLocalPort() returned 41096
[junit] 2009-06-14 15:18:03,729 INFO http.HttpServer (HttpServer.java:start(463)) - Jetty bound to port 41096
[junit] 2009-06-14 15:18:03,730 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2009-06-14 15:18:03,793 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:41096
[junit] 2009-06-14 15:18:03,798 INFO jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
[junit] 2009-06-14 15:18:03,801 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=TaskTracker, port=42919
[junit] 2009-06-14 15:18:03,802 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-14 15:18:03,802 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 42919: starting
[junit] 2009-06-14 15:18:03,803 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 3 on 42919: starting
[junit] 2009-06-14 15:18:03,803 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 42919: starting
[junit] 2009-06-14 15:18:03,802 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 42919: starting
[junit] 2009-06-14 15:18:03,802 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 42919: starting
[junit] 2009-06-14 15:18:03,839 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(51)) - setsid exited with exit code 0
[junit] 2009-06-14 15:18:03,842 INFO mapred.IndexCache (IndexCache.java:<init>(46)) - IndexCache created with max memory = 10485760
[junit] 2009-06-14 15:18:03,874 INFO net.NetworkTopology (NetworkTopology.java:add(327)) - Adding a new node: /default-rack/host0.foo.com
[junit] 2009-06-14 15:18:03,875 INFO mapred.JobTracker (JobTracker.java:addNewTracker(2426)) - Adding tracker tracker_host0.foo.com:localhost/127.0.0.1:42919 to host host0.foo.com
[junit] 2009-06-14 15:18:03,878 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-14 15:18:08,897 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-14 15:18:08,898 INFO http.HttpServer (HttpServer.java:start(430)) - listener.getLocalPort() returned 37573 webServer.getConnectors()[0].getLocalPort() returned 37573
[junit] 2009-06-14 15:18:08,898 INFO http.HttpServer (HttpServer.java:start(463)) - Jetty bound to port 37573
[junit] 2009-06-14 15:18:08,898 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2009-06-14 15:18:09,002 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:37573
[junit] 2009-06-14 15:18:09,004 INFO jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
[junit] 2009-06-14 15:18:09,005 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=TaskTracker, port=46495
[junit] 2009-06-14 15:18:09,006 INFO ipc.Server (Server.java:run(474)) - IPC Server Responder: starting
[junit] 2009-06-14 15:18:09,006 INFO ipc.Server (Server.java:run(313)) - IPC Server listener on 46495: starting
[junit] 2009-06-14 15:18:09,009 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 0 on 46495: starting
[junit] 2009-06-14 15:18:09,010 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 1 on 46495: starting
[junit] 2009-06-14 15:18:09,010 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 2 on 46495: starting
[junit] 2009-06-14 15:18:09,010 INFO ipc.Server (Server.java:run(939)) - IPC Server handler 3 on 46495: starting
[junit] 2009-06-14 15:18:09,015 INFO mapred.IndexCache (IndexCache.java:<init>(46)) - IndexCache created with max memory = 10485760
[junit] 2009-06-14 15:18:09,037 INFO net.NetworkTopology (NetworkTopology.java:add(327)) - Adding a new node: /default-rack/host1.foo.com
[junit] 2009-06-14 15:18:09,038 INFO mapred.JobTracker (JobTracker.java:addNewTracker(2426)) - Adding tracker tracker_host1.foo.com:localhost/127.0.0.1:46495 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-14 15:18 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-14 15:18 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-14 15:18 /test/testDistCh/f3
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub0
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub1/f4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub1/f5
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub2
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub2/f6
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub2/f7
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub3/f8
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub3/f9
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-06-14 15:18 /test/testDistCh/sub4/f10
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-06-14 15:18 /test/testDistCh/sub4/f11
[junit]
[junit] args=[/test/testDistCh/sub0:sub0::]
[junit] newstatus=[sub0:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x,
[junit] hudson:supergroup:rwxr-xr-x]
[junit] 2009-06-14 15:18:09,689 INFO tools.DistTool (DistCh.java:run(376)) - ops=[/test/testDistCh/sub0:sub0:null:null]
[junit] 2009-06-14 15:18:09,689 INFO tools.DistTool (DistCh.java:run(377)) - isIgnoreFailures=false
[junit] 2009-06-14 15:18:09,710 INFO tools.DistTool (DistCh.java:setup(427)) - distch.job.dir=hdfs://localhost:59371/user/hudson/build/test/mapred/system/distch_r9hs4g
[junit] 2009-06-14 15:18:09,713 INFO tools.DistTool (DistCh.java:setup(433)) - log=hdfs://localhost:59371/user/hudson/build/test/mapred/system/distch_r9hs4g/_logs
[junit] 2009-06-14 15:18:09,815 INFO tools.DistTool (DistCh.java:setup(476)) - distch.op.count=1
[junit] 2009-06-14 15:18:09,821 WARN mapred.JobClient (JobClient.java:configureCommandLineOptions(551)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2009-06-14 15:18:09,859 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-14 15:18:09,871 INFO tools.DistTool (DistCh.java:getSplits(261)) - numSplits=1, splits.size()=1
[junit] 2009-06-14 15:18:10,068 WARN conf.Configuration (Configuration.java:loadResource(1179)) - http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/mapred/local/jobTracker/job_200906141517_0001.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-06-14 15:18:10,077 INFO mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:run(80)) - Initializing job_200906141517_0001
[junit] 2009-06-14 15:18:10,078 INFO mapred.JobInProgress (JobInProgress.java:initTasks(395)) - Initializing job_200906141517_0001
[junit] 2009-06-14 15:18:10,079 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1287)) - Running job: job_200906141517_0001
[junit] 2009-06-14 15:18:10,221 INFO mapred.JobInProgress (JobInProgress.java:initTasks(439)) - Input size for job job_200906141517_0001 = 157. Number of splits = 1
[junit] 2009-06-14 15:18:11,083 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1300)) - map 0% reduce 0%
[junit] 2009-06-14 15:18:12,062 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1962)) - Adding task 'attempt_200906141517_0001_m_000002_0' to tip task_200906141517_0001_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:46495'
[junit] 2009-06-14 15:18:12,144 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_200906141517_0001/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2009-06-14 15:18:12,209 INFO mapred.JvmManager (JvmManager.java:<init>(383)) - In JvmRunner constructed JVM ID: jvm_200906141517_0001_m_-1225556648
[junit] 2009-06-14 15:18:12,210 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(351)) - JVM Runner jvm_200906141517_0001_m_-1225556648 spawned.
[junit] 2009-06-14 15:18:13,340 WARN util.ProcessTree (ProcessTree.java:terminateProcessGroup(136)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-14 15:18:13,340 INFO util.ProcessTree (ProcessTree.java:terminateProcessGroup(138)) - Killing all processes in the process group 18182 with SIGTERM. Exit code 1
[junit] 2009-06-14 15:18:15,072 INFO mapred.JobInProgress (JobInProgress.java:completedTask(2022)) - Task 'attempt_200906141517_0001_m_000002_0' has completed task_200906141517_0001_m_000002 successfully.
[junit] 2009-06-14 15:18:15,076 INFO mapred.JobInProgress (JobInProgress.java:findNewMapTask(1815)) - Choosing a non-local task task_200906141517_0001_m_000000
[junit] 2009-06-14 15:18:15,077 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1962)) - Adding task 'attempt_200906141517_0001_m_000000_0' to tip task_200906141517_0001_m_000000, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:46495'
[junit] 2009-06-14 15:18:15,082 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200906141517_0001_m_000002_0 done; removing files.
[junit] 2009-06-14 15:18:15,082 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200906141517_0001_m_000002_0 not found in cache
[junit] 2009-06-14 15:18:15,100 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1349)) - Task Id : attempt_200906141517_0001_m_000002_0, Status : SUCCEEDED
[junit] 2009-06-14 15:18:15,137 INFO mapred.JvmManager (JvmManager.java:<init>(383)) - In JvmRunner constructed JVM ID: jvm_200906141517_0001_m_-655478487
[junit] 2009-06-14 15:18:15,137 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(351)) - JVM Runner jvm_200906141517_0001_m_-655478487 spawned.
[junit] attempt_200906141517_0001_m_000002_0: 2009-06-14 15:18:12,940 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_200906141517_0001/attempt_200906141517_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906141517_0001_m_000002_0: 2009-06-14 15:18:12,957 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200906141517_0001_m_000002_0: 2009-06-14 15:18:13,054 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_200906141517_0001/attempt_200906141517_0001_m_000002_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906141517_0001_m_000002_0: 2009-06-14 15:18:13,112 INFO mapred.Task (Task.java:done(620)) - Task:attempt_200906141517_0001_m_000002_0 is done. And is in the process of commiting
[junit] attempt_200906141517_0001_m_000002_0: 2009-06-14 15:18:13,127 INFO mapred.Task (Task.java:sendDone(691)) - Task 'attempt_200906141517_0001_m_000002_0' done.
[junit] 2009-06-14 15:18:18,352 INFO mapred.JvmManager (JvmManager.java:runChild(411)) - JVM : jvm_200906141517_0001_m_-1225556648 exited. Number of tasks it ran: 1
[junit] 2009-06-14 15:18:18,454 WARN util.ProcessTree (ProcessTree.java:terminateProcessGroup(136)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-14 15:18:18,455 INFO util.ProcessTree (ProcessTree.java:terminateProcessGroup(138)) - Killing all processes in the process group 18231 with SIGTERM. Exit code 1
[junit] 2009-06-14 15:18:21,088 INFO mapred.JobInProgress (JobInProgress.java:completedTask(2022)) - Task 'attempt_200906141517_0001_m_000000_0' has completed task_200906141517_0001_m_000000 successfully.
[junit] 2009-06-14 15:18:21,091 INFO mapred.JobTracker (JobTracker.java:createTaskEntry(1962)) - Adding task 'attempt_200906141517_0001_m_000001_0' to tip task_200906141517_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:46495'
[junit] 2009-06-14 15:18:21,125 INFO mapred.JvmManager (JvmManager.java:<init>(383)) - In JvmRunner constructed JVM ID: jvm_200906141517_0001_m_-23686325
[junit] 2009-06-14 15:18:21,125 INFO mapred.JvmManager (JvmManager.java:spawnNewJvm(351)) - JVM Runner jvm_200906141517_0001_m_-23686325 spawned.
[junit] 2009-06-14 15:18:21,192 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1349)) - Task Id : attempt_200906141517_0001_m_000000_0, Status : SUCCEEDED
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:15,846 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_200906141517_0001/attempt_200906141517_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:15,863 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:15,960 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_200906141517_0001/attempt_200906141517_0001_m_000000_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:16,051 INFO mapred.MapTask (MapTask.java:runOldMapper(352)) - numReduceTasks: 0
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:16,166 INFO mapred.Task (Task.java:done(620)) - Task:attempt_200906141517_0001_m_000000_0 is done. And is in the process of commiting
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:18,175 INFO mapred.Task (Task.java:commit(720)) - Task attempt_200906141517_0001_m_000000_0 is allowed to commit now
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:18,252 INFO mapred.FileOutputCommitter (FileOutputCommitter.java:commitTask(92)) - Saved output of task 'attempt_200906141517_0001_m_000000_0' to hdfs://localhost:59371/user/hudson/build/test/mapred/system/distch_r9hs4g/_logs
[junit] attempt_200906141517_0001_m_000000_0: 2009-06-14 15:18:18,260 INFO mapred.Task (Task.java:sendDone(691)) - Task 'attempt_200906141517_0001_m_000000_0' done.
[junit] 2009-06-14 15:18:22,203 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1300)) - map 100% reduce 0%
[junit] 2009-06-14 15:18:22,307 WARN util.ProcessTree (ProcessTree.java:terminateProcessGroup(136)) - Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 2009-06-14 15:18:22,307 INFO util.ProcessTree (ProcessTree.java:terminateProcessGroup(138)) - Killing all processes in the process group 18286 with SIGTERM. Exit code 1
[junit] 2009-06-14 15:18:23,468 INFO mapred.JvmManager (JvmManager.java:runChild(411)) - JVM : jvm_200906141517_0001_m_-655478487 exited. Number of tasks it ran: 1
[junit] 2009-06-14 15:18:24,097 INFO mapred.JobInProgress (JobInProgress.java:completedTask(2022)) - Task 'attempt_200906141517_0001_m_000001_0' has completed task_200906141517_0001_m_000001 successfully.
[junit] 2009-06-14 15:18:24,099 INFO mapred.JobInProgress (JobInProgress.java:jobComplete(2146)) - Job job_200906141517_0001 has completed successfully.
[junit] 2009-06-14 15:18:24,286 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077)) - Removed completed task 'attempt_200906141517_0001_m_000000_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:46495'
[junit] 2009-06-14 15:18:24,286 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077)) - Removed completed task 'attempt_200906141517_0001_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:46495'
[junit] 2009-06-14 15:18:24,286 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(2077)) - Removed completed task 'attempt_200906141517_0001_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:46495'
[junit] 2009-06-14 15:18:24,287 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1349)) - Task Id : attempt_200906141517_0001_m_000001_0, Status : SUCCEEDED
[junit] 2009-06-14 15:18:24,287 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200906141517_0001_m_000000_0 done; removing files.
[junit] 2009-06-14 15:18:24,289 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200906141517_0001_m_000000_0 not found in cache
[junit] 2009-06-14 15:18:24,289 INFO mapred.TaskRunner (MapTaskRunner.java:close(43)) - attempt_200906141517_0001_m_000001_0 done; removing files.
[junit] attempt_200906141517_0001_m_000001_0: 2009-06-14 15:18:21,872 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_200906141517_0001/attempt_200906141517_0001_m_000001_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906141517_0001_m_000001_0: 2009-06-14 15:18:21,889 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Initializing JVM Metrics with processName=MAP, sessionId=
[junit] attempt_200906141517_0001_m_000001_0: 2009-06-14 15:18:21,989 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_200906141517_0001/attempt_200906141517_0001_m_000001_0/job.xml :a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] attempt_200906141517_0001_m_000001_0: 2009-06-14 15:18:22,086 INFO mapred.Task (Task.java:done(620)) - Task:attempt_200906141517_0001_m_000001_0 is done. And is in the process of commiting
[junit] attempt_200906141517_0001_m_000001_0: 2009-06-14 15:18:22,102 INFO mapred.Task (Task.java:sendDone(691)) - Task 'attempt_200906141517_0001_m_000001_0' done.
[junit] 2009-06-14 15:18:24,290 INFO mapred.IndexCache (IndexCache.java:removeMap(140)) - Map ID attempt_200906141517_0001_m_000001_0 not found in cache
[junit] 2009-06-14 15:18:24,292 INFO mapred.JobClient (JobClient.java:monitorAndPrintJob(1355)) - Job complete: job_200906141517_0001
[junit] 2009-06-14 15:18:24,295 INFO mapred.JobClient (Counters.java:log(534)) - Counters: 7
[junit] 2009-06-14 15:18:24,295 INFO mapred.JobClient (Counters.java:log(536)) - Job Counters
[junit] 2009-06-14 15:18:24,296 INFO mapred.JobClient (Counters.java:log(538)) - Launched map tasks=1
[junit] 2009-06-14 15:18:24,296 INFO mapred.JobClient (Counters.java:log(536)) - FileInputFormatCounters
[junit] 2009-06-14 15:18:24,296 INFO mapred.JobClient (Counters.java:log(538)) - BYTES_READ=60
[junit] 2009-06-14 15:18:24,297 INFO mapred.JobClient (Counters.java:log(536)) - org.apache.hadoop.tools.DistCh$Counter
[junit] 2009-06-14 15:18:24,297 INFO mapred.JobClient (Counters.java:log(538)) - SUCCEED=1
[junit] 2009-06-14 15:18:24,297 INFO mapred.JobClient (Counters.java:log(536)) - FileSystemCounters
[junit] 2009-06-14 15:18:24,298 INFO mapred.JobClient (Counters.java:log(538)) - HDFS_BYTES_READ=157
[junit] 2009-06-14 15:18:24,298 INFO mapred.JobClient (Counters.java:log(536)) - Map-Reduce Framework
[junit] 2009-06-14 15:18:24,298 INFO mapred.JobClient (Counters.java:log(538)) - Map input records=1
[junit] 2009-06-14 15:18:24,299 INFO mapred.JobClient (Counters.java:log(538)) - Spilled Records=0
[junit] 2009-06-14 15:18:24,299 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-14 15:18 /test/testDistCh/f1
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-14 15:18 /test/testDistCh/f2
[junit] -rw-r--r-- 2 hudson supergroup 38 2009-06-14 15:18 /test/testDistCh/f3
[junit] drwxr-xr-x - sub0 supergroup 0 2009-06-14 15:18 /test/testDistCh/sub0
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub1
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub1/f4
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub1/f5
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub2
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub2/f6
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub2/f7
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub3
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub3/f8
[junit] -rw-r--r-- 2 hudson supergroup 43 2009-06-14 15:18 /test/testDistCh/sub3/f9
[junit] drwxr-xr-x - hudson supergroup 0 2009-06-14 15:18 /test/testDistCh/sub4
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-06-14 15:18 /test/testDistCh/sub4/f10
[junit] -rw-r--r-- 2 hudson supergroup 44 2009-06-14 15:18 /test/testDistCh/sub4/f11
[junit]
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-14 15:18:24,524 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 56913
[junit] 2009-06-14 15:18:24,524 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 1 on 56913: exiting
[junit] 2009-06-14 15:18:24,524 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 2 on 56913: exiting
[junit] 2009-06-14 15:18:24,524 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 0 on 56913: exiting
[junit] 2009-06-14 15:18:24,526 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 56913
[junit] 2009-06-14 15:18:24,526 INFO ipc.Server (Server.java:run(539)) - Stopping IPC Server Responder
[junit] 2009-06-14 15:18:24,529 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-14 15:18:24,529 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 56913
[junit] Shutting down DataNode 0
[junit] 2009-06-14 15:18:24,631 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 40832
[junit] 2009-06-14 15:18:24,631 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 0 on 40832: exiting
[junit] 2009-06-14 15:18:24,632 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 2 on 40832: exiting
[junit] 2009-06-14 15:18:24,631 INFO ipc.Server (Server.java:run(539)) - Stopping IPC Server Responder
[junit] 2009-06-14 15:18:24,631 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 40832
[junit] 2009-06-14 15:18:24,631 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 1 on 40832: exiting
[junit] 2009-06-14 15:18:24,633 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-14 15:18:24,634 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 40832
[junit] 2009-06-14 15:18:24,744 WARN namenode.DecommissionManager (DecommissionManager.java:run(67)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2009-06-14 15:18:24,761 INFO ipc.Server (Server.java:stop(1103)) - Stopping server on 59371
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 2 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 4 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 1 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 59371
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(539)) - Stopping IPC Server Responder
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 8 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 5 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 3 on 59371: exiting
[junit] 2009-06-14 15:18:24,763 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 0 on 59371: exiting
[junit] 2009-06-14 15:18:24,763 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 7 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 6 on 59371: exiting
[junit] 2009-06-14 15:18:24,762 INFO ipc.Server (Server.java:run(997)) - IPC Server handler 9 on 59371: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 29.982 sec
checkfailure:
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1043: Tests failed!
Total time: 228 minutes 58 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...
Hudson build is back to normal: Hadoop-trunk #867
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/867/changes