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 #842
Date Thu, 21 May 2009 17:59:59 GMT
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/842/changes

Changes:

[ddas] HADOOP-4372. Moving the comment in CHANGES.txt for HADOOP-4372 from trunk to the 0.20.1
part.

[szetszwo] HADOOP-5878. Fix import and Serializable javac warnings found in hdfs jsp.

[szetszwo] HADOOP-5841. Resolve findbugs warnings in DistributedFileSystem, DatanodeInfo,
BlocksMap, DataNodeDescriptor.  Contributed by Jakob Homan

------------------------------------------
[...truncated 472218 lines...]
    [junit] 09/05/21 18:16:32 INFO mapred.JobInProgress: Choosing rack-local task task_200905211816_0001_m_000000
    [junit] 09/05/21 18:16:32 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_200905211816_0001_m_000000_0
task's state:UNASSIGNED
    [junit] 09/05/21 18:16:32 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_200905211816_0001_m_000002_0
    [junit] 09/05/21 18:16:32 INFO mapred.TaskTracker: About to purge task: attempt_200905211816_0001_m_000002_0
    [junit] 09/05/21 18:16:32 INFO mapred.TaskRunner: attempt_200905211816_0001_m_000002_0
done; removing files.
    [junit] 09/05/21 18:16:32 INFO mapred.IndexCache: Map ID attempt_200905211816_0001_m_000002_0
not found in cache
    [junit] 09/05/21 18:16:32 INFO mapred.TaskTracker: Trying to launch : attempt_200905211816_0001_m_000000_0
    [junit] 09/05/21 18:16:32 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_200905211816_0001_m_000000_0
    [junit] 09/05/21 18:16:32 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_200905211816_0001_m_277980030
    [junit] 09/05/21 18:16:32 INFO mapred.JvmManager: JVM Runner jvm_200905211816_0001_m_277980030
spawned.
    [junit] 09/05/21 18:16:33 INFO mapred.TaskTracker: JVM with ID: jvm_200905211816_0001_m_277980030
given task: attempt_200905211816_0001_m_000000_0
    [junit] 09/05/21 18:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/testing/in/part0	dst=null	perm=null
    [junit] 09/05/21 18:16:33 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/21 18:16:33 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/21 18:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/testing/out/_temporary/_attempt_200905211816_0001_m_000000_0	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/05/21 18:16:33 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/21 18:16:33 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/21 18:16:33 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/21 18:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/testing/out/_temporary/_attempt_200905211816_0001_m_000000_0/part-00000	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/05/21 18:16:33 INFO DataNode.clienttrace: src: /127.0.0.1:50620, dest: /127.0.0.1:59415,
bytes: 19, op: HDFS_READ, cliID: DFSClient_attempt_200905211816_0001_m_000000_0, offset: 0,
srvID: DS-1207334634-67.195.138.9-50620-1242929775040, blockid: blk_6373480733157481997_1002,
duration: 243929
    [junit] 09/05/21 18:16:33 INFO mapred.TaskTracker: attempt_200905211816_0001_m_000000_0
0.0% 
    [junit] 09/05/21 18:16:33 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /testing/out/_temporary/_attempt_200905211816_0001_m_000000_0/part-00000.
blk_8832556030678788585_1008
    [junit] 09/05/21 18:16:33 INFO datanode.DataNode: Receiving block blk_8832556030678788585_1008
src: /127.0.0.1:59416 dest: /127.0.0.1:50620
    [junit] 09/05/21 18:16:33 INFO datanode.DataNode: Receiving block blk_8832556030678788585_1008
src: /127.0.0.1:56272 dest: /127.0.0.1:45260
    [junit] 09/05/21 18:16:33 INFO DataNode.clienttrace: src: /127.0.0.1:56272, dest: /127.0.0.1:45260,
bytes: 8, op: HDFS_WRITE, cliID: DFSClient_attempt_200905211816_0001_m_000000_0, offset: 0,
srvID: DS-1787900321-67.195.138.9-45260-1242929774382, blockid: blk_8832556030678788585_1008,
duration: 883567
    [junit] 09/05/21 18:16:33 INFO datanode.DataNode: PacketResponder 0 for block blk_8832556030678788585_1008
