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 #839
Date Mon, 18 May 2009 20:40:59 GMT
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/839/

------------------------------------------
[...truncated 502113 lines...]
    [junit] 09/05/18 20:56:52 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/18 20:56:52 INFO util.ProcessTree: Killing process group3117 with SIGKILL.
Exit code 1
    [junit] 09/05/18 20:56:52 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/18 20:56:52 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/18 20:56:52 INFO mapred.JvmManager: JVM : jvm_200905182056_0001_m_-1283549413
exited. Number of tasks it ran: 1
    [junit] 09/05/18 20:56:52 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/18 20:56:52 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out/_temporary/_attempt_200905182056_0001_m_000000_0	dst=null	perm=null
    [junit] 09/05/18 20:56:52 INFO mapred.TaskTracker: attempt_200905182056_0001_m_000000_0
1.0% Records R/W=1/1
    [junit] 09/05/18 20:56:52 INFO mapred.TaskTracker: Task attempt_200905182056_0001_m_000000_0
is done.
    [junit] 09/05/18 20:56:52 INFO mapred.TaskTracker: reported output size for attempt_200905182056_0001_m_000000_0
 was 0
    [junit] 09/05/18 20:56:52 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/05/18 20:56:52 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/18 20:56:52 INFO util.ProcessTree: Killing all processes in the process
group 3172 with SIGTERM. Exit code 1
    [junit] 09/05/18 20:56:54 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200905182056_0001/attempt_200905182056_0001_m_000000_0/output/file.out
in any of the configured local directories
    [junit] 09/05/18 20:56:54 INFO mapred.JobInProgress: Task 'attempt_200905182056_0001_m_000000_0'
has completed task_200905182056_0001_m_000000 successfully.
    [junit] 09/05/18 20:56:54 INFO mapred.JobTracker: Adding task 'attempt_200905182056_0001_m_000001_0'
to tip task_200905182056_0001_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:54829'
    [junit] 09/05/18 20:56:54 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_200905182056_0001_m_000001_0
task's state:UNASSIGNED
    [junit] 09/05/18 20:56:54 INFO mapred.TaskTracker: Trying to launch : attempt_200905182056_0001_m_000001_0
    [junit] 09/05/18 20:56:54 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_200905182056_0001_m_000001_0
    [junit] 09/05/18 20:56:54 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_200905182056_0001_m_134676098
    [junit] 09/05/18 20:56:54 INFO mapred.JvmManager: JVM Runner jvm_200905182056_0001_m_134676098
spawned.
    [junit] 09/05/18 20:56:55 INFO streaming.StreamJob:  map 100%  reduce 0%
    [junit] 09/05/18 20:56:55 INFO mapred.TaskTracker: JVM with ID: jvm_200905182056_0001_m_134676098
given task: attempt_200905182056_0001_m_000001_0
    [junit] 09/05/18 20:56:55 INFO mapred.TaskTracker: attempt_200905182056_0001_m_000001_0
0.0% 
    [junit] 09/05/18 20:56: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/18 20:56: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/18 20:56: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/18 20:56:55 INFO mapred.TaskTracker: attempt_200905182056_0001_m_000001_0
0.0% cleanup
    [junit] 09/05/18 20:56:55 INFO mapred.TaskTracker: Task attempt_200905182056_0001_m_000001_0
is done.
    [junit] 09/05/18 20:56:55 INFO mapred.TaskTracker: reported output size for attempt_200905182056_0001_m_000001_0
 was 0
    [junit] 09/05/18 20:56:55 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/05/18 20:56:55 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/18 20:56:55 INFO util.ProcessTree: Killing all processes in the process
group 3262 with SIGTERM. Exit code 1
    [junit] 09/05/18 20:56:57 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/18 20:56:57 INFO util.ProcessTree: Killing process group3172 with SIGKILL.
Exit code 1
    [junit] 09/05/18 20:56:57 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/18 20:56:57 INFO mapred.JvmManager: JVM : jvm_200905182056_0001_m_1119558971
exited. Number of tasks it ran: 1
    [junit] 09/05/18 20:56:57 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_200905182056_0001/attempt_200905182056_0001_m_000001_0/output/file.out
in any of the configured local directories
    [junit] 09/05/18 20:56:57 INFO mapred.JobInProgress: Task 'attempt_200905182056_0001_m_000001_0'
has completed task_200905182056_0001_m_000001 successfully.
    [junit] 09/05/18 20:56:57 INFO mapred.JobInProgress: Job job_200905182056_0001 has completed
successfully.
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /testing/out/_logs/history/localhost_1242680191232_job_200905182056_0001_hudson_streamjob59366279102996624.jar.
blk_-5325914367423356616_1008
    [junit] 09/05/18 20:56:57 INFO datanode.DataNode: Receiving block blk_-5325914367423356616_1008
