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 #791
Date Thu, 26 Mar 2009 17:43:32 GMT
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/791/changes

Changes:

[cdouglas] HADOOP-4365. Make Configuration::getProps protected in support of
meaningful subclassing. Contributed by Steve Loughran

[ddas] HADOOP-5194. Disables setsid for tasks run on cygwin. Contributed by Ravi Gummadi.

[cdouglas] HADOOP-5477. Fix rare failure in TestCLI for hosts returning variations of
'localhost'. Contributed by Jacob Homan

[cdouglas] HADOOP-5149. Modify HistoryViewer to ignore unfamiliar files in the log
directory. Contributed by Hong Tang

[cdouglas] HADOOP-5459. Fix undetected CRC errors where intermediate output is closed
before it has been completely consumed.

[cdouglas] HADOOP-5331. Add support for KFS appends. Contributed by Sriram Rao

[cdouglas] Commit HADOOP-5066 to 0.20 branch

[cdouglas] HADOOP-5363. Add support for proxying connections to multiple clusters with
different versions to hdfsproxy. Contributed by Zhiyong Zhang

[cdouglas] HADOOP-5561. Add javadoc.maxmemory parameter to build, preventing OOM
exceptions from javadoc-dev. Contributed by Jacob Homan

[cdouglas] HADOOP-5423. Include option of preserving file metadata in
SequenceFile::sort. Contributed by Michael Tamm

[cdouglas] HADOOP-5390. Remove duplicate jars from tarball, src from binary tarball
added by hdfsproxy. Contributed by Zhiyong Zhang

[cdouglas] HADOOP-5565. Add API for failing/finalized jobs to the JT metrics
instrumentation. Contributed by Jerome Boulon

[rangadi] HADOOP-5191. Accessing HDFS with any ip or hostname should work as long
as it points to the interface NameNode is listening on. (Raghu Angadi)

------------------------------------------
[...truncated 311621 lines...]
    [junit] 09/03/26 17:54:20 INFO mapred.TaskTracker: reported output size for attempt_200903261751_0005_r_000002_0
 was 0
    [junit] 09/03/26 17:54:20 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/03/26 17:54:20 INFO mapred.JvmManager: JVM : jvm_200903261751_0005_r_-1554166081
exited. Number of tasks it ran: 1
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 0 from
map: attempt_200903261751_0005_m_000000_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:52085, dest: 127.0.0.1:41575,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000000_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 0 from
map: attempt_200903261751_0005_m_000001_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:42365, dest: 127.0.0.1:44267,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000001_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 0 from
map: attempt_200903261751_0005_m_000002_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:35405, dest: 127.0.0.1:58940,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000002_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 0 from
map: attempt_200903261751_0005_m_000003_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:35405, dest: 127.0.0.1:58941,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000003_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000000_0
0.0% reduce > copy > 
    [junit] 09/03/26 17:54:21 INFO mapred.ControlledMapReduceJob: Waiting till 4 reduce tasks
of the job job_200903261751_0005 finish. 0 tasks already got finished.
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000000_0
0.0% reduce > copy > 
    [junit] 09/03/26 17:54:21 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/user/hudson/signalFileDir-8330855945937908376	dst=null	perm=null
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000000_0
1.0% reduce > reduce
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Task attempt_200903261751_0005_r_000000_0
is done.
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: reported output size for attempt_200903261751_0005_r_000000_0
 was 0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/03/26 17:54:21 INFO mapred.JvmManager: JVM : jvm_200903261751_0005_r_1998205200
exited. Number of tasks it ran: 1
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 3 from
map: attempt_200903261751_0005_m_000000_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:52085, dest: 127.0.0.1:41580,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000000_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 3 from
map: attempt_200903261751_0005_m_000001_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:42365, dest: 127.0.0.1:44272,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000001_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 3 from
map: attempt_200903261751_0005_m_000002_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:35405, dest: 127.0.0.1:58945,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000002_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Sent out 12 bytes for reduce: 3 from
map: attempt_200903261751_0005_m_000003_0 given 12/8
    [junit] 09/03/26 17:54:21 INFO TaskTracker.clienttrace: src: 127.0.0.1:35405, dest: 127.0.0.1:58945,
bytes: 12, op: MAPRED_SHUFFLE, cliID: attempt_200903261751_0005_m_000003_0
    [junit] 09/03/26 17:54:21 INFO mapred.JobInProgress: Task 'attempt_200903261751_0005_r_000000_0'
has completed task_200903261751_0005_r_000000 successfully.
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_200903261751_0005_r_000000_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskTracker: About to purge task: attempt_200903261751_0005_r_000000_0
    [junit] 09/03/26 17:54:21 INFO mapred.TaskRunner: attempt_200903261751_0005_r_000000_0