terminating
    [junit] 09/05/21 18:16:33 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:45260 is added to blk_8832556030678788585_1008 size 8
    [junit] 09/05/21 18:16:33 INFO DataNode.clienttrace: src: /127.0.0.1:59416, dest: /127.0.0.1:50620,
bytes: 8, op: HDFS_WRITE, cliID: DFSClient_attempt_200905211816_0001_m_000000_0, offset: 0,
srvID: DS-1207334634-67.195.138.9-50620-1242929775040, blockid: blk_8832556030678788585_1008,
duration: 1890158
    [junit] 09/05/21 18:16:34 INFO datanode.DataNode: PacketResponder 1 for block blk_8832556030678788585_1008
terminating
    [junit] 09/05/21 18:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50620 is added to blk_8832556030678788585_1008 size 8
    [junit] 09/05/21 18:16:34 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/21 18:16:34 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /testing/out/_temporary/_attempt_200905211816_0001_m_000000_0/part-00000
is closed by DFSClient_attempt_200905211816_0001_m_000000_0
    [junit] 09/05/21 18:16:34 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/21 18:16:34 INFO mapred.TaskTracker: Task attempt_200905211816_0001_m_000000_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 09/05/21 18:16:34 INFO mapred.TaskTracker: attempt_200905211816_0001_m_000000_0
0.0% 
    [junit] 09/05/21 18:16:35 INFO mapred.TaskTracker: Received commit task action for attempt_200905211816_0001_m_000000_0
    [junit] 09/05/21 18:16:35 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/21 18:16:35 INFO util.ProcessTree: Killing process group31127 with SIGKILL.
Exit code 1
    [junit] 09/05/21 18:16:35 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/21 18:16:35 INFO mapred.JvmManager: JVM : jvm_200905211816_0001_m_-1716704130
exited. Number of tasks it ran: 1
    [junit] 09/05/21 18:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/testing/out/_temporary/_attempt_200905211816_0001_m_000000_0	dst=null	perm=null
    [junit] 09/05/21 18:16:36 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/21 18:16:36 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/21 18:16:36 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/21 18:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/testing/out/_temporary/_attempt_200905211816_0001_m_000000_0/part-00000	dst=/testing/out/part-00000
perm=hudson:supergroup:rw-r--r--
    [junit] 09/05/21 18:16:36 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/21 18:16:36 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/21 18:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out/_temporary/_attempt_200905211816_0001_m_000000_0	dst=null	perm=null
    [junit] 09/05/21 18:16:36 INFO mapred.TaskTracker: attempt_200905211816_0001_m_000000_0
0.667% Records R/W=1/1 > sort
    [junit] 09/05/21 18:16:36 INFO mapred.TaskTracker: Task attempt_200905211816_0001_m_000000_0
is done.
    [junit] 09/05/21 18:16:36 INFO mapred.TaskTracker: reported output size for attempt_200905211816_0001_m_000000_0
 was 0
    [junit] 09/05/21 18:16:36 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/05/21 18:16:36 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/21 18:16:36 INFO util.ProcessTree: Killing all processes in the process
group 31182 with SIGTERM. Exit code 1
    [junit] 09/05/21 18:16:38 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200905211816_0001/attempt_200905211816_0001_m_000000_0/output/file.out
in any of the configured local directories
    [junit] 09/05/21 18:16:38 INFO mapred.JobInProgress: Task 'attempt_200905211816_0001_m_000000_0'
has completed task_200905211816_0001_m_000000 successfully.
    [junit] 09/05/21 18:16:38 INFO mapred.JobTracker: Adding task 'attempt_200905211816_0001_m_000001_0'
to tip task_200905211816_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:57298'
    [junit] 09/05/21 18:16:38 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_200905211816_0001_m_000001_0
