See http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/392/
------------------------------------------ [...truncated 544191 lines...] [exec] [junit] 09/05/24 13:27:40 INFO mapred.TaskTracker: attempt_200905241327_0001_m_000001_0 0.0% cleanup > map [exec] [junit] 09/05/24 13:27:40 INFO mapred.TaskTracker: Task attempt_200905241327_0001_m_000001_0 is done. [exec] [junit] 09/05/24 13:27:40 INFO mapred.TaskTracker: reported output size for attempt_200905241327_0001_m_000001_0 was 0 [exec] [junit] 09/05/24 13:27:40 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [exec] [junit] 09/05/24 13:27:40 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process [exec] [junit] [exec] [junit] 09/05/24 13:27:40 INFO util.ProcessTree: Killing all processes in the process group 25535 with SIGTERM. Exit code 1 [exec] [junit] 09/05/24 13:27:42 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process [exec] [junit] [exec] [junit] 09/05/24 13:27:42 INFO util.ProcessTree: Killing process group25448 with SIGKILL. Exit code 1 [exec] [junit] 09/05/24 13:27:42 INFO mapred.DefaultTaskController: Process exited with exit code:0 [exec] [junit] 09/05/24 13:27:42 INFO mapred.JvmManager: JVM : jvm_200905241327_0001_m_932706131 exited. Number of tasks it ran: 1 [exec] [junit] 09/05/24 13:27:42 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_200905241327_0001/attempt_200905241327_0001_m_000001_0/output/file.out in any of the configured local directories [exec] [junit] 09/05/24 13:27:42 INFO mapred.JobInProgress: Task 'attempt_200905241327_0001_m_000001_0' has completed task_200905241327_0001_m_000001 successfully. [exec] [junit] 09/05/24 13:27:42 INFO mapred.JobInProgress: Job job_200905241327_0001 has completed successfully. [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /testing/out/_logs/history/localhost_1243171638364_job_200905241327_0001_hudson_streamjob4996332457387293899.jar. blk_4401585164976448455_1008 [exec] [junit] 09/05/24 13:27:42 INFO datanode.DataNode: Receiving block blk_4401585164976448455_1008 src: /127.0.0.1:58037 dest: /127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:42 INFO datanode.DataNode: Receiving block blk_4401585164976448455_1008 src: /127.0.0.1:60469 dest: /127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:42 INFO DataNode.clienttrace: src: /127.0.0.1:60469, dest: /127.0.0.1:43457, bytes: 4676, op: HDFS_WRITE, cliID: DFSClient_530990265, offset: 0, srvID: DS-1661115638-67.195.138.9-43457-1243171638127, blockid: blk_4401585164976448455_1008, duration: 1366571 [exec] [junit] 09/05/24 13:27:42 INFO datanode.DataNode: PacketResponder 0 for block blk_4401585164976448455_1008 terminating [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:43457 is added to blk_4401585164976448455_1008 size 4676 [exec] [junit] 09/05/24 13:27:42 INFO DataNode.clienttrace: src: /127.0.0.1:58037, dest: /127.0.0.1:47633, bytes: 4676, op: HDFS_WRITE, cliID: DFSClient_530990265, offset: 0, srvID: DS-1128851187-67.195.138.9-47633-1243171637530, blockid: blk_4401585164976448455_1008, duration: 2230006 [exec] [junit] 09/05/24 13:27:42 INFO datanode.DataNode: PacketResponder 1 for block blk_4401585164976448455_1008 terminating [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:47633 is added to blk_4401585164976448455_1008 size 4676 [exec] [junit] 09/05/24 13:27:42 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /testing/out/_logs/history/localhost_1243171638364_job_200905241327_0001_hudson_streamjob4996332457387293899.jar is closed by DFSClient_530990265 [exec] [junit] 09/05/24 13:27:42 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:42 INFO mapred.JobTracker: Removed completed task 'attempt_200905241327_0001_m_000000_0' from 'tracker_host0.foo.com:localhost/127.0.0.1:32962' [exec] [junit] 09/05/24 13:27:42 INFO mapred.JobTracker: Removed completed task 'attempt_200905241327_0001_m_000001_0' from 'tracker_host0.foo.com:localhost/127.0.0.1:32962' [exec] [junit] 09/05/24 13:27:42 INFO mapred.JobTracker: Removed completed task 'attempt_200905241327_0001_m_000002_0' from 'tracker_host0.foo.com:localhost/127.0.0.1:32962' [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5113834099660913212 is added to invalidSet of 127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5113834099660913212 is added to invalidSet of 127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3091411917736160876 is added to invalidSet of 127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3091411917736160876 is added to invalidSet of 127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2279462974894700564 is added to invalidSet of 127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2279462974894700564 is added to invalidSet of 127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:42 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:42 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_200905241327_0001 [exec] [junit] 09/05/24 13:27:42 INFO mapred.TaskRunner: attempt_200905241327_0001_m_000001_0 done; removing files. [exec] [junit] 09/05/24 13:27:42 INFO mapred.IndexCache: Map ID attempt_200905241327_0001_m_000001_0 not found in cache [exec] [junit] 09/05/24 13:27:42 INFO mapred.TaskRunner: attempt_200905241327_0001_m_000000_0 done; removing files. [exec] [junit] 09/05/24 13:27:42 INFO mapred.IndexCache: Map ID attempt_200905241327_0001_m_000000_0 not found in cache [exec] [junit] 09/05/24 13:27:42 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:42 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/user/hudson/build/contrib/streaming/test/system/job_200905241327_0001 dst=null perm=null [exec] [junit] 09/05/24 13:27:43 INFO streaming.StreamJob: map 100% reduce 100% [exec] [junit] 09/05/24 13:27:43 INFO streaming.StreamJob: Job complete: job_200905241327_0001 [exec] [junit] 09/05/24 13:27:43 INFO streaming.StreamJob: Output: /testing/out [exec] [junit] 09/05/24 13:27:43 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/testing/out dst=null perm=null [exec] [junit] 09/05/24 13:27:43 INFO mapred.TestMiniMRWithDFS: File list[0]: hdfs://localhost:39012/testing/out/part-00000 [exec] [junit] 09/05/24 13:27:43 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/testing/out/part-00000 dst=null perm=null [exec] [junit] 09/05/24 13:27:43 INFO DataNode.clienttrace: src: /127.0.0.1:47633, dest: /127.0.0.1:58039, bytes: 12, op: HDFS_READ, cliID: DFSClient_530990265, offset: 0, srvID: DS-1128851187-67.195.138.9-47633-1243171637530, blockid: blk_-2291793374848469371_1008, duration: 240151 [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3107743610861158211 is added to invalidSet of 127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3107743610861158211 is added to invalidSet of 127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_4401585164976448455 is added to invalidSet of 127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_4401585164976448455 is added to invalidSet of 127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2291793374848469371 is added to invalidSet of 127.0.0.1:43457 [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2291793374848469371 is added to invalidSet of 127.0.0.1:47633 [exec] [junit] 09/05/24 13:27:43 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:43 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:43 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/testing/out dst=null perm=null [exec] [junit] 09/05/24 13:27:43 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:32962 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping server on 32962 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 0 on 32962: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 1 on 32962: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 2 on 32962: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 3 on 32962: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server listener on 32962 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server Responder [exec] [junit] 09/05/24 13:27:43 INFO mapred.TaskTracker: Shutting down StatusHttpServer [exec] [junit] 09/05/24 13:27:43 INFO mapred.TaskTracker: Interrupted. Closing down. [exec] [junit] 09/05/24 13:27:43 INFO mapred.TaskTracker: Shutting down: Map-events fetcher for all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:38366 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping server on 38366 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 0 on 38366: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 1 on 38366: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 2 on 38366: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 3 on 38366: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server listener on 38366 [exec] [junit] 09/05/24 13:27:43 INFO mapred.TaskTracker: Shutting down StatusHttpServer [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server Responder [exec] [junit] 09/05/24 13:27:43 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:47633 to delete blk_3107743610861158211_1007 blk_4401585164976448455_1008 blk_-2279462974894700564_1005 blk_-2291793374848469371_1008 blk_-5113834099660913212_1003 blk_-3091411917736160876_1004 [exec] [junit] 09/05/24 13:27:43 INFO mapred.TaskTracker: Interrupted. Closing down. [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: Stopping infoServer [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: Stopping interTrackerServer [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping server on 49896 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 0 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 1 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 8 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 3 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 9 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 6 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 5 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 2 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 4 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 7 on 49896: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server listener on 49896 [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: Stopping expireTrackers [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server Responder [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: Stopped interTrackerServer [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: Stopping retirer [exec] [junit] 09/05/24 13:27:43 INFO mapred.EagerTaskInitializationListener: Stopping Job Init Manager thread [exec] [junit] 09/05/24 13:27:43 INFO mapred.EagerTaskInitializationListener: JobInitManagerThread interrupted. [exec] [junit] 09/05/24 13:27:43 INFO mapred.EagerTaskInitializationListener: Shutting down thread pool [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: Stopping expireLaunchingTasks [exec] [junit] 09/05/24 13:27:43 INFO mapred.JobTracker: stopped all jobtracker services [exec] [junit] Shutting down the Mini HDFS Cluster [exec] [junit] Shutting down DataNode 1 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping server on 54961 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 2 on 54961: exiting [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server listener on 54961 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: Stopping IPC Server Responder [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 1 on 54961: exiting [exec] [junit] 09/05/24 13:27:43 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1 [exec] [junit] 09/05/24 13:27:43 INFO ipc.Server: IPC Server handler 0 on 54961: exiting [exec] [junit] 09/05/24 13:27:43 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:43457, storageID=DS-1661115638-67.195.138.9-43457-1243171638127, infoPort=42025, ipcPort=54961):DataXceiveServer: java.nio.channels.AsynchronousCloseException [exec] [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [exec] [junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152) [exec] [junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84) [exec] [junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130) [exec] [junit] at java.lang.Thread.run(Thread.java:619) [exec] [junit] [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread. [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Deleting block blk_-5113834099660913212_1003 file http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current/blk_-5113834099660913212 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Deleting block blk_-3091411917736160876_1004 file http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data2/current/blk_-3091411917736160876 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Deleting block blk_-2291793374848469371_1008 file http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current/blk_-2291793374848469371 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Deleting block blk_-2279462974894700564_1005 file http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current/blk_-2279462974894700564 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Deleting block blk_3107743610861158211_1007 file http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data2/current/blk_3107743610861158211 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Deleting block blk_4401585164976448455_1008 file http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data2/current/blk_4401585164976448455 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:43457, storageID=DS-1661115638-67.195.138.9-43457-1243171638127, infoPort=42025, ipcPort=54961):Finishing DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/data/data4/current'} [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: Stopping server on 54961 [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0 [exec] [junit] Shutting down DataNode 0 [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: Stopping server on 42053 [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: IPC Server handler 0 on 42053: exiting [exec] [junit] 09/05/24 13:27:44 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1 [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: Stopping IPC Server Responder [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: Stopping IPC Server listener on 42053 [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: IPC Server handler 2 on 42053: exiting [exec] [junit] 09/05/24 13:27:44 INFO ipc.Server: IPC Server handler 1 on 42053: exiting [exec] [junit] 09/05/24 13:27:44 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:47633, storageID=DS-1128851187-67.195.138.9-47633-1243171637530, infoPort=49806, ipcPort=42053):DataXceiveServer: java.nio.channels.AsynchronousCloseException [exec] [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [exec] [junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152) [exec] [junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84) [exec] [junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130) [exec] [junit] at java.lang.Thread.run(Thread.java:619) [exec] [junit] [exec] [junit] 09/05/24 13:27:45 WARN util.ProcessTree: Error executing shell command org.apache.hadoop.util.Shell$ExitCodeException: kill: No such process [exec] [junit] [exec] [junit] 09/05/24 13:27:45 INFO util.ProcessTree: Killing process group25535 with SIGKILL. Exit code 1 [exec] [junit] 09/05/24 13:27:45 INFO mapred.DefaultTaskController: Process exited with exit code:0 [exec] [junit] 09/05/24 13:27:45 INFO mapred.JvmManager: JVM : jvm_200905241327_0001_m_-347672849 exited. Number of tasks it ran: 1 [exec] [junit] 09/05/24 13:27:45 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread. [exec] [junit] 09/05/24 13:27:45 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0 [exec] [junit] 09/05/24 13:27:45 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:47633, storageID=DS-1128851187-67.195.138.9-47633-1243171637530, infoPort=49806, ipcPort=42053):Finishing DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/data/data2/current'} [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: Stopping server on 42053 [exec] [junit] 09/05/24 13:27:45 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0 [exec] [junit] 09/05/24 13:27:45 WARN namenode.DecommissionManager: Monitor interrupted: java.lang.InterruptedException: sleep interrupted [exec] [junit] 09/05/24 13:27:45 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): 673 358 [exec] [junit] 09/05/24 13:27:45 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted [exec] [junit] 09/05/24 13:27:45 INFO namenode.FSNamesystem: current list of storage dirs:http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build/contrib/streaming/test/data/dfs/name1(IMAGE_AND_EDITS);/home/hudson/hudson-slave/workspace/Hadoop-Patch-vesta.apache.org/trunk/build/contrib/streaming/test/data/dfs/name2(IMAGE_AND_EDITS); [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: Stopping server on 39012 [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 1 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 0 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 2 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 5 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 8 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 4 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 3 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 6 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 9 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: IPC Server handler 7 on 39012: exiting [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: Stopping IPC Server listener on 39012 [exec] [junit] 09/05/24 13:27:45 INFO ipc.Server: Stopping IPC Server Responder [exec] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 30.473 sec [exec] [junit] Running org.apache.hadoop.typedbytes.TestIO [exec] [junit] 09/05/24 13:27:46 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 [exec] [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.197 sec [exec] [junit] Running org.apache.hadoop.typedbytes.TestTypedBytesWritable [exec] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.157 sec [exec] [exec] BUILD FAILED [exec] http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build.xml :1027: The following error occurred while executing this line: [exec] http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/src/contrib/build.xml :48: The following error occurred while executing this line: [exec] http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/src/contrib/streaming/build.xml :42: The following error occurred while executing this line: [exec] http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/src/contrib/build-contrib.xml :250: Tests failed! [exec] [exec] Total time: 21 minutes 20 seconds [exec] [exec] [exec] [exec] [exec] -1 overall. Here are the results of testing the latest attachment [exec] http://issues.apache.org/jira/secure/attachment/12408747/tasklimits-v3.patch [exec] against trunk revision 777761. [exec] [exec] +1 @author. The patch does not contain any @author tags. [exec] [exec] +1 tests included. The patch appears to include 4 new or modified tests. [exec] [exec] +1 javadoc. The javadoc tool did not generate any warning messages. [exec] [exec] +1 javac. The applied patch does not increase the total number of javac compiler warnings. [exec] [exec] +1 findbugs. The patch does not introduce any new Findbugs warnings. [exec] [exec] +1 Eclipse classpath. The patch retains Eclipse classpath integrity. [exec] [exec] -1 release audit. The applied patch generated 492 release audit warnings (more than the trunk's current 491 warnings). [exec] [exec] -1 core tests. The patch failed core unit tests. [exec] [exec] -1 contrib tests. The patch failed contrib unit tests. [exec] [exec] Test results: http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/392/testReport/ [exec] Release audit warnings: http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/392/artifact/trunk/current/releaseAuditDiffWarnings.txt [exec] Findbugs warnings: http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/392/artifact/trunk/build/test/findbugs/newPatchFindbugsWarnings.html [exec] Checkstyle results: http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/392/artifact/trunk/build/test/checkstyle-errors.html [exec] Console output: http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/392/console [exec] [exec] This message is automatically generated. [exec] [exec] [exec] ====================================================================== [exec] ====================================================================== [exec] Adding comment to Jira. [exec] ====================================================================== [exec] ====================================================================== [exec] [exec] [exec] % Total % Received % Xferd Average Speed Time Time Time Current [exec] Dload Upload Total Spent Left Speed [exec] [exec] [exec] [exec] ====================================================================== [exec] ====================================================================== [exec] Finished build. [exec] ====================================================================== [exec] ====================================================================== [exec] [exec] [exec] 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 BUILD FAILED http://hudson.zones.apache.org/hudson/job/Hadoop-Patch-vesta.apache.org/ws/trunk/build.xml :1877: exec returned: 3 Total time: 267 minutes 19 seconds Recording test results Description found: HADOOP-5170