See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/801/changes
Changes:
[sharad] HADOOP-5394. JobTracker might schedule 2 attempts of the same task
with the same attempt id across restarts. Contributed by Amar kamat.
[sharad] HADOOP-5521. Moving the comment in CHANGES.txt to 0.20's Improvement
section
[sharad] HADOOP-5521. Committing to branch-0.20 as well. Moving the comment in
CHANGES.txt to 0.20 section
[omalley] HADOOP-5585. Clear FileSystem statistics between tasks when jvm-reuse
is enabled. (omalley)
[cutting] HADOOP-5469. Add /metrics servlet to daemons, providing metrics over
HTTP as either text or JSON. Contributed by Philip Zeyliger.
[cdouglas] Revert HADOOP-5469
[cutting] HADOOP-5469. Add /metrics servlet to daemons, providing metrics over
HTTP as either text or JSON. Contributed by Philip Zeyliger.
[cutting] HADOOP-5518. Update eclipse classpath, missed in original commit.
[szetszwo] HADOOP-5068. Fix NPE in TestCapacityScheduler. (Vinod Kumar
Vavilapalli via szetszwo)
[hairong] HADOOP-3810. NameNode seems unstable on a cluster with little space
left. Contributed by Hairong Kuang.
[hairong] HADOOP-5603. Improve NameNode's block placement performance by
reducing the number of iterations on datanodes. Contributed by Hairong Kuang.
------------------------------------------
[...truncated 355398 lines...]
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0002 User: u1 Queue : default
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0001 in Queue default For user : u1
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0002 in Queue default For user : u1
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0001
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0003 User: u1 Queue : default
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0003 in Queue default For user : u1
[junit] 09/04/08 16:22:00 INFO mapred.JobQueuesManager: Job job_test_0001
submitted to queue default has completed
[junit] 09/04/08 16:22:00 INFO mapred.JobQueuesManager: Job job_test_0002
submitted to queue default has completed
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Removing
killed/completed job from initalized jobs list : job_test_0002
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0004 User: u1 Queue : default
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0004 in Queue default For user : u1
[junit] 09/04/08 16:22:00 INFO mapred.JobQueuesManager: Job job_test_0005
submitted to queue default has completed
[junit] 09/04/08 16:22:00 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0003
[junit] 09/04/08 16:22:00 INFO mapred.JobQueuesManager: Job job_test_0003
submitted to queue default has completed
[junit] 09/04/08 16:22:00 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:00 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:00 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:00 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:00 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:00 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 WARN mapred.CapacityTaskScheduler: job_test_0001
(5368709120vmem, 3221225472pmem) exceeds the cluster's max-memory-limits
(1073741824vmem, 1073741824pmem). Cannot run in this cluster, so killing it.
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0001
submitted to queue default has completed
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0002
submitted to queue default has completed
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0001
submitted to queue default has completed
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0001 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0002 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0005 User: u2 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0006 User: u2 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0009 User: u3 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0010 User: u3 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0001 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0002 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0005 in Queue default For user : u2
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0006 in Queue default For user : u2
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0009 in Queue default For user : u3
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0010 in Queue default For user : u3
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0001
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0002
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0003 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0004 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0003 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0004 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0003
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0004
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0013 User: u4 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0013 in Queue default For user : u4
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0001 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0002 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0004 User: u2 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0005 User: u2 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0007 User: u3 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0008 User: u3 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0001 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0002 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0004 in Queue default For user : u2
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0005 in Queue default For user : u2
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0007 in Queue default For user : u3
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0008 in Queue default For user : u3
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0010 User: u4 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0010 in Queue default For user : u4
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Only one queue
present. Reclaim capacity thread not started.
[junit] 09/04/08 16:22:01 INFO mapred.CapacityTaskScheduler: Capacity
scheduler initialized 1 queues
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0001 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0001 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Removing
scheduled jobs from waiting queuejob_test_0001
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0001
submitted to queue default has completed
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0002
submitted to queue default has completed
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Passing to
Initializer Job Id :job_test_0003 User: u1 Queue : default
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Initializing
job : job_test_0003 in Queue default For user : u1
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0003
submitted to queue default has completed
[junit] 09/04/08 16:22:01 INFO mapred.JobInitializationPoller: Removing
killed/completed job from initalized jobs list : job_test_0003
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job submitted to
queue default
[junit] 09/04/08 16:22:01 INFO mapred.JobQueuesManager: Job job_test_0004
submitted to queue default has completed
[junit] Tests run: 27, Failures: 0, Errors: 0, Time elapsed: 1.286 sec
[junit] Running org.apache.hadoop.mapred.TestCapacitySchedulerConf
[junit] 09/04/08 16:22:01 WARN conf.Configuration: DEPRECATED:
hadoop-site.xml found in the classpath. Usage of hadoop-site.xml is deprecated.
Instead use core-site.xml, mapred-site.xml and hdfs-site.xml to override
properties of core-default.xml, mapred-default.xml and hdfs-default.xml
respectively
[junit] Tests run: 10, Failures: 0, Errors: 0, Time elapsed: 0.241 sec
[junit] Running org.apache.hadoop.mapred.TestQueueCapacities
[junit] 09/04/08 16:22:02 WARN conf.Configuration: DEPRECATED:
hadoop-site.xml found in the classpath. Usage of hadoop-site.xml is deprecated.
Instead use core-site.xml, mapred-site.xml and hdfs-site.xml to override
properties of core-default.xml, mapred-default.xml and hdfs-default.xml
respectively
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: fsOwner=hudson,hudson
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: supergroup=supergroup
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem:
isPermissionEnabled=true
[junit] 09/04/08 16:22:02 INFO common.Storage: Image file of size 96 saved
in 0 seconds.
[junit] 09/04/08 16:22:02 INFO common.Storage: Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/name1
has been successfully formatted.
[junit] 09/04/08 16:22:02 INFO common.Storage: Image file of size 96 saved
in 0 seconds.
[junit] 09/04/08 16:22:02 INFO common.Storage: Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/name2
has been successfully formatted.
[junit] 09/04/08 16:22:02 INFO metrics.RpcMetrics: Initializing RPC Metrics
with hostName=NameNode, port=50253
[junit] 09/04/08 16:22:02 INFO namenode.NameNode: NameNode up at:
localhost/127.0.0.1:50253
[junit] 09/04/08 16:22:02 INFO jvm.JvmMetrics: Initializing JVM Metrics
with processName=NameNode, sessionId=null
[junit] 09/04/08 16:22:02 INFO metrics.NameNodeMetrics: Initializing
NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: fsOwner=hudson,hudson
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: supergroup=supergroup
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem:
isPermissionEnabled=true
[junit] 09/04/08 16:22:02 INFO metrics.FSNamesystemMetrics: Initializing
FSNamesystemMetrics using context
object:org.apache.hadoop.metrics.spi.NullContext
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: Registered
FSNamesystemStatusMBean
[junit] 09/04/08 16:22:02 INFO common.Storage: Number of files = 1
[junit] 09/04/08 16:22:02 INFO common.Storage: Number of files under
construction = 0
[junit] 09/04/08 16:22:02 INFO common.Storage: Image file of size 96 loaded
in 0 seconds.
[junit] 09/04/08 16:22:02 INFO common.Storage: Edits file
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/name1/current/edits
of size 4 edits # 0 loaded in 0 seconds.
[junit] 09/04/08 16:22:02 INFO common.Storage: Image file of size 96 saved
in 0 seconds.
[junit] 09/04/08 16:22:02 INFO common.Storage: Image file of size 96 saved
in 0 seconds.
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: current list of
storage
dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/capacity-scheduler/test/data/dfs/name2(IMAGE_AND_EDITS);
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: Finished loading
FSImage in 46 msecs
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: Total number of
blocks = 0
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: Number of invalid
blocks = 0
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: Number of
under-replicated blocks = 0
[junit] 09/04/08 16:22:02 INFO namenode.FSNamesystem: Number of
over-replicated blocks = 0
[junit] 09/04/08 16:22:02 INFO hdfs.StateChange: STATE* Leaving safe mode
after 0 secs.
[junit] 09/04/08 16:22:02 INFO hdfs.StateChange: STATE* Network topology
has 0 racks and 0 datanodes
[junit] 09/04/08 16:22:02 INFO hdfs.StateChange: STATE*
UnderReplicatedBlocks has 0 blocks
[junit] 2009-04-08 16:22:02.842::INFO: Logging to STDERR via
org.mortbay.log.StdErrLog
[junit] 09/04/08 16:22:02 INFO http.HttpServer: Jetty bound to port 50256
[junit] 2009-04-08 16:22:02.906::INFO: jetty-6.1.14
[junit] 2009-04-08 16:22:03.330::INFO: Started
selectchannelconnec...@localhost:50256
[junit] 09/04/08 16:22:03 INFO namenode.NameNode: NameNode Web-server up
at: localhost/127.0.0.1:50256
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server Responder: starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server listener on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 0 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 1 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 3 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 2 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 4 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 5 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 6 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 7 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 8 on 50253:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 9 on 50253:
starting
[junit] Starting DataNode 0 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data2
[junit] 09/04/08 16:22:03 INFO common.Storage: Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data1
is not formatted.
[junit] 09/04/08 16:22:03 INFO common.Storage: Formatting ...
[junit] 09/04/08 16:22:03 INFO common.Storage: Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data2
is not formatted.
[junit] 09/04/08 16:22:03 INFO common.Storage: Formatting ...
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Registered
FSDatasetStatusMBean
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Opened info server at
55128
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Balancing bandwith is
1048576 bytes/s
[junit] 09/04/08 16:22:03 INFO http.HttpServer: Jetty bound to port 49840
[junit] 2009-04-08 16:22:03.453::INFO: jetty-6.1.14
[junit] 2009-04-08 16:22:03.554::INFO: Started
selectchannelconnec...@localhost:49840
[junit] 09/04/08 16:22:03 INFO jvm.JvmMetrics: Cannot initialize JVM
Metrics with processName=DataNode, sessionId=null - already initialized
[junit] 09/04/08 16:22:03 INFO metrics.RpcMetrics: Initializing RPC Metrics
with hostName=DataNode, port=42542
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server Responder: starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server listener on 42542:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 1 on 42542:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 2 on 42542:
starting
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: dnRegistration =
DatanodeRegistration(vesta.apache.org:55128, storageID=, infoPort=49840,
ipcPort=42542)
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 0 on 42542:
starting
[junit] 09/04/08 16:22:03 INFO hdfs.StateChange: BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:55128 storage
DS-1019259086-67.195.138.9-55128-1239207723574
[junit] 09/04/08 16:22:03 INFO net.NetworkTopology: Adding a new node:
/default-rack/127.0.0.1:55128
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: New storage id
DS-1019259086-67.195.138.9-55128-1239207723574 is assigned to data-node
127.0.0.1:55128
[junit] 09/04/08 16:22:03 INFO datanode.DataNode:
DatanodeRegistration(127.0.0.1:55128,
storageID=DS-1019259086-67.195.138.9-55128-1239207723574, infoPort=49840,
ipcPort=42542)In DataNode.run, data =
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/04/08 16:22:03 INFO datanode.DataNode: using
BLOCKREPORT_INTERVAL of 3600000msec Initial delay: 0msec
[junit] Starting DataNode 1 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data4
[junit] 09/04/08 16:22:03 INFO common.Storage: Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data3
is not formatted.
[junit] 09/04/08 16:22:03 INFO common.Storage: Formatting ...
[junit] 09/04/08 16:22:03 INFO common.Storage: Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/data/data4
is not formatted.
[junit] 09/04/08 16:22:03 INFO common.Storage: Formatting ...
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: BlockReport of 0 blocks
got processed in 6 msecs
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Starting Periodic block
scanner.
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Registered
FSDatasetStatusMBean
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Opened info server at
51489
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Balancing bandwith is
1048576 bytes/s
[junit] 09/04/08 16:22:03 INFO http.HttpServer: Jetty bound to port 46878
[junit] 2009-04-08 16:22:03.630::INFO: jetty-6.1.14
[junit] 2009-04-08 16:22:03.720::INFO: Started
selectchannelconnec...@localhost:46878
[junit] 09/04/08 16:22:03 INFO jvm.JvmMetrics: Cannot initialize JVM
Metrics with processName=DataNode, sessionId=null - already initialized
[junit] 09/04/08 16:22:03 INFO metrics.RpcMetrics: Initializing RPC Metrics
with hostName=DataNode, port=49357
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server Responder: starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 0 on 49357:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server listener on 49357:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 1 on 49357:
starting
[junit] 09/04/08 16:22:03 INFO ipc.Server: IPC Server handler 2 on 49357:
starting
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: dnRegistration =
DatanodeRegistration(vesta.apache.org:51489, storageID=, infoPort=46878,
ipcPort=49357)
[junit] 09/04/08 16:22:03 INFO hdfs.StateChange: BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:51489 storage
DS-1033408810-67.195.138.9-51489-1239207723724
[junit] 09/04/08 16:22:03 INFO net.NetworkTopology: Adding a new node:
/default-rack/127.0.0.1:51489
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: New storage id
DS-1033408810-67.195.138.9-51489-1239207723724 is assigned to data-node
127.0.0.1:51489
[junit] 09/04/08 16:22:03 INFO datanode.DataNode:
DatanodeRegistration(127.0.0.1:51489,
storageID=DS-1033408810-67.195.138.9-51489-1239207723724, infoPort=46878,
ipcPort=49357)In DataNode.run, data =
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/04/08 16:22:03 INFO datanode.DataNode: using
BLOCKREPORT_INTERVAL of 3600000msec Initial delay: 0msec
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: BlockReport of 0 blocks
got processed in 1 msecs
[junit] 09/04/08 16:22:03 INFO datanode.DataNode: Starting Periodic block
scanner.
[junit] 09/04/08 16:22:03 INFO namenode.FSNamesystem: fsOwner=hudson,hudson
[junit] 09/04/08 16:22:03 INFO namenode.FSNamesystem: supergroup=supergroup
[junit] 09/04/08 16:22:03 INFO namenode.FSNamesystem:
isPermissionEnabled=true
[junit] 09/04/08 16:22:03 INFO common.Storage: Cannot lock storage
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/name1.
The directory is already locked.
[junit] 09/04/08 16:22:03 INFO namenode.FSNamesystem: fsOwner=hudson,hudson
[junit] 09/04/08 16:22:03 INFO namenode.FSNamesystem: supergroup=supergroup
[junit] 09/04/08 16:22:03 INFO namenode.FSNamesystem:
isPermissionEnabled=true
[junit] 09/04/08 16:22:03 INFO common.Storage: Cannot lock storage
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/capacity-scheduler/test/data/dfs/name1.
The directory is already locked.
[junit] Tests run: 3, Failures: 0, Errors: 3, Time elapsed: 1.738 sec
[junit] Test org.apache.hadoop.mapred.TestQueueCapacities FAILED
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: 185 minutes 16 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...
