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 #801
Date Wed, 08 Apr 2009 16:09:43 GMT
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 SelectChannelConnector@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 SelectChannelConnector@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 SelectChannelConnector@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...


Mime
View raw message