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 #835
Date Wed, 13 May 2009 18:11:05 GMT
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/835/changes

Changes:

[sharad] HADOOP-5203. TT's version build is too restrictive. Contributed by Rick Cox.

[gkesavan] To resolve jsp-2.1 jars through ivy

[gkesavan] To resolve jsp-2.1 jars through ivy

[szetszwo] HADOOP-5714. Add a metric for NameNode getFileInfo operation.  Contributed by Jakob
Homan

[rangadi] HADOOP-5780. Improve per block message prited by -metaSave in HDFS.
(Raghu Angadi)

[szetszwo] HADOOP-5808. Fix import never used javac warnings in hdfs. (szetszwo)

------------------------------------------
[...truncated 463768 lines...]
    [junit] 09/05/13 18:25:48 INFO mapred.JobTracker: Adding task 'attempt_200905131825_0001_m_000000_0'
to tip task_200905131825_0001_m_000000, for tracker 'tracker_host0.foo.com:localhost/127.0.0.1:37730'
    [junit] 09/05/13 18:25:48 INFO mapred.JobInProgress: Choosing rack-local task task_200905131825_0001_m_000000
    [junit] 09/05/13 18:25:48 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_200905131825_0001_m_000000_0
task's state:UNASSIGNED
    [junit] 09/05/13 18:25:48 INFO mapred.TaskTracker: Trying to launch : attempt_200905131825_0001_m_000000_0
    [junit] 09/05/13 18:25:48 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_200905131825_0001_m_000000_0
    [junit] 09/05/13 18:25:48 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_200905131825_0001_m_000002_0
    [junit] 09/05/13 18:25:48 INFO mapred.TaskTracker: About to purge task: attempt_200905131825_0001_m_000002_0
    [junit] 09/05/13 18:25:48 INFO mapred.TaskRunner: attempt_200905131825_0001_m_000002_0
done; removing files.
    [junit] 09/05/13 18:25:48 INFO mapred.IndexCache: Map ID attempt_200905131825_0001_m_000002_0
not found in cache
    [junit] 09/05/13 18:25:48 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_200905131825_0001_m_1329649916
    [junit] 09/05/13 18:25:48 INFO mapred.JvmManager: JVM Runner jvm_200905131825_0001_m_1329649916
spawned.
    [junit] 09/05/13 18:25:48 INFO mapred.TaskTracker: JVM with ID: jvm_200905131825_0001_m_1329649916
given task: attempt_200905131825_0001_m_000000_0
    [junit] 09/05/13 18:25:49 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/testing/in/part0	dst=null	perm=null
    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/testing/out/_temporary/_attempt_200905131825_0001_m_000000_0	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/testing/out/_temporary/_attempt_200905131825_0001_m_000000_0/part-00000	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/05/13 18:25:49 INFO DataNode.clienttrace: src: /127.0.0.1:56759, dest: /127.0.0.1:35564,
bytes: 19, op: HDFS_READ, cliID: DFSClient_attempt_200905131825_0001_m_000000_0, offset: 0,
srvID: DS-387480647-67.195.138.9-56759-1242239132881, blockid: blk_3328967673695045485_1002,
duration: 250644
    [junit] 09/05/13 18:25:49 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /testing/out/_temporary/_attempt_200905131825_0001_m_000000_0/part-00000.
blk_-6534787357854195957_1008
    [junit] 09/05/13 18:25:49 INFO datanode.DataNode: Receiving block blk_-6534787357854195957_1008
src: /127.0.0.1:35565 dest: /127.0.0.1:56759
    [junit] 09/05/13 18:25:49 INFO datanode.DataNode: Receiving block blk_-6534787357854195957_1008
src: /127.0.0.1:58457 dest: /127.0.0.1:33492
    [junit] 09/05/13 18:25:49 INFO DataNode.clienttrace: src: /127.0.0.1:58457, dest: /127.0.0.1:33492,
bytes: 8, op: HDFS_WRITE, cliID: DFSClient_attempt_200905131825_0001_m_000000_0, offset: 0,
srvID: DS-718470054-67.195.138.9-33492-1242239132658, blockid: blk_-6534787357854195957_1008,
duration: 2075458
    [junit] 09/05/13 18:25:49 INFO datanode.DataNode: PacketResponder 0 for block blk_-6534787357854195957_1008