src: /127.0.0.1:53979 dest: /127.0.0.1:44758
    [junit] 09/05/18 20:56:57 INFO datanode.DataNode: Receiving block blk_-5325914367423356616_1008
src: /127.0.0.1:59586 dest: /127.0.0.1:51811
    [junit] 09/05/18 20:56:57 INFO DataNode.clienttrace: src: /127.0.0.1:59586, dest: /127.0.0.1:51811,
bytes: 4568, op: HDFS_WRITE, cliID: DFSClient_-1514095405, offset: 0, srvID: DS-82110765-67.195.138.9-51811-1242680190359,
blockid: blk_-5325914367423356616_1008, duration: 1420410
    [junit] 09/05/18 20:56:57 INFO datanode.DataNode: PacketResponder 0 for block blk_-5325914367423356616_1008
terminating
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:51811 is added to blk_-5325914367423356616_1008 size 4568
    [junit] 09/05/18 20:56:57 INFO DataNode.clienttrace: src: /127.0.0.1:53979, dest: /127.0.0.1:44758,
bytes: 4568, op: HDFS_WRITE, cliID: DFSClient_-1514095405, offset: 0, srvID: DS-1792113821-67.195.138.9-44758-1242680190984,
blockid: blk_-5325914367423356616_1008, duration: 2412298
    [junit] 09/05/18 20:56:57 INFO datanode.DataNode: PacketResponder 1 for block blk_-5325914367423356616_1008
terminating
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:44758 is added to blk_-5325914367423356616_1008 size 4568
    [junit] 09/05/18 20:56: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/18 20:56:57 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /testing/out/_logs/history/localhost_1242680191232_job_200905182056_0001_hudson_streamjob59366279102996624.jar
is closed by DFSClient_-1514095405
    [junit] 09/05/18 20:56: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/18 20:56:57 INFO mapred.JobTracker: Removed completed task 'attempt_200905182056_0001_m_000000_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:54829'
    [junit] 09/05/18 20:56:57 INFO mapred.JobTracker: Removed completed task 'attempt_200905182056_0001_m_000001_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:54829'
    [junit] 09/05/18 20:56:57 INFO mapred.JobTracker: Removed completed task 'attempt_200905182056_0001_m_000002_0'
from 'tracker_host1.foo.com:localhost/127.0.0.1:54829'
    [junit] 09/05/18 20:56:57 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200905182056_0001
    [junit] 09/05/18 20:56:57 INFO mapred.TaskRunner: attempt_200905182056_0001_m_000001_0
done; removing files.
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2438938056165278442
is added to invalidSet of 127.0.0.1:51811
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2438938056165278442
is added to invalidSet of 127.0.0.1:44758
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_489083662729203715
is added to invalidSet of 127.0.0.1:51811
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_489083662729203715
is added to invalidSet of 127.0.0.1:44758
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4938438679259624890
is added to invalidSet of 127.0.0.1:51811
    [junit] 09/05/18 20:56:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4938438679259624890
is added to invalidSet of 127.0.0.1:44758
    [junit] 09/05/18 20:56: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/18 20:56:57 INFO mapred.IndexCache: Map ID attempt_200905182056_0001_m_000001_0
not found in cache
    [junit] 09/05/18 20:56:57 INFO mapred.TaskRunner: attempt_200905182056_0001_m_000000_0
done; removing files.
    [junit] 09/05/18 20:56:57 INFO mapred.IndexCache: Map ID attempt_200905182056_0001_m_000000_0
not found in cache
    [junit] 09/05/18 20:56: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/18 20:56:57 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/user/hudson/build/contrib/streaming/test/system/job_200905182056_0001	dst=null	perm=null
    [junit] 09/05/18 20:56:58 INFO streaming.StreamJob: Job complete: job_200905182056_0001
    [junit] 09/05/18 20:56:58 INFO streaming.StreamJob: Output: /testing/out
    [junit] 09/05/18 20:56:58 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/testing/out	dst=null	perm=null
    [junit] 09/05/18 20:56:58 INFO mapred.TestMiniMRWithDFS: File list[0]: hdfs://localhost:55955/testing/out/part-00000
    [junit] 09/05/18 20:56:58 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/18 20:56:58 INFO DataNode.clienttrace: src: /127.0.0.1:44758, dest: /127.0.0.1:53981,
bytes: 12, op: HDFS_READ, cliID: DFSClient_-1514095405, offset: 0, srvID: DS-1792113821-67.195.138.9-44758-1242680190984,
blockid: blk_-1306895429785761547_1008, duration: 256450
    [junit] 09/05/18 20:56:58 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2463356580547760546