done; removing files.
    [junit] 09/03/26 17:54:22 INFO mapred.JobInProgress: Task 'attempt_200903261751_0005_r_000001_0'
has completed task_200903261751_0005_r_000001 successfully.
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_200903261751_0005_r_000001_0
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: About to purge task: attempt_200903261751_0005_r_000001_0
    [junit] 09/03/26 17:54:22 INFO mapred.TaskRunner: attempt_200903261751_0005_r_000001_0
done; removing files.
    [junit] 09/03/26 17:54:22 INFO mapred.JobInProgress: Task 'attempt_200903261751_0005_r_000002_0'
has completed task_200903261751_0005_r_000002 successfully.
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_200903261751_0005_r_000002_0
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: About to purge task: attempt_200903261751_0005_r_000002_0
    [junit] 09/03/26 17:54:22 INFO mapred.TaskRunner: attempt_200903261751_0005_r_000002_0
done; removing files.
    [junit] 09/03/26 17:54:22 INFO mapred.ControlledMapReduceJob: Waiting till 4 reduce tasks
of the job job_200903261751_0005 finish. 3 tasks already got finished.
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000003_0
0.25% reduce > copy (3 of 4 at 0.00 MB/s) > 
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000003_0
0.25% reduce > copy (3 of 4 at 0.00 MB/s) > 
    [junit] 09/03/26 17:54:22 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000003_0
0.25% reduce > copy (3 of 4 at 0.00 MB/s) > 
    [junit] 09/03/26 17:54:23 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/user/hudson/signalFileDir-8330855945937908376	dst=null	perm=null
    [junit] 09/03/26 17:54:23 INFO mapred.TaskTracker: attempt_200903261751_0005_r_000003_0
1.0% reduce > reduce
    [junit] 09/03/26 17:54:23 INFO mapred.TaskTracker: Task attempt_200903261751_0005_r_000003_0
is done.
    [junit] 09/03/26 17:54:23 INFO mapred.TaskTracker: reported output size for attempt_200903261751_0005_r_000003_0
 was 0
    [junit] 09/03/26 17:54:23 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/03/26 17:54:23 INFO mapred.JvmManager: JVM : jvm_200903261751_0005_r_1368019062
exited. Number of tasks it ran: 1
    [junit] 09/03/26 17:54:23 INFO mapred.ControlledMapReduceJob: Waiting till 4 reduce tasks
of the job job_200903261751_0005 finish. 3 tasks already got finished.
    [junit] 09/03/26 17:54:24 INFO mapred.ControlledMapReduceJob: Waiting till 4 reduce tasks
of the job job_200903261751_0005 finish. 3 tasks already got finished.
    [junit] 09/03/26 17:54:25 INFO mapred.JobInProgress: Task 'attempt_200903261751_0005_r_000003_0'
has completed task_200903261751_0005_r_000003 successfully.
    [junit] 09/03/26 17:54:25 INFO mapred.JobTracker: Adding task 'attempt_200903261751_0005_m_000004_0'
to tip task_200903261751_0005_m_000004, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:39818'
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_200903261751_0005_m_000004_0
task's state:UNASSIGNED
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: Trying to launch : attempt_200903261751_0005_m_000004_0
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_200903261751_0005_m_000004_0
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_200903261751_0005_r_000003_0
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: About to purge task: attempt_200903261751_0005_r_000003_0
    [junit] 09/03/26 17:54:25 INFO mapred.TaskRunner: attempt_200903261751_0005_r_000003_0
done; removing files.
    [junit] 09/03/26 17:54:25 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_200903261751_0005_m_-1744410028
    [junit] 09/03/26 17:54:25 INFO mapred.JvmManager: JVM Runner jvm_200903261751_0005_m_-1744410028
spawned.
    [junit] 09/03/26 17:54:25 INFO mapred.ControlledMapReduceJob: Waiting till 4 reduce tasks
of the job job_200903261751_0005 finish. 4 tasks already got finished.
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: JVM with ID: jvm_200903261751_0005_m_-1744410028
given task: attempt_200903261751_0005_m_000004_0
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: attempt_200903261751_0005_m_000004_0
0.0% 
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: attempt_200903261751_0005_m_000004_0
0.0% cleanup
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: Task attempt_200903261751_0005_m_000004_0
is done.
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: reported output size for attempt_200903261751_0005_m_000004_0
 was 0
    [junit] 09/03/26 17:54:25 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/03/26 17:54:26 INFO mapred.JvmManager: JVM : jvm_200903261751_0005_m_-1744410028
exited. Number of tasks it ran: 1
    [junit] 09/03/26 17:54:28 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200903261751_0005/attempt_200903261751_0005_m_000004_0/output/file.out