terminating
    [junit] 09/05/13 18:25:49 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:33492 is added to blk_-6534787357854195957_1008 size 8
    [junit] 09/05/13 18:25:49 INFO DataNode.clienttrace: src: /127.0.0.1:35565, dest: /127.0.0.1:56759,
bytes: 8, op: HDFS_WRITE, cliID: DFSClient_attempt_200905131825_0001_m_000000_0, offset: 0,
srvID: DS-387480647-67.195.138.9-56759-1242239132881, blockid: blk_-6534787357854195957_1008,
duration: 3259734
    [junit] 09/05/13 18:25:49 INFO datanode.DataNode: PacketResponder 1 for block blk_-6534787357854195957_1008
terminating
    [junit] 09/05/13 18:25:49 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56759 is added to blk_-6534787357854195957_1008 size 8
    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /testing/out/_temporary/_attempt_200905131825_0001_m_000000_0/part-00000
is closed by DFSClient_attempt_200905131825_0001_m_000000_0
    [junit] 09/05/13 18:25:49 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:49 INFO mapred.TaskTracker: Task attempt_200905131825_0001_m_000000_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 09/05/13 18:25:49 INFO mapred.TaskTracker: attempt_200905131825_0001_m_000000_0
0.0% 
    [junit] 09/05/13 18:25:51 INFO mapred.TaskTracker: Received commit task action for attempt_200905131825_0001_m_000000_0
    [junit] 09/05/13 18:25:51 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/testing/out/_temporary/_attempt_200905131825_0001_m_000000_0	dst=null	perm=null
    [junit] 09/05/13 18:25:51 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/testing/out	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/05/13 18:25:51 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:51 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:51 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/testing/out/_temporary/_attempt_200905131825_0001_m_000000_0/part-00000	dst=/testing/out/part-00000
perm=hudson:supergroup:rw-r--r--
    [junit] 09/05/13 18:25:51 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:51 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:51 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out/_temporary/_attempt_200905131825_0001_m_000000_0	dst=null	perm=null
    [junit] 09/05/13 18:25:51 INFO mapred.TaskTracker: attempt_200905131825_0001_m_000000_0
1.0% Records R/W=1/1
    [junit] 09/05/13 18:25:51 INFO mapred.TaskTracker: Task attempt_200905131825_0001_m_000000_0
is done.
    [junit] 09/05/13 18:25:51 INFO mapred.TaskTracker: reported output size for attempt_200905131825_0001_m_000000_0
 was 0
    [junit] 09/05/13 18:25:51 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/05/13 18:25:51 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/13 18:25:51 INFO util.ProcessTree: Killing process group15989 with SIGKILL.
Exit code 1
    [junit] 09/05/13 18:25:51 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/13 18:25:51 INFO mapred.JvmManager: JVM : jvm_200905131825_0001_m_-98835857
exited. Number of tasks it ran: 1
    [junit] 09/05/13 18:25:51 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/13 18:25:51 INFO util.ProcessTree: Killing all processes in the process
group 16044 with SIGTERM. Exit code 1
    [junit] 09/05/13 18:25:54 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200905131825_0001/attempt_200905131825_0001_m_000000_0/output/file.out
in any of the configured local directories
    [junit] 09/05/13 18:25:54 INFO mapred.JobInProgress: Task 'attempt_200905131825_0001_m_000000_0'
has completed task_200905131825_0001_m_000000 successfully.
    [junit] 09/05/13 18:25:54 INFO mapred.JobTracker: Adding task 'attempt_200905131825_0001_m_000001_0'
to tip task_200905131825_0001_m_000001, for tracker 'tracker_host0.foo.com:localhost/127.0.0.1:37730'
    [junit] 09/05/13 18:25:54 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_200905131825_0001_m_000001_0