task's state:UNASSIGNED
    [junit] 09/05/21 18:16:38 INFO mapred.TaskTracker: Trying to launch : attempt_200905211816_0001_m_000001_0
    [junit] 09/05/21 18:16:38 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_200905211816_0001_m_000001_0
    [junit] 09/05/21 18:16:38 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_200905211816_0001_m_1407654158
    [junit] 09/05/21 18:16:38 INFO mapred.JvmManager: JVM Runner jvm_200905211816_0001_m_1407654158
spawned.
    [junit] 09/05/21 18:16:39 INFO mapred.TaskTracker: JVM with ID: jvm_200905211816_0001_m_1407654158
given task: attempt_200905211816_0001_m_000001_0
    [junit] 09/05/21 18:16:39 INFO streaming.StreamJob:  map 100%  reduce 0%
    [junit] 09/05/21 18:16:39 INFO mapred.TaskTracker: attempt_200905211816_0001_m_000001_0
0.0% 
    [junit] 09/05/21 18:16:39 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/21 18:16:39 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/21 18:16:39 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out/_temporary	dst=null	perm=null
    [junit] 09/05/21 18:16:39 INFO mapred.TaskTracker: attempt_200905211816_0001_m_000001_0
0.0% cleanup > map
    [junit] 09/05/21 18:16:39 INFO mapred.TaskTracker: Task attempt_200905211816_0001_m_000001_0
is done.
    [junit] 09/05/21 18:16:39 INFO mapred.TaskTracker: reported output size for attempt_200905211816_0001_m_000001_0
 was 0
    [junit] 09/05/21 18:16:39 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/05/21 18:16:39 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/21 18:16:39 INFO util.ProcessTree: Killing all processes in the process
group 31272 with SIGTERM. Exit code 1
    [junit] 09/05/21 18:16:41 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/21 18:16:41 INFO util.ProcessTree: Killing process group31182 with SIGKILL.
Exit code 1
    [junit] 09/05/21 18:16:41 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/21 18:16:41 INFO mapred.JvmManager: JVM : jvm_200905211816_0001_m_277980030
exited. Number of tasks it ran: 1
    [junit] 09/05/21 18:16:41 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200905211816_0001/attempt_200905211816_0001_m_000001_0/output/file.out
in any of the configured local directories
    [junit] 09/05/21 18:16:41 INFO mapred.JobInProgress: Task 'attempt_200905211816_0001_m_000001_0'
has completed task_200905211816_0001_m_000001 successfully.
    [junit] 09/05/21 18:16:41 INFO mapred.JobInProgress: Job job_200905211816_0001 has completed
successfully.
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /testing/out/_logs/history/localhost_1242929775283_job_200905211816_0001_hudson_streamjob7305859837258310529.jar.
blk_4621926812198321375_1008
    [junit] 09/05/21 18:16:41 INFO datanode.DataNode: Receiving block blk_4621926812198321375_1008
src: /127.0.0.1:56278 dest: /127.0.0.1:45260
    [junit] 09/05/21 18:16:41 INFO datanode.DataNode: Receiving block blk_4621926812198321375_1008
src: /127.0.0.1:59424 dest: /127.0.0.1:50620
    [junit] 09/05/21 18:16:41 INFO DataNode.clienttrace: src: /127.0.0.1:59424, dest: /127.0.0.1:50620,
bytes: 4676, op: HDFS_WRITE, cliID: DFSClient_-491283925, offset: 0, srvID: DS-1207334634-67.195.138.9-50620-1242929775040,
blockid: blk_4621926812198321375_1008, duration: 1551438
    [junit] 09/05/21 18:16:41 INFO datanode.DataNode: PacketResponder 0 for block blk_4621926812198321375_1008
terminating
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50620 is added to blk_4621926812198321375_1008 size 4676
    [junit] 09/05/21 18:16:41 INFO DataNode.clienttrace: src: /127.0.0.1:56278, dest: /127.0.0.1:45260,