is added to invalidSet of 127.0.0.1:51811
    [junit] 09/05/18 20:56:58 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2463356580547760546
is added to invalidSet of 127.0.0.1:44758
    [junit] 09/05/18 20:56:58 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5325914367423356616
is added to invalidSet of 127.0.0.1:51811
    [junit] 09/05/18 20:56:58 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5325914367423356616
is added to invalidSet of 127.0.0.1:44758
    [junit] 09/05/18 20:56:58 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-1306895429785761547
is added to invalidSet of 127.0.0.1:51811
    [junit] 09/05/18 20:56:58 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-1306895429785761547
is added to invalidSet of 127.0.0.1:44758
    [junit] 09/05/18 20:56:58 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/18 20:56:58 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/18 20:56:58 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/testing/out	dst=null	perm=null
    [junit] 09/05/18 20:56:58 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:60525
    [junit] 09/05/18 20:56:58 INFO ipc.Server: Stopping server on 60525
    [junit] 09/05/18 20:56:58 INFO ipc.Server: IPC Server handler 3 on 60525: exiting
    [junit] 09/05/18 20:56:58 INFO ipc.Server: Stopping IPC Server listener on 60525
    [junit] 09/05/18 20:56:58 INFO ipc.Server: IPC Server handler 0 on 60525: exiting
    [junit] 09/05/18 20:56:58 INFO ipc.Server: IPC Server handler 1 on 60525: exiting
    [junit] 09/05/18 20:56:58 INFO ipc.Server: IPC Server handler 2 on 60525: exiting
    [junit] 09/05/18 20:56:58 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/05/18 20:56:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/18 20:56:59 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:44758 to delete
 blk_2463356580547760546_1007 blk_489083662729203715_1004 blk_-1306895429785761547_1008 blk_2438938056165278442_1003
blk_-4938438679259624890_1005 blk_-5325914367423356616_1008
    [junit] 09/05/18 20:57:00 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException:
kill: No such process
    [junit] 
    [junit] 09/05/18 20:57:00 INFO util.ProcessTree: Killing process group3262 with SIGKILL.
Exit code 1
    [junit] 09/05/18 20:57:00 INFO mapred.DefaultTaskController: Process exited with exit
code:0
    [junit] 09/05/18 20:57:00 INFO mapred.JvmManager: JVM : jvm_200905182056_0001_m_134676098
exited. Number of tasks it ran: 1
    [junit] 09/05/18 20:57:01 INFO datanode.DataNode: Deleting block blk_-5325914367423356616_1008
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data4/current/blk_-5325914367423356616

    [junit] 09/05/18 20:57:01 INFO datanode.DataNode: Deleting block blk_-4938438679259624890_1005
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data3/current/blk_-4938438679259624890

    [junit] 09/05/18 20:57:01 INFO datanode.DataNode: Deleting block blk_-1306895429785761547_1008
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data3/current/blk_-1306895429785761547

    [junit] 09/05/18 20:57:01 INFO datanode.DataNode: Deleting block blk_489083662729203715_1004
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data4/current/blk_489083662729203715

    [junit] 09/05/18 20:57:01 INFO datanode.DataNode: Deleting block blk_2438938056165278442_1003
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data3/current/blk_2438938056165278442

    [junit] 09/05/18 20:57:01 INFO datanode.DataNode: Deleting block blk_2463356580547760546_1007
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data4/current/blk_2463356580547760546

    [junit] 09/05/18 20:57:02 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:51811 to delete
 blk_2463356580547760546_1007 blk_489083662729203715_1004 blk_-1306895429785761547_1008 blk_2438938056165278442_1003
blk_-4938438679259624890_1005 blk_-5325914367423356616_1008
    [junit] 09/05/18 20:57:03 INFO datanode.DataNode: Deleting block blk_-5325914367423356616_1008
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data2/current/blk_-5325914367423356616

    [junit] 09/05/18 20:57:03 INFO datanode.DataNode: Deleting block blk_-4938438679259624890_1005
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current/blk_-4938438679259624890

    [junit] 09/05/18 20:57:03 INFO datanode.DataNode: Deleting block blk_-1306895429785761547_1008
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current/blk_-1306895429785761547

    [junit] 09/05/18 20:57:03 INFO datanode.DataNode: Deleting block blk_489083662729203715_1004
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data2/current/blk_489083662729203715

    [junit] 09/05/18 20:57:03 INFO datanode.DataNode: Deleting block blk_2438938056165278442_1003
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current/blk_2438938056165278442

    [junit] 09/05/18 20:57:03 INFO datanode.DataNode: Deleting block blk_2463356580547760546_1007