task's state:UNASSIGNED
    [junit] 09/05/13 18:25:54 INFO mapred.TaskTracker: Trying to launch : attempt_200905131825_0001_m_000001_0
    [junit] 09/05/13 18:25:54 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_200905131825_0001_m_000001_0
    [junit] 09/05/13 18:25:54 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_200905131825_0001_m_-1795405387
    [junit] 09/05/13 18:25:54 INFO mapred.JvmManager: JVM Runner jvm_200905131825_0001_m_-1795405387
spawned.
    [junit] 09/05/13 18:25:54 INFO mapred.TaskTracker: JVM with ID: jvm_200905131825_0001_m_-1795405387
given task: attempt_200905131825_0001_m_000001_0
    [junit] 09/05/13 18:25:54 INFO streaming.StreamJob:  map 100%  reduce 0%
    [junit] 09/05/13 18:25:55 INFO mapred.TaskTracker: attempt_200905131825_0001_m_000001_0
0.0% 
    [junit] 09/05/13 18:25:55 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:55 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:55 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out/_temporary	dst=null	perm=null
    [junit] 09/05/13 18:25:55 INFO mapred.TaskTracker: attempt_200905131825_0001_m_000001_0
0.0% cleanup
    [junit] 09/05/13 18:25:55 INFO mapred.TaskTracker: Task attempt_200905131825_0001_m_000001_0
is done.
    [junit] 09/05/13 18:25:55 INFO mapred.TaskTracker: reported output size for attempt_200905131825_0001_m_000001_0
 was 0
    [junit] 09/05/13 18:25:55 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/05/13 18:25:55 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/13 18:25:55 INFO util.ProcessTree: Killing all processes in the process
group 16136 with SIGTERM. Exit code 1
    [junit] 09/05/13 18:25:56 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/13 18:25:56 INFO util.ProcessTree: Killing process group16044 with SIGKILL.
Exit code 1
    [junit] 09/05/13 18:25:56 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/13 18:25:56 INFO mapred.JvmManager: JVM : jvm_200905131825_0001_m_1329649916
exited. Number of tasks it ran: 1
    [junit] 09/05/13 18:25:57 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200905131825_0001/attempt_200905131825_0001_m_000001_0/output/file.out
in any of the configured local directories
    [junit] 09/05/13 18:25:57 INFO mapred.JobInProgress: Task 'attempt_200905131825_0001_m_000001_0'
has completed task_200905131825_0001_m_000001 successfully.
    [junit] 09/05/13 18:25:57 INFO mapred.JobInProgress: Job job_200905131825_0001 has completed
successfully.
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /testing/out/_logs/history/localhost_1242239133182_job_200905131825_0001_hudson_streamjob2920950849554931793.jar.
blk_-3833894703572525500_1008
    [junit] 09/05/13 18:25:57 INFO datanode.DataNode: Receiving block blk_-3833894703572525500_1008
src: /127.0.0.1:58463 dest: /127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO datanode.DataNode: Receiving block blk_-3833894703572525500_1008
src: /127.0.0.1:35573 dest: /127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO DataNode.clienttrace: src: /127.0.0.1:35573, dest: /127.0.0.1:56759,
bytes: 4570, op: HDFS_WRITE, cliID: DFSClient_2048427392, offset: 0, srvID: DS-387480647-67.195.138.9-56759-1242239132881,
blockid: blk_-3833894703572525500_1008, duration: 1831754
    [junit] 09/05/13 18:25:57 INFO datanode.DataNode: PacketResponder 0 for block blk_-3833894703572525500_1008
terminating
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56759 is added to blk_-3833894703572525500_1008 size 4570
    [junit] 09/05/13 18:25:57 INFO DataNode.clienttrace: src: /127.0.0.1:58463, dest: /127.0.0.1:33492,
bytes: 4570, op: HDFS_WRITE, cliID: DFSClient_2048427392, offset: 0, srvID: DS-718470054-67.195.138.9-33492-1242239132658,
blockid: blk_-3833894703572525500_1008, duration: 2607776
    [junit] 09/05/13 18:25:57 INFO datanode.DataNode: PacketResponder 1 for block blk_-3833894703572525500_1008
terminating
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:33492 is added to blk_-3833894703572525500_1008 size 4570
    [junit] 09/05/13 18:25:57 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /testing/out/_logs/history/localhost_1242239133182_job_200905131825_0001_hudson_streamjob2920950849554931793.jar