bytes: 4676, op: HDFS_WRITE, cliID: DFSClient_-491283925, offset: 0, srvID: DS-1787900321-67.195.138.9-45260-1242929774382,
blockid: blk_4621926812198321375_1008, duration: 2791162
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:45260 is added to blk_4621926812198321375_1008 size 4676
    [junit] 09/05/21 18:16:41 INFO datanode.DataNode: PacketResponder 1 for block blk_4621926812198321375_1008
terminating
    [junit] 09/05/21 18:16:41 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/21 18:16:41 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /testing/out/_logs/history/localhost_1242929775283_job_200905211816_0001_hudson_streamjob7305859837258310529.jar
is closed by DFSClient_-491283925
    [junit] 09/05/21 18:16:41 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/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3011590720027896268
is added to invalidSet of 127.0.0.1:50620
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3011590720027896268
is added to invalidSet of 127.0.0.1:45260
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2499218022810148086
is added to invalidSet of 127.0.0.1:45260
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2499218022810148086
is added to invalidSet of 127.0.0.1:50620
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-8652876003523198577
is added to invalidSet of 127.0.0.1:50620
    [junit] 09/05/21 18:16:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-8652876003523198577
is added to invalidSet of 127.0.0.1:45260
    [junit] 09/05/21 18:16:41 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/21 18:16:41 INFO mapred.JobTracker: Removed completed task 'attempt_200905211816_0001_m_000000_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:57298'
    [junit] 09/05/21 18:16:41 INFO mapred.JobTracker: Removed completed task 'attempt_200905211816_0001_m_000001_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:57298'
    [junit] 09/05/21 18:16:41 INFO mapred.JobTracker: Removed completed task 'attempt_200905211816_0001_m_000002_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:57298'
    [junit] 09/05/21 18:16:41 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200905211816_0001
    [junit] 09/05/21 18:16:41 INFO mapred.TaskRunner: attempt_200905211816_0001_m_000001_0
done; removing files.
    [junit] 09/05/21 18:16:41 INFO mapred.IndexCache: Map ID attempt_200905211816_0001_m_000001_0
not found in cache
    [junit] 09/05/21 18:16:41 INFO mapred.TaskRunner: attempt_200905211816_0001_m_000000_0
done; removing files.
    [junit] 09/05/21 18:16:41 INFO mapred.IndexCache: Map ID attempt_200905211816_0001_m_000000_0
not found in cache
    [junit] 09/05/21 18:16:41 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/21 18:16:41 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/user/hudson/build/contrib/streaming/test/system/job_200905211816_0001	dst=null	perm=null
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200905211816_0001
    [junit] 09/05/21 18:16:42 WARN mapred.TaskTracker: Unknown job job_200905211816_0001 being
deleted.
    [junit] 09/05/21 18:16:42 INFO streaming.StreamJob: Job complete: job_200905211816_0001
    [junit] 09/05/21 18:16:42 INFO streaming.StreamJob: Output: /testing/out
    [junit] 09/05/21 18:16:42 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/testing/out	dst=null	perm=null
    [junit] 09/05/21 18:16:42 INFO mapred.TestMiniMRWithDFS: File list[0]: hdfs://localhost:49965/testing/out/part-00000
    [junit] 09/05/21 18:16:42 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/21 18:16:42 INFO DataNode.clienttrace: src: /127.0.0.1:50620, dest: /127.0.0.1:59425,
bytes: 12, op: HDFS_READ, cliID: DFSClient_-491283925, offset: 0, srvID: DS-1207334634-67.195.138.9-50620-1242929775040,
blockid: blk_8832556030678788585_1008, duration: 269899
    [junit] 09/05/21 18:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_711443832720628485
is added to invalidSet of 127.0.0.1:45260
    [junit] 09/05/21 18:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_711443832720628485
is added to invalidSet of 127.0.0.1:50620
    [junit] 09/05/21 18:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_4621926812198321375
is added to invalidSet of 127.0.0.1:50620
    [junit] 09/05/21 18:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_4621926812198321375
is added to invalidSet of 127.0.0.1:45260
    [junit] 09/05/21 18:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_8832556030678788585