in any of the configured local directories
    [junit] 09/03/26 17:54:28 INFO mapred.JobInProgress: Task 'attempt_200903261751_0005_m_000004_0'
has completed task_200903261751_0005_m_000004 successfully.
    [junit] 09/03/26 17:54:28 INFO mapred.JobInProgress: Job job_200903261751_0005 has completed
successfully.
    [junit] 09/03/26 17:54:28 INFO mapred.JobHistory: Recovered job history filename for job
job_200903261751_0005 is localhost_1238089894237_job_200903261751_0005_hudson_ControlledJob
    [junit] 09/03/26 17:54:28 INFO mapred.JobQueuesManager: Job job_200903261751_0005 submitted
to queue Q4 has completed
    [junit] 09/03/26 17:54:28 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_m_000002_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:39818'
    [junit] 09/03/26 17:54:28 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_m_000003_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:39818'
    [junit] 09/03/26 17:54:28 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_m_000004_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:39818'
    [junit] 09/03/26 17:54:28 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_1078672372117611819
is added to invalidSet of 127.0.0.1:53926
    [junit] 09/03/26 17:54:28 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_r_000003_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:39818'
    [junit] 09/03/26 17:54:28 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_1078672372117611819
is added to invalidSet of 127.0.0.1:35184
    [junit] 09/03/26 17:54:28 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4225370303530786027
is added to invalidSet of 127.0.0.1:53926
    [junit] 09/03/26 17:54:28 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4225370303530786027
is added to invalidSet of 127.0.0.1:35184
    [junit] 09/03/26 17:54:28 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200903261751_0005
    [junit] 09/03/26 17:54:28 INFO mapred.TaskRunner: attempt_200903261751_0005_m_000003_0
done; removing files.
    [junit] 09/03/26 17:54:28 INFO mapred.TaskRunner: attempt_200903261751_0005_m_000004_0
done; removing files.
    [junit] 09/03/26 17:54:28 INFO mapred.IndexCache: Map ID attempt_200903261751_0005_m_000004_0
not found in cache
    [junit] 09/03/26 17:54:28 INFO mapred.TaskRunner: attempt_200903261751_0005_m_000002_0
done; removing files.
    [junit] 09/03/26 17:54:28 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/user/hudson/build/contrib/capacity-scheduler/test/system/job_200903261751_0005	dst=null
perm=null
    [junit] 09/03/26 17:54:28 INFO mapred.ControlledMapReduceJob: Return value for the job
: 0
    [junit] 09/03/26 17:54:28 INFO mapred.MiniMRCluster: Waiting for task tracker tracker_host1.foo.com:localhost/127.0.0.1:44889
to be idle.
    [junit] 09/03/26 17:54:29 INFO mapred.MiniMRCluster: Waiting for task tracker tracker_host1.foo.com:localhost/127.0.0.1:44889
to be idle.
    [junit] 09/03/26 17:54:30 INFO mapred.MiniMRCluster: Waiting for task tracker tracker_host1.foo.com:localhost/127.0.0.1:44889
to be idle.
    [junit] 09/03/26 17:54:30 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:53926 to delete
 blk_1078672372117611819_1019 blk_-4225370303530786027_1020
    [junit] 09/03/26 17:54:30 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200903261751_0005
    [junit] 09/03/26 17:54:30 WARN mapred.TaskTracker: Unknown job job_200903261751_0005 being
deleted.
    [junit] 09/03/26 17:54:30 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_r_000000_0'
from 'tracker_host0.foo.com:localhost/127.0.0.1:51083'
    [junit] 09/03/26 17:54:30 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_r_000005_0'
from 'tracker_host0.foo.com:localhost/127.0.0.1:51083'
    [junit] 09/03/26 17:54:30 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200903261751_0005
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_m_000000_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:44889'
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_r_000001_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:44889'
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200903261751_0005
    [junit] 09/03/26 17:54:31 INFO mapred.TaskRunner: attempt_200903261751_0005_m_000000_0
done; removing files.
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_m_000001_0'
from 'tracker_host4.foo.com:localhost/127.0.0.1:45176'
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Removed completed task 'attempt_200903261751_0005_r_000002_0'
from 'tracker_host4.foo.com:localhost/127.0.0.1:45176'
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200903261751_0005
    [junit] 09/03/26 17:54:31 INFO mapred.TaskRunner: attempt_200903261751_0005_m_000001_0