is closed by DFSClient_2048427392
    [junit] 09/05/13 18:25:57 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4459242487938212841
is added to invalidSet of 127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4459242487938212841
is added to invalidSet of 127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_7836101389498804706
is added to invalidSet of 127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_7836101389498804706
is added to invalidSet of 127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-7469754048978467764
is added to invalidSet of 127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-7469754048978467764
is added to invalidSet of 127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:57 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:57 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/user/hudson/build/contrib/streaming/test/system/job_200905131825_0001	dst=null	perm=null
    [junit] 09/05/13 18:25:57 INFO mapred.JobTracker: Removed completed task 'attempt_200905131825_0001_m_000000_0'
from 'tracker_host0.foo.com:localhost/127.0.0.1:37730'
    [junit] 09/05/13 18:25:57 INFO mapred.JobTracker: Removed completed task 'attempt_200905131825_0001_m_000001_0'
from 'tracker_host0.foo.com:localhost/127.0.0.1:37730'
    [junit] 09/05/13 18:25:57 INFO mapred.JobTracker: Removed completed task 'attempt_200905131825_0001_m_000002_0'
from 'tracker_host0.foo.com:localhost/127.0.0.1:37730'
    [junit] 09/05/13 18:25:57 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200905131825_0001
    [junit] 09/05/13 18:25:57 INFO mapred.TaskRunner: attempt_200905131825_0001_m_000001_0
done; removing files.
    [junit] 09/05/13 18:25:57 INFO mapred.IndexCache: Map ID attempt_200905131825_0001_m_000001_0
not found in cache
    [junit] 09/05/13 18:25:57 INFO mapred.TaskRunner: attempt_200905131825_0001_m_000000_0
done; removing files.
    [junit] 09/05/13 18:25:57 INFO mapred.IndexCache: Map ID attempt_200905131825_0001_m_000000_0
not found in cache
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:56759 to delete
 blk_7836101389498804706_1004 blk_-4459242487938212841_1003 blk_-7469754048978467764_1005
    [junit] 09/05/13 18:25:57 INFO streaming.StreamJob: Job complete: job_200905131825_0001
    [junit] 09/05/13 18:25:57 INFO streaming.StreamJob: Output: /testing/out
    [junit] 09/05/13 18:25:57 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/testing/out	dst=null	perm=null
    [junit] 09/05/13 18:25:57 INFO mapred.TestMiniMRWithDFS: File list[0]: hdfs://localhost:34126/testing/out/part-00000
    [junit] 09/05/13 18:25:57 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/testing/out/part-00000	dst=null	perm=null
    [junit] 09/05/13 18:25:57 INFO DataNode.clienttrace: src: /127.0.0.1:56759, dest: /127.0.0.1:35574,
bytes: 12, op: HDFS_READ, cliID: DFSClient_2048427392, offset: 0, srvID: DS-387480647-67.195.138.9-56759-1242239132881,
blockid: blk_-6534787357854195957_1008, duration: 276679
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3471641933698950102
is added to invalidSet of 127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3471641933698950102
is added to invalidSet of 127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3833894703572525500
is added to invalidSet of 127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3833894703572525500
is added to invalidSet of 127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6534787357854195957
is added to invalidSet of 127.0.0.1:33492
    [junit] 09/05/13 18:25:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6534787357854195957