is added to invalidSet of 127.0.0.1:45260
    [junit] 09/05/21 18:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_8832556030678788585
is added to invalidSet of 127.0.0.1:50620
    [junit] 09/05/21 18:16:42 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/21 18:16:42 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/21 18:16:42 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out	dst=null	perm=null
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:39517
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping server on 39517
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 0 on 39517: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 1 on 39517: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 2 on 39517: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 3 on 39517: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server listener on 39517
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:57298
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping server on 57298
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 0 on 57298: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 1 on 57298: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 3 on 57298: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 2 on 57298: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server listener on 57298
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/05/21 18:16:42 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: Stopping infoServer
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: Stopping interTrackerServer
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping server on 34913
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 0 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 2 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server listener on 34913
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: Stopping expireTrackers
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: Stopping retirer
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 6 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: Stopped interTrackerServer
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 9 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 7 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 8 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 1 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO mapred.EagerTaskInitializationListener: Stopping Job Init
Manager thread
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 3 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO mapred.EagerTaskInitializationListener: JobInitManagerThread
interrupted.
    [junit] 09/05/21 18:16:42 INFO mapred.EagerTaskInitializationListener: Shutting down thread
pool
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 4 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 5 on 34913: exiting
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: Stopping expireLaunchingTasks
    [junit] 09/05/21 18:16:42 INFO mapred.JobTracker: stopped all jobtracker services
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping server on 54153
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 2 on 54153: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server listener on 54153
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 0 on 54153: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 1 on 54153: exiting
    [junit] 09/05/21 18:16:42 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:50620,
storageID=DS-1207334634-67.195.138.9-50620-1242929775040, infoPort=40381, ipcPort=54153):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/21 18:16:42 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/21 18:16:42 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/05/21 18:16:42 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:50620,
storageID=DS-1207334634-67.195.138.9-50620-1242929775040, infoPort=40381, ipcPort=54153):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/21 18:16:42 INFO ipc.Server: Stopping server on 54153
    [junit] 09/05/21 18:16:42 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] Shutting down DataNode 0
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping server on 33518
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server listener on 33518
    [junit] 09/05/21 18:16:42 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:45260,
storageID=DS-1787900321-67.195.138.9-45260-1242929774382, infoPort=37479, ipcPort=33518):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/21 18:16:42 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 2 on 33518: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 0 on 33518: exiting
    [junit] 09/05/21 18:16:42 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 1
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 1 on 33518: exiting
    [junit] 09/05/21 18:16:42 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/05/21 18:16:42 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:45260,
storageID=DS-1787900321-67.195.138.9-45260-1242929774382, infoPort=37479, ipcPort=33518):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/21 18:16:42 INFO ipc.Server: Stopping server on 33518
    [junit] 09/05/21 18:16:42 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/21 18:16:42 WARN namenode.DecommissionManager: Monitor interrupted: java.lang.InterruptedException:
sleep interrupted
    [junit] 09/05/21 18:16:42 INFO namenode.FSNamesystem: Number of transactions: 51 Total
time for transactions(ms): 5Number of transactions batched in Syncs: 1 Number of syncs: 34
SyncTimes(ms): 406 249 
    [junit] 09/05/21 18:16:42 WARN namenode.FSNamesystem: ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 09/05/21 18:16:42 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/21 18:16:42 INFO ipc.Server: Stopping server on 49965
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 0 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 1 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 2 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 3 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 4 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 5 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 6 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server listener on 49965
    [junit] 09/05/21 18:16:42 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 9 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 8 on 49965: exiting
    [junit] 09/05/21 18:16:42 INFO ipc.Server: IPC Server handler 7 on 49965: exiting
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 30.797 sec
    [junit] Running org.apache.hadoop.typedbytes.TestIO
    [junit] 09/05/21 18:16:43 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.249 sec
    [junit] Running org.apache.hadoop.typedbytes.TestTypedBytesWritable
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.192 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1046: The following
error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :1027: 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: 252 minutes 27 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Mime
View raw message