done; removing files.
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:51083
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 51083
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 51083: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 51083: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 51083: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 3 on 51083: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 51083
    [junit] 09/03/26 17:54:31 INFO datanode.DataNode: Deleting block blk_-4225370303530786027_1020
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data4/current/blk_-4225370303530786027

    [junit] 09/03/26 17:54:31 INFO datanode.DataNode: Deleting block blk_1078672372117611819_1019
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data3/current/blk_1078672372117611819

    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:44889
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 44889
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 44889: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 44889: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 44889
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 44889: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 3 on 44889: exiting
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host2.foo.com:localhost/127.0.0.1:46515
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 46515
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 46515
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 46515: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 46515: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 46515: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 3 on 46515: exiting
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host3.foo.com:localhost/127.0.0.1:39818
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 39818
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 39818: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 39818
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 39818: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 39818: exiting
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 3 on 39818: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host4.foo.com:localhost/127.0.0.1:45176
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 45176
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 45176: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 45176
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 3 on 45176: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 45176: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 45176: exiting
    [junit] 09/03/26 17:54:31 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Stopping infoServer
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Stopping interTrackerServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 36194
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 36194
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Stopped interTrackerServer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 5 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 6 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Stopping expireTrackers
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 4 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 8 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 3 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Stopping retirer
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 7 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 9 on 36194: exiting
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: Stopping expireLaunchingTasks
    [junit] 09/03/26 17:54:31 INFO mapred.JobTracker: stopped all jobtracker services
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping server on 55287
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 0 on 55287: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 1 on 55287: exiting
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server listener on 55287
    [junit] 09/03/26 17:54:31 INFO ipc.Server: IPC Server handler 2 on 55287: exiting
    [junit] 09/03/26 17:54:31 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 1
    [junit] 09/03/26 17:54:31 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:31 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:53926,
storageID=DS-2033481505-67.195.138.9-53926-1238089894168, infoPort=51624, ipcPort=55287):DataXceiveServer:
java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 09/03/26 17:54:32 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/03/26 17:54:32 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/03/26 17:54:32 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:53926,
storageID=DS-2033481505-67.195.138.9-53926-1238089894168, infoPort=51624, ipcPort=55287):Finishing
DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data4/current'}

    [junit] 09/03/26 17:54:32 INFO ipc.Server: Stopping server on 55287
    [junit] 09/03/26 17:54:32 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] Shutting down DataNode 0
    [junit] 09/03/26 17:54:32 INFO ipc.Server: Stopping server on 51421
    [junit] 09/03/26 17:54:32 INFO ipc.Server: Stopping IPC Server listener on 51421
    [junit] 09/03/26 17:54:32 INFO ipc.Server: IPC Server handler 1 on 51421: exiting
    [junit] 09/03/26 17:54:32 INFO ipc.Server: IPC Server handler 2 on 51421: exiting
    [junit] 09/03/26 17:54:32 INFO ipc.Server: IPC Server handler 0 on 51421: exiting
    [junit] 09/03/26 17:54:32 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 1
    [junit] 09/03/26 17:54:32 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:32 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:35184,
storageID=DS-216842008-67.195.138.9-35184-1238089894027, infoPort=35235, ipcPort=51421):DataXceiveServer:
java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 09/03/26 17:54:33 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/03/26 17:54:33 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:35184 to delete
 blk_1078672372117611819_1019 blk_-4225370303530786027_1020
    [junit] 09/03/26 17:54:33 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/03/26 17:54:33 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:35184,
storageID=DS-216842008-67.195.138.9-35184-1238089894027, infoPort=35235, ipcPort=51421):Finishing
DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data2/current'}

    [junit] 09/03/26 17:54:33 INFO ipc.Server: Stopping server on 51421
    [junit] 09/03/26 17:54:33 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/03/26 17:54:34 WARN namenode.DecommissionManager: Monitor interrupted: java.lang.InterruptedException:
sleep interrupted
    [junit] 09/03/26 17:54:34 INFO namenode.FSNamesystem: Number of transactions: 109 Total
time for transactions(ms): 5Number of transactions batched in Syncs: 4 Number of syncs: 85
SyncTimes(ms): 57 43 
    [junit] 09/03/26 17:54:34 WARN namenode.FSNamesystem: ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 09/03/26 17:54:34 INFO ipc.Server: Stopping server on 49409
    [junit] 09/03/26 17:54:34 INFO ipc.Server: Stopping IPC Server listener on 49409
    [junit] 09/03/26 17:54:34 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 5 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 2 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 3 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 9 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 4 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 0 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 6 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 1 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 7 on 49409: exiting
    [junit] 09/03/26 17:54:34 INFO ipc.Server: IPC Server handler 8 on 49409: exiting
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 403.461 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :774: The following
error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/src/contrib/build.xml :48:
The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/src/contrib/build-contrib.xml
:245: Tests failed!

Total time: 201 minutes 48 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Mime
View raw message