See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/844/changes
Changes:
[shv] HADOOP-5877. Fix javac warnings in TestHDFSServerPorts, TestCheckpoint,
TestNameEditsConfig, TestStartup and TestStorageRestore. Contributed by Jakob
Homan.
[johan] HADOOP-5728. Fixed FSEditLog.printStatistics IndexOutOfBoundsException.
(Wang Xu via johan)
[ddas] HADOOP-5850. Fixes a problem to do with not being able to jobs with 0
maps/reduces. Contributed by Vinod K V.
------------------------------------------
[...truncated 511440 lines...]
[junit] 09/05/23 19:34:16 INFO datanode.DataNode: PacketResponder 0 for
block blk_-1782868949718891394_1008 terminating
[junit] 09/05/23 19:34:16 INFO DataNode.clienttrace: src: /127.0.0.1:49622,
dest: /127.0.0.1:50568, bytes: 8, op: HDFS_WRITE, cliID:
DFSClient_attempt_200905231933_0001_m_000000_0, offset: 0, srvID:
DS-929368163-67.195.138.9-50568-1243107237886, blockid:
blk_-1782868949718891394_1008, duration: 2916657
[junit] 09/05/23 19:34:16 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:47770 is added to
blk_-1782868949718891394_1008 size 8
[junit] 09/05/23 19:34:16 INFO datanode.DataNode: PacketResponder 1 for
block blk_-1782868949718891394_1008 terminating
[junit] 09/05/23 19:34:16 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:50568 is added to
blk_-1782868949718891394_1008 size 8
[junit] 09/05/23 19:34:16 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/23 19:34:16 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/testing/out/_temporary/_attempt_200905231933_0001_m_000000_0/part-00000 is
closed by DFSClient_attempt_200905231933_0001_m_000000_0
[junit] 09/05/23 19:34:16 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/23 19:34:16 INFO mapred.TaskTracker: Task
attempt_200905231933_0001_m_000000_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 09/05/23 19:34:16 INFO mapred.TaskTracker:
attempt_200905231933_0001_m_000000_0 0.0%
[junit] 09/05/23 19:34:18 INFO mapred.TaskTracker: Received commit task
action for attempt_200905231933_0001_m_000000_0
[junit] 09/05/23 19:34:18 WARN util.ProcessTree: Error executing shell
command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 09/05/23 19:34:18 INFO util.ProcessTree: Killing process group27283
with SIGKILL. Exit code 1
[junit] 09/05/23 19:34:18 INFO mapred.DefaultTaskController: Process exited
with exit code:0
[junit] 09/05/23 19:34:18 INFO mapred.JvmManager: JVM :
jvm_200905231933_0001_m_531273684 exited. Number of tasks it ran: 1
[junit] 09/05/23 19:34:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/testing/out/_temporary/_attempt_200905231933_0001_m_000000_0
dst=null perm=null
[junit] 09/05/23 19:34:18 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/23 19:34:18 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/23 19:34:18 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/23 19:34:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/testing/out/_temporary/_attempt_200905231933_0001_m_000000_0/part-00000
dst=/testing/out/part-00000 perm=hudson:supergroup:rw-r--r--
[junit] 09/05/23 19:34:18 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/23 19:34:18 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/23 19:34:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/testing/out/_temporary/_attempt_200905231933_0001_m_000000_0
dst=null perm=null
[junit] 09/05/23 19:34:18 INFO mapred.TaskTracker:
attempt_200905231933_0001_m_000000_0 0.667% Records R/W=1/1 > sort
[junit] 09/05/23 19:34:18 INFO mapred.TaskTracker: Task
attempt_200905231933_0001_m_000000_0 is done.
[junit] 09/05/23 19:34:18 INFO mapred.TaskTracker: reported output size for
attempt_200905231933_0001_m_000000_0 was 0
[junit] 09/05/23 19:34:18 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 09/05/23 19:34:19 WARN util.ProcessTree: Error executing shell
command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 09/05/23 19:34:19 INFO util.ProcessTree: Killing all processes in
the process group 27338 with SIGTERM. Exit code 1
[junit] 09/05/23 19:34:21 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_200905231933_0001/attempt_200905231933_0001_m_000000_0/output/file.out
in any of the configured local directories
[junit] 09/05/23 19:34:21 INFO mapred.JobInProgress: Task
'attempt_200905231933_0001_m_000000_0' has completed
task_200905231933_0001_m_000000 successfully.
[junit] 09/05/23 19:34:21 INFO mapred.JobTracker: Adding task
'attempt_200905231933_0001_m_000001_0' to tip task_200905231933_0001_m_000001,
for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:39374'
[junit] 09/05/23 19:34:21 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_200905231933_0001_m_000001_0 task's state:UNASSIGNED
[junit] 09/05/23 19:34:21 INFO mapred.TaskTracker: Trying to launch :
attempt_200905231933_0001_m_000001_0
[junit] 09/05/23 19:34:21 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_200905231933_0001_m_000001_0
[junit] 09/05/23 19:34:21 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_200905231933_0001_m_1534210860
[junit] 09/05/23 19:34:21 INFO mapred.JvmManager: JVM Runner
jvm_200905231933_0001_m_1534210860 spawned.
[junit] 09/05/23 19:34:22 INFO mapred.TaskTracker: JVM with ID:
jvm_200905231933_0001_m_1534210860 given task:
attempt_200905231933_0001_m_000001_0
[junit] 09/05/23 19:34:22 INFO streaming.StreamJob: map 100% reduce 0%
[junit] 09/05/23 19:34:22 INFO mapred.TaskTracker:
attempt_200905231933_0001_m_000001_0 0.0%
[junit] 09/05/23 19:34:22 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/23 19:34:22 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/23 19:34:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete src=/testing/out/_temporary dst=null
perm=null
[junit] 09/05/23 19:34:22 INFO mapred.TaskTracker:
attempt_200905231933_0001_m_000001_0 0.0% cleanup > map
[junit] 09/05/23 19:34:22 INFO mapred.TaskTracker: Task
attempt_200905231933_0001_m_000001_0 is done.
[junit] 09/05/23 19:34:22 INFO mapred.TaskTracker: reported output size for
attempt_200905231933_0001_m_000001_0 was 0
[junit] 09/05/23 19:34:22 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 09/05/23 19:34:22 WARN util.ProcessTree: Error executing shell
command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 09/05/23 19:34:22 INFO util.ProcessTree: Killing all processes in
the process group 27428 with SIGTERM. Exit code 1
[junit] 09/05/23 19:34:24 WARN util.ProcessTree: Error executing shell
command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process
[junit]
[junit] 09/05/23 19:34:24 INFO util.ProcessTree: Killing process group27338
with SIGKILL. Exit code 1
[junit] 09/05/23 19:34:24 INFO mapred.DefaultTaskController: Process exited
with exit code:0
[junit] 09/05/23 19:34:24 INFO mapred.JvmManager: JVM :
jvm_200905231933_0001_m_-389190671 exited. Number of tasks it ran: 1
[junit] 09/05/23 19:34:24 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_200905231933_0001/attempt_200905231933_0001_m_000001_0/output/file.out
in any of the configured local directories
[junit] 09/05/23 19:34:24 INFO mapred.JobInProgress: Task
'attempt_200905231933_0001_m_000001_0' has completed
task_200905231933_0001_m_000001 successfully.
[junit] 09/05/23 19:34:24 INFO mapred.JobInProgress: Job
job_200905231933_0001 has completed successfully.
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/testing/out/_logs/history/localhost_1243107238184_job_200905231933_0001_hudson_streamjob3798484171641868901.jar.
blk_7309234729955106126_1008
[junit] 09/05/23 19:34:24 INFO datanode.DataNode: Receiving block
blk_7309234729955106126_1008 src: /127.0.0.1:39031 dest: /127.0.0.1:47770
[junit] 09/05/23 19:34:24 INFO datanode.DataNode: Receiving block
blk_7309234729955106126_1008 src: /127.0.0.1:49630 dest: /127.0.0.1:50568
[junit] 09/05/23 19:34:24 INFO DataNode.clienttrace: src: /127.0.0.1:49630,
dest: /127.0.0.1:50568, bytes: 4676, op: HDFS_WRITE, cliID:
DFSClient_516981703, offset: 0, srvID:
DS-929368163-67.195.138.9-50568-1243107237886, blockid:
blk_7309234729955106126_1008, duration: 2263831
[junit] 09/05/23 19:34:24 INFO datanode.DataNode: PacketResponder 0 for
block blk_7309234729955106126_1008 terminating
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:50568 is added to
blk_7309234729955106126_1008 size 4676
[junit] 09/05/23 19:34:24 INFO DataNode.clienttrace: src: /127.0.0.1:39031,
dest: /127.0.0.1:47770, bytes: 4676, op: HDFS_WRITE, cliID:
DFSClient_516981703, offset: 0, srvID:
DS-1592949193-67.195.138.9-47770-1243107237257, blockid:
blk_7309234729955106126_1008, duration: 3324969
[junit] 09/05/23 19:34:24 INFO datanode.DataNode: PacketResponder 1 for
block blk_7309234729955106126_1008 terminating
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:47770 is added to
blk_7309234729955106126_1008 size 4676
[junit] 09/05/23 19:34:24 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/23 19:34:24 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/testing/out/_logs/history/localhost_1243107238184_job_200905231933_0001_hudson_streamjob3798484171641868901.jar
is closed by DFSClient_516981703
[junit] 09/05/23 19:34:24 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/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_475290470390155249 is added to invalidSet of
127.0.0.1:50568
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_475290470390155249 is added to invalidSet of
127.0.0.1:47770
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_2717389646736566184 is added to invalidSet of
127.0.0.1:47770
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_2717389646736566184 is added to invalidSet of
127.0.0.1:50568
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-9082134695233722002 is added to invalidSet of
127.0.0.1:50568
[junit] 09/05/23 19:34:24 INFO mapred.JobTracker: Removed completed task
'attempt_200905231933_0001_m_000000_0' from
'tracker_host1.foo.com:localhost/127.0.0.1:39374'
[junit] 09/05/23 19:34:24 INFO mapred.JobTracker: Removed completed task
'attempt_200905231933_0001_m_000001_0' from
'tracker_host1.foo.com:localhost/127.0.0.1:39374'
[junit] 09/05/23 19:34:24 INFO mapred.JobTracker: Removed completed task
'attempt_200905231933_0001_m_000002_0' from
'tracker_host1.foo.com:localhost/127.0.0.1:39374'
[junit] 09/05/23 19:34:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-9082134695233722002 is added to invalidSet of
127.0.0.1:47770
[junit] 09/05/23 19:34:24 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/23 19:34:24 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_200905231933_0001
[junit] 09/05/23 19:34:24 INFO mapred.TaskRunner:
attempt_200905231933_0001_m_000000_0 done; removing files.
[junit] 09/05/23 19:34:24 INFO mapred.IndexCache: Map ID
attempt_200905231933_0001_m_000000_0 not found in cache
[junit] 09/05/23 19:34:24 INFO mapred.TaskRunner:
attempt_200905231933_0001_m_000001_0 done; removing files.
[junit] 09/05/23 19:34:24 INFO mapred.IndexCache: Map ID
attempt_200905231933_0001_m_000001_0 not found in cache
[junit] 09/05/23 19:34:24 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/23 19:34:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/user/hudson/build/contrib/streaming/test/system/job_200905231933_0001
dst=null perm=null
[junit] 09/05/23 19:34:25 INFO streaming.StreamJob: map 100% reduce 100%
[junit] 09/05/23 19:34:25 INFO streaming.StreamJob: Job complete:
job_200905231933_0001
[junit] 09/05/23 19:34:25 INFO streaming.StreamJob: Output: /testing/out
[junit] 09/05/23 19:34:25 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus src=/testing/out dst=null
perm=null
[junit] 09/05/23 19:34:25 INFO mapred.TestMiniMRWithDFS: File list[0]:
hdfs://localhost:35586/testing/out/part-00000
[junit] 09/05/23 19:34:25 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/23 19:34:25 INFO DataNode.clienttrace: src: /127.0.0.1:50568,
dest: /127.0.0.1:49631, bytes: 12, op: HDFS_READ, cliID: DFSClient_516981703,
offset: 0, srvID: DS-929368163-67.195.138.9-50568-1243107237886, blockid:
blk_-1782868949718891394_1008, duration: 287728
[junit] 09/05/23 19:34:25 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-475346729069164352 is added to invalidSet of
127.0.0.1:47770
[junit] 09/05/23 19:34:25 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-475346729069164352 is added to invalidSet of
127.0.0.1:50568
[junit] 09/05/23 19:34:25 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7309234729955106126 is added to invalidSet of
127.0.0.1:50568
[junit] 09/05/23 19:34:25 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7309234729955106126 is added to invalidSet of
127.0.0.1:47770
[junit] 09/05/23 19:34:25 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-1782868949718891394 is added to invalidSet of
127.0.0.1:47770
[junit] 09/05/23 19:34:25 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-1782868949718891394 is added to invalidSet of
127.0.0.1:50568
[junit] 09/05/23 19:34:25 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/23 19:34:25 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/23 19:34:25 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete src=/testing/out dst=null
perm=null
[junit] 09/05/23 19:34:25 INFO mapred.TaskTracker: Shutting down:
Map-events fetcher for all reduce tasks on
tracker_host0.foo.com:localhost/127.0.0.1:58877
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping server on 58877
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 0 on 58877:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 2 on 58877:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 1 on 58877:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server listener on
58877
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 3 on 58877:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server Responder
[junit] 09/05/23 19:34:25 INFO mapred.TaskTracker: Shutting down
StatusHttpServer
[junit] 09/05/23 19:34:25 ERROR mapred.TaskTracker: Caught exception:
java.io.IOException: Call to localhost/127.0.0.1:45839 failed on local
exception: java.nio.channels.ClosedChannelException
[junit] at org.apache.hadoop.ipc.Client.wrapException(Client.java:778)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:746)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:220)
[junit] at org.apache.hadoop.mapred.$Proxy6.heartbeat(Unknown Source)
[junit] at
org.apache.hadoop.mapred.TaskTracker.transmitHeartBeat(TaskTracker.java:1263)
[junit] at
org.apache.hadoop.mapred.TaskTracker.offerService(TaskTracker.java:1085)
[junit] at
org.apache.hadoop.mapred.TaskTracker.run(TaskTracker.java:1808)
[junit] at
org.apache.hadoop.mapred.MiniMRCluster$TaskTrackerRunner.run(MiniMRCluster.java:193)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] Caused by: java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.ensureReadOpen(SocketChannelImpl.java:113)
[junit] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:156)
[junit] at
org.apache.hadoop.net.SocketInputStream$Reader.performIO(SocketInputStream.java:55)
[junit] at
org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at
org.apache.hadoop.net.SocketInputStream.read(SocketInputStream.java:155)
[junit] at
org.apache.hadoop.net.SocketInputStream.read(SocketInputStream.java:128)
[junit] at java.io.FilterInputStream.read(FilterInputStream.java:116)
[junit] at
org.apache.hadoop.ipc.Client$Connection$PingInputStream.read(Client.java:280)
[junit] at
java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
[junit] at
java.io.BufferedInputStream.read(BufferedInputStream.java:237)
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:371)
[junit] at
org.apache.hadoop.ipc.Client$Connection.receiveResponse(Client.java:505)
[junit] at org.apache.hadoop.ipc.Client$Connection.run(Client.java:450)
[junit]
[junit] 09/05/23 19:34:25 INFO mapred.TaskTracker: Shutting down:
Map-events fetcher for all reduce tasks on
tracker_host1.foo.com:localhost/127.0.0.1:39374
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping server on 39374
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 0 on 39374:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 1 on 39374:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 3 on 39374:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 2 on 39374:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server listener on
39374
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server Responder
[junit] 09/05/23 19:34:25 INFO mapred.TaskTracker: Shutting down
StatusHttpServer
[junit] 09/05/23 19:34:25 INFO mapred.TaskTracker: Interrupted. Closing
down.
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: Stopping infoServer
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: Stopping
interTrackerServer
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping server on 45839
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 0 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server Responder
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: Stopping expireTrackers
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: Stopped interTrackerServer
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server listener on
45839
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 6 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 9 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 1 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 8 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 7 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 5 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 4 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 3 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: Stopping retirer
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 2 on 45839:
exiting
[junit] 09/05/23 19:34:25 INFO mapred.EagerTaskInitializationListener:
Stopping Job Init Manager thread
[junit] 09/05/23 19:34:25 INFO mapred.EagerTaskInitializationListener:
JobInitManagerThread interrupted.
[junit] 09/05/23 19:34:25 INFO mapred.EagerTaskInitializationListener:
Shutting down thread pool
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: Stopping
expireLaunchingTasks
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 09/05/23 19:34:25 INFO mapred.JobTracker: stopped all jobtracker
services
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping server on 53001
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 0 on 53001:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 1 on 53001:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 2 on 53001:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server listener on
53001
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server Responder
[junit] 09/05/23 19:34:25 WARN datanode.DataNode:
DatanodeRegistration(127.0.0.1:50568,
storageID=DS-929368163-67.195.138.9-50568-1243107237886, infoPort=45313,
ipcPort=53001):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/23 19:34:25 INFO datanode.DataNode: Waiting for threadgroup
to exit, active threads is 0
[junit] 09/05/23 19:34:25 INFO datanode.DataBlockScanner: Exiting
DataBlockScanner thread.
[junit] 09/05/23 19:34:25 INFO datanode.DataNode:
DatanodeRegistration(127.0.0.1:50568,
storageID=DS-929368163-67.195.138.9-50568-1243107237886, infoPort=45313,
ipcPort=53001):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/23 19:34:25 INFO ipc.Server: Stopping server on 53001
[junit] 09/05/23 19:34:25 INFO datanode.DataNode: Waiting for threadgroup
to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping server on 37281
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 0 on 37281:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 2 on 37281:
exiting
[junit] 09/05/23 19:34:25 INFO ipc.Server: Stopping IPC Server listener on
37281
[junit] 09/05/23 19:34:25 INFO datanode.DataNode: Waiting for threadgroup
to exit, active threads is 1
[junit] 09/05/23 19:34:25 WARN datanode.DataNode:
DatanodeRegistration(127.0.0.1:47770,
storageID=DS-1592949193-67.195.138.9-47770-1243107237257, infoPort=54415,
ipcPort=37281):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/23 19:34:25 INFO ipc.Server: Stopping IPC Server Responder
[junit] 09/05/23 19:34:25 INFO ipc.Server: IPC Server handler 1 on 37281:
exiting
[junit] 09/05/23 19:34:26 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:50568
to delete blk_-1782868949718891394_1008 blk_475290470390155249_1003
blk_-475346729069164352_1007 blk_-9082134695233722002_1005
blk_7309234729955106126_1008 blk_2717389646736566184_1004
[junit] 09/05/23 19:34:26 INFO datanode.DataBlockScanner: Exiting
DataBlockScanner thread.
[junit] 09/05/23 19:34:26 INFO datanode.DataNode: Waiting for threadgroup
to exit, active threads is 0
[junit] 09/05/23 19:34:26 INFO datanode.DataNode:
DatanodeRegistration(127.0.0.1:47770,
storageID=DS-1592949193-67.195.138.9-47770-1243107237257, infoPort=54415,
ipcPort=37281):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/23 19:34:26 INFO ipc.Server: Stopping server on 37281
[junit] 09/05/23 19:34:26 INFO datanode.DataNode: Waiting for threadgroup
to exit, active threads is 0
[junit] 09/05/23 19:34:26 WARN namenode.DecommissionManager: Monitor
interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 09/05/23 19:34:26 WARN namenode.FSNamesystem: ReplicationMonitor
thread received InterruptedException.java.lang.InterruptedException: sleep
interrupted
[junit] 09/05/23 19:34:26 INFO namenode.FSNamesystem: Number of
transactions: 51 Total time for transactions(ms): 11Number of transactions
batched in Syncs: 1 Number of syncs: 34 SyncTimes(ms): 375 251
[junit] 09/05/23 19:34:26 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/23 19:34:26 INFO ipc.Server: Stopping server on 35586
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 0 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 1 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: Stopping IPC Server listener on
35586
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 9 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 3 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 5 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 8 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 6 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 7 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 4 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: IPC Server handler 2 on 35586:
exiting
[junit] 09/05/23 19:34:26 INFO ipc.Server: Stopping IPC Server Responder
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 31.909 sec
[junit] Running org.apache.hadoop.typedbytes.TestIO
[junit] 09/05/23 19:34:27 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.248 sec
[junit] Running org.apache.hadoop.typedbytes.TestTypedBytesWritable
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.189 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: 250 minutes 47 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...