file http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/contrib/streaming/test/data/dfs/data/data2/current/blk_2463356580547760546

    [junit] 09/05/18 20:57:58 ERROR mapred.TaskTracker: Caught exception: java.io.IOException:
Call to localhost/127.0.0.1:35441 failed on local exception: java.nio.channels.ClosedByInterruptException
    [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.ClosedByInterruptException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:184)
    [junit] 	at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:341)
    [junit] 	at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:55)
    [junit] 	at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
    [junit] 	at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:146)
    [junit] 	at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:107)
    [junit] 	at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65)
    [junit] 	at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123)
    [junit] 	at java.io.DataOutputStream.flush(DataOutputStream.java:106)
    [junit] 	at org.apache.hadoop.ipc.Client$Connection.sendParam(Client.java:484)
    [junit] 	at org.apache.hadoop.ipc.Client.call(Client.java:724)
    [junit] 	... 7 more
    [junit] 
    [junit] 09/05/18 20:57:58 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for
all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:54829
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping server on 54829
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server listener on 54829
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/18 20:57:58 INFO mapred.TaskTracker: Shutting down StatusHttpServer
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 2 on 54829: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 3 on 54829: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 0 on 54829: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 1 on 54829: exiting
    [junit] 09/05/18 20:57:58 INFO mapred.TaskTracker: Interrupted. Closing down.
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: Stopping infoServer
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: Stopping interTrackerServer
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping server on 35441
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 3 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 9 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server listener on 35441
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: Stopped interTrackerServer
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 6 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 0 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: Stopping expireTrackers
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 2 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: Stopping retirer
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 5 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 1 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 4 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 8 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO mapred.EagerTaskInitializationListener: Stopping Job Init
Manager thread
    [junit] 09/05/18 20:57:58 INFO mapred.EagerTaskInitializationListener: JobInitManagerThread
interrupted.
    [junit] 09/05/18 20:57:58 INFO mapred.EagerTaskInitializationListener: Shutting down thread
pool
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: Stopping expireLaunchingTasks
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 7 on 35441: exiting
    [junit] 09/05/18 20:57:58 INFO mapred.JobTracker: stopped all jobtracker services
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping server on 45430
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 0 on 45430: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server listener on 45430
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 2 on 45430: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 1 on 45430: exiting
    [junit] 09/05/18 20:57:58 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:44758,
storageID=DS-1792113821-67.195.138.9-44758-1242680190984, infoPort=45684, ipcPort=45430):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/18 20:57:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/18 20:57:58 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/05/18 20:57:58 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:44758,
storageID=DS-1792113821-67.195.138.9-44758-1242680190984, infoPort=45684, ipcPort=45430):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/18 20:57:58 INFO ipc.Server: Stopping server on 45430
    [junit] 09/05/18 20:57:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] Shutting down DataNode 0
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping server on 48493
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 0 on 48493: exiting
    [junit] 09/05/18 20:57:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 1
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/18 20:57:58 INFO ipc.Server: Stopping IPC Server listener on 48493
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 2 on 48493: exiting
    [junit] 09/05/18 20:57:58 INFO ipc.Server: IPC Server handler 1 on 48493: exiting
    [junit] 09/05/18 20:57:58 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:51811,
storageID=DS-82110765-67.195.138.9-51811-1242680190359, infoPort=40569, ipcPort=48493):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/18 20:57:59 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
    [junit] 09/05/18 20:57:59 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/18 20:57:59 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:51811,
storageID=DS-82110765-67.195.138.9-51811-1242680190359, infoPort=40569, ipcPort=48493):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/18 20:57:59 INFO ipc.Server: Stopping server on 48493
    [junit] 09/05/18 20:57:59 INFO datanode.DataNode: Waiting for threadgroup to exit, active
threads is 0
    [junit] 09/05/18 20:57:59 WARN namenode.FSNamesystem: ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 09/05/18 20:57:59 WARN namenode.DecommissionManager: Monitor interrupted: java.lang.InterruptedException:
sleep interrupted
    [junit] 09/05/18 20:57: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): 318 194 
    [junit] 09/05/18 20:57: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/18 20:57:59 INFO ipc.Server: Stopping server on 55955
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 0 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 1 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: Stopping IPC Server listener on 55955
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 3 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 4 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 6 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: Stopping IPC Server Responder
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 5 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 7 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 8 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 2 on 55955: exiting
    [junit] 09/05/18 20:57:59 INFO ipc.Server: IPC Server handler 9 on 55955: exiting
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 91.997 sec
    [junit] Running org.apache.hadoop.typedbytes.TestIO
    [junit] 09/05/18 20:58: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.253 sec
    [junit] Running org.apache.hadoop.typedbytes.TestTypedBytesWritable
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.19 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: 230 minutes 39 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Mime
View raw message