is added to invalidSet of 127.0.0.1:56759
    [junit] 09/05/13 18:25:57 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:57 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:57 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out	dst=null	perm=null
    [junit] 09/05/13 18:25:57 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:37730
    [junit] 09/05/13 18:25:57 INFO ipc.Server: Stopping server on 37730
    [junit] 09/05/13 18:25:57 INFO ipc.Server: IPC Server handler 0 on 37730: exiting
    [junit] 09/05/13 18:25:57 INFO ipc.Server: IPC Server handler 1 on 37730: exiting
    [junit] 09/05/13 18:25:57 INFO ipc.Server: IPC Server handler 2 on 37730: exiting
    [junit] 09/05/13 18:25:57 INFO ipc.Server: IPC Server handler 3 on 37730: exiting
    [junit] 09/05/13 18:25:57 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/05/13 18:25:57 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/13 18:25:57 INFO ipc.Server: Stopping IPC Server listener on 37730
    [junit] 09/05/13 18:25:58 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/05/13 18:25:58 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:43104
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping server on 43104
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 0 on 43104: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 2 on 43104: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 3 on 43104: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 1 on 43104: exiting
    [junit] 09/05/13 18:25:58 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server listener on 43104
    [junit] 09/05/13 18:25:58 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: Stopping infoServer
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: Stopping interTrackerServer
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping server on 47932
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 0 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 1 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server listener on 47932
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: Stopping expireTrackers
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: Stopped interTrackerServer
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 9 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 4 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 2 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: Stopping retirer
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 5 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 6 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO mapred.EagerTaskInitializationListener: Stopping Job Init
Manager thread
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 8 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 7 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO mapred.EagerTaskInitializationListener: JobInitManagerThread
interrupted.
    [junit] 09/05/13 18:25:58 INFO mapred.EagerTaskInitializationListener: Shutting down thread
pool
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 3 on 47932: exiting
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: Stopping expireLaunchingTasks
    [junit] 09/05/13 18:25:58 INFO mapred.JobTracker: stopped all jobtracker services
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping server on 45913
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 0 on 45913: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 2 on 45913: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 1 on 45913: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server listener on 45913
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/13 18:25:58 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:56759,
storageID=DS-387480647-67.195.138.9-56759-1242239132881, infoPort=49884, ipcPort=45913):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/05/13 18:25:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/13 18:25:58 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/05/13 18:25:58 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:56759,
storageID=DS-387480647-67.195.138.9-56759-1242239132881, infoPort=49884, ipcPort=45913):Finishing
DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/data/data4/current'}

    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping server on 45913
    [junit] 09/05/13 18:25:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] Shutting down DataNode 0
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping server on 35403
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 0 on 35403: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server listener on 35403
    [junit] 09/05/13 18:25:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/13 18:25:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 1
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 1 on 35403: exiting
    [junit] 09/05/13 18:25:58 INFO ipc.Server: IPC Server handler 2 on 35403: exiting
    [junit] 09/05/13 18:25:58 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:33492,
storageID=DS-718470054-67.195.138.9-33492-1242239132658, infoPort=45132, ipcPort=35403):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/05/13 18:25:58 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/05/13 18:25:59 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/13 18:25:59 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:33492,
storageID=DS-718470054-67.195.138.9-33492-1242239132658, infoPort=45132, ipcPort=35403):Finishing
DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/data/data2/current'}

    [junit] 09/05/13 18:25:59 INFO ipc.Server: Stopping server on 35403
    [junit] 09/05/13 18:25:59 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/13 18:25:59 WARN namenode.DecommissionManager: Monitor interrupted: java.lang.InterruptedException:
sleep interrupted
    [junit] 09/05/13 18:25:59 INFO namenode.FSNamesystem: Number of transactions: 51 Total
time for transactions(ms): 7Number of transactions batched in Syncs: 1 Number of syncs: 34
SyncTimes(ms): 28 33 
    [junit] 09/05/13 18:25:59 WARN namenode.FSNamesystem: ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 09/05/13 18:25:59 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS);

    [junit] 09/05/13 18:25:59 INFO ipc.Server: Stopping server on 34126
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 1 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 8 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: Stopping IPC Server listener on 34126
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 6 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 9 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 7 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 4 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 5 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 3 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 2 on 34126: exiting
    [junit] 09/05/13 18:25:59 INFO ipc.Server: IPC Server handler 0 on 34126: exiting
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 47.778 sec
    [junit] Running org.apache.hadoop.typedbytes.TestIO
    [junit] 09/05/13 18:26:00 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: 3, Failures: 0, Errors: 0, Time elapsed: 0.25 sec
    [junit] Running org.apache.hadoop.typedbytes.TestTypedBytesWritable
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.194 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1049: The following
error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1022: 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/streaming/build.xml
:42: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/src/contrib/build-contrib.xml
:250: Tests failed!

Total time: 228 minutes 52 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Mime
View raw message