You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2009/03/23 16:14:38 UTC

Build failed in Hudson: Hadoop-trunk #788

See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/788/

------------------------------------------
[...truncated 297447 lines...]
    [junit] 2009-03-23 15:25:15,991 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 6 on 55190: starting
    [junit] 2009-03-23 15:25:15,992 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 9 on 55190: starting
    [junit] Starting DataNode 0 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/test/data/dfs/data/data2 
    [junit] 2009-03-23 15:25:15,997 INFO  common.Storage (DataStorage.java:recoverTransitionRead(123)) - Storage directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data1  is not formatted.
    [junit] 2009-03-23 15:25:15,997 INFO  common.Storage (DataStorage.java:recoverTransitionRead(124)) - Formatting ...
    [junit] 2009-03-23 15:25:16,001 INFO  common.Storage (DataStorage.java:recoverTransitionRead(123)) - Storage directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data2  is not formatted.
    [junit] 2009-03-23 15:25:16,001 INFO  common.Storage (DataStorage.java:recoverTransitionRead(124)) - Formatting ...
    [junit] 2009-03-23 15:25:16,030 INFO  datanode.DataNode (FSDataset.java:registerMBean(1414)) - Registered FSDatasetStatusMBean
    [junit] 2009-03-23 15:25:16,031 INFO  datanode.DataNode (DataNode.java:startDataNode(317)) - Opened info server at 60758
    [junit] 2009-03-23 15:25:16,031 INFO  datanode.DataNode (DataXceiverServer.java:<init>(74)) - Balancing bandwith is 1048576 bytes/s
    [junit] 2009-03-23 15:25:16,033 INFO  http.HttpServer (HttpServer.java:start(452)) - Jetty bound to port 56213
    [junit] 2009-03-23 15:25:16,033 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-03-23 15:25:16,109 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:56213
    [junit] 2009-03-23 15:25:16,110 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2009-03-23 15:25:16,111 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=DataNode, port=40259
    [junit] 2009-03-23 15:25:16,112 INFO  ipc.Server (Server.java:run(471)) - IPC Server Responder: starting
    [junit] 2009-03-23 15:25:16,112 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 0 on 40259: starting
    [junit] 2009-03-23 15:25:16,112 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener on 40259: starting
    [junit] 2009-03-23 15:25:16,113 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 1 on 40259: starting
    [junit] 2009-03-23 15:25:16,113 INFO  datanode.DataNode (DataNode.java:startDataNode(400)) - dnRegistration = DatanodeRegistration(vesta.apache.org:60758, storageID=, infoPort=56213, ipcPort=40259)
    [junit] 2009-03-23 15:25:16,113 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 2 on 40259: starting
    [junit] 2009-03-23 15:25:16,115 INFO  hdfs.StateChange (FSNamesystem.java:registerDatanode(2071)) - BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:60758 storage DS-1934982319-67.195.138.9-60758-1237821916114
    [junit] 2009-03-23 15:25:16,115 INFO  net.NetworkTopology (NetworkTopology.java:add(328)) - Adding a new node: /default-rack/127.0.0.1:60758
    [junit] 2009-03-23 15:25:16,117 INFO  datanode.DataNode (DataNode.java:register(548)) - New storage id DS-1934982319-67.195.138.9-60758-1237821916114 is assigned to data-node 127.0.0.1:60758
    [junit] 2009-03-23 15:25:16,118 INFO  datanode.DataNode (DataNode.java:run(1179)) - DatanodeRegistration(127.0.0.1:60758, storageID=DS-1934982319-67.195.138.9-60758-1237821916114, infoPort=56213, ipcPort=40259)In DataNode.run, data = FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/test/data/dfs/data/data2/current'} 
    [junit] Starting DataNode 1 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/test/data/dfs/data/data4 
    [junit] 2009-03-23 15:25:16,118 INFO  datanode.DataNode (DataNode.java:offerService(679)) - using BLOCKREPORT_INTERVAL of 3600000msec Initial delay: 0msec
    [junit] 2009-03-23 15:25:16,127 INFO  common.Storage (DataStorage.java:recoverTransitionRead(123)) - Storage directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data3  is not formatted.
    [junit] 2009-03-23 15:25:16,128 INFO  common.Storage (DataStorage.java:recoverTransitionRead(124)) - Formatting ...
    [junit] 2009-03-23 15:25:16,132 INFO  common.Storage (DataStorage.java:recoverTransitionRead(123)) - Storage directory http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data4  is not formatted.
    [junit] 2009-03-23 15:25:16,132 INFO  common.Storage (DataStorage.java:recoverTransitionRead(124)) - Formatting ...
    [junit] 2009-03-23 15:25:16,156 INFO  datanode.DataNode (DataNode.java:offerService(761)) - BlockReport of 0 blocks got processed in 1 msecs
    [junit] 2009-03-23 15:25:16,162 INFO  datanode.DataNode (DataNode.java:offerService(786)) - Starting Periodic block scanner.
    [junit] 2009-03-23 15:25:16,171 INFO  datanode.DataNode (FSDataset.java:registerMBean(1414)) - Registered FSDatasetStatusMBean
    [junit] 2009-03-23 15:25:16,172 INFO  datanode.DataNode (DataNode.java:startDataNode(317)) - Opened info server at 47789
    [junit] 2009-03-23 15:25:16,172 INFO  datanode.DataNode (DataXceiverServer.java:<init>(74)) - Balancing bandwith is 1048576 bytes/s
    [junit] 2009-03-23 15:25:16,174 INFO  http.HttpServer (HttpServer.java:start(452)) - Jetty bound to port 39493
    [junit] 2009-03-23 15:25:16,174 INFO  mortbay.log (?:invoke0(?)) - jetty-6.1.14
    [junit] 2009-03-23 15:25:16,244 INFO  mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:39493
    [junit] 2009-03-23 15:25:16,244 INFO  jvm.JvmMetrics (JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2009-03-23 15:25:16,246 INFO  metrics.RpcMetrics (RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=DataNode, port=48922
    [junit] 2009-03-23 15:25:16,246 INFO  ipc.Server (Server.java:run(471)) - IPC Server Responder: starting
    [junit] 2009-03-23 15:25:16,247 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 1 on 48922: starting
    [junit] 2009-03-23 15:25:16,247 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 0 on 48922: starting
    [junit] 2009-03-23 15:25:16,247 INFO  ipc.Server (Server.java:run(313)) - IPC Server listener on 48922: starting
    [junit] 2009-03-23 15:25:16,247 INFO  datanode.DataNode (DataNode.java:startDataNode(400)) - dnRegistration = DatanodeRegistration(vesta.apache.org:47789, storageID=, infoPort=39493, ipcPort=48922)
    [junit] 2009-03-23 15:25:16,247 INFO  ipc.Server (Server.java:run(934)) - IPC Server handler 2 on 48922: starting
    [junit] 2009-03-23 15:25:16,274 INFO  hdfs.StateChange (FSNamesystem.java:registerDatanode(2071)) - BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:47789 storage DS-2052225438-67.195.138.9-47789-1237821916248
    [junit] 2009-03-23 15:25:16,274 INFO  net.NetworkTopology (NetworkTopology.java:add(328)) - Adding a new node: /default-rack/127.0.0.1:47789
    [junit] 2009-03-23 15:25:16,276 INFO  datanode.DataNode (DataNode.java:register(548)) - New storage id DS-2052225438-67.195.138.9-47789-1237821916248 is assigned to data-node 127.0.0.1:47789
    [junit] 2009-03-23 15:25:16,277 INFO  datanode.DataNode (DataNode.java:run(1179)) - DatanodeRegistration(127.0.0.1:47789, storageID=DS-2052225438-67.195.138.9-47789-1237821916248, infoPort=39493, ipcPort=48922)In DataNode.run, data = FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/test/data/dfs/data/data4/current'} 
    [junit] 2009-03-23 15:25:16,277 INFO  datanode.DataNode (DataNode.java:offerService(679)) - using BLOCKREPORT_INTERVAL of 3600000msec Initial delay: 0msec
    [junit] 2009-03-23 15:25:16,316 INFO  datanode.DataNode (DataNode.java:offerService(761)) - BlockReport of 0 blocks got processed in 1 msecs
    [junit] 2009-03-23 15:25:16,317 INFO  datanode.DataNode (DataNode.java:offerService(786)) - Starting Periodic block scanner.
    [junit] 2009-03-23 15:25:16,330 INFO  FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson	ip=/127.0.0.1	cmd=create	src=/test	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 2009-03-23 15:25:16,333 INFO  hdfs.StateChange (FSNamesystem.java:allocateBlock(1476)) - BLOCK* NameSystem.allocateBlock: /test. blk_1972034158064802331_1001
    [junit] 2009-03-23 15:25:16,334 INFO  datanode.DataNode (DataXceiver.java:writeBlock(228)) - Receiving block blk_1972034158064802331_1001 src: /127.0.0.1:52058 dest: /127.0.0.1:60758
    [junit] 2009-03-23 15:25:16,336 INFO  datanode.DataNode (DataXceiver.java:writeBlock(228)) - Receiving block blk_1972034158064802331_1001 src: /127.0.0.1:33547 dest: /127.0.0.1:47789
    [junit] 2009-03-23 15:25:16,342 INFO  DataNode.clienttrace (BlockReceiver.java:lastDataNodeRun(805)) - src: /127.0.0.1:33547, dest: /127.0.0.1:47789, bytes: 4096, op: HDFS_WRITE, cliID: DFSClient_-160421265, offset: 0, srvID: DS-2052225438-67.195.138.9-47789-1237821916248, blockid: blk_1972034158064802331_1001
    [junit] 2009-03-23 15:25:16,342 INFO  datanode.DataNode (BlockReceiver.java:lastDataNodeRun(829)) - PacketResponder 0 for block blk_1972034158064802331_1001 terminating
    [junit] 2009-03-23 15:25:16,343 INFO  hdfs.StateChange (FSNamesystem.java:addStoredBlock(2998)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:47789 is added to blk_1972034158064802331_1001 size 4096
    [junit] 2009-03-23 15:25:16,343 INFO  DataNode.clienttrace (BlockReceiver.java:run(929)) - src: /127.0.0.1:52058, dest: /127.0.0.1:60758, bytes: 4096, op: HDFS_WRITE, cliID: DFSClient_-160421265, offset: 0, srvID: DS-1934982319-67.195.138.9-60758-1237821916114, blockid: blk_1972034158064802331_1001
    [junit] 2009-03-23 15:25:16,344 INFO  datanode.DataNode (BlockReceiver.java:run(993)) - PacketResponder 1 for block blk_1972034158064802331_1001 terminating
    [junit] 2009-03-23 15:25:16,344 INFO  hdfs.StateChange (FSNamesystem.java:addStoredBlock(2998)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60758 is added to blk_1972034158064802331_1001 size 4096
    [junit] 2009-03-23 15:25:16,345 INFO  hdfs.StateChange (FSNamesystem.java:allocateBlock(1476)) - BLOCK* NameSystem.allocateBlock: /test. blk_-8430342522692710799_1001
    [junit] 2009-03-23 15:25:16,346 INFO  datanode.DataNode (DataXceiver.java:writeBlock(228)) - Receiving block blk_-8430342522692710799_1001 src: /127.0.0.1:52060 dest: /127.0.0.1:60758
    [junit] 2009-03-23 15:25:16,347 INFO  datanode.DataNode (DataXceiver.java:writeBlock(228)) - Receiving block blk_-8430342522692710799_1001 src: /127.0.0.1:33549 dest: /127.0.0.1:47789
    [junit] 2009-03-23 15:25:16,349 INFO  DataNode.clienttrace (BlockReceiver.java:lastDataNodeRun(805)) - src: /127.0.0.1:33549, dest: /127.0.0.1:47789, bytes: 4096, op: HDFS_WRITE, cliID: DFSClient_-160421265, offset: 0, srvID: DS-2052225438-67.195.138.9-47789-1237821916248, blockid: blk_-8430342522692710799_1001
    [junit] 2009-03-23 15:25:16,349 INFO  datanode.DataNode (BlockReceiver.java:lastDataNodeRun(829)) - PacketResponder 0 for block blk_-8430342522692710799_1001 terminating
    [junit] 2009-03-23 15:25:16,349 INFO  hdfs.StateChange (FSNamesystem.java:addStoredBlock(2998)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:47789 is added to blk_-8430342522692710799_1001 size 4096
    [junit] 2009-03-23 15:25:16,350 INFO  DataNode.clienttrace (BlockReceiver.java:run(929)) - src: /127.0.0.1:52060, dest: /127.0.0.1:60758, bytes: 4096, op: HDFS_WRITE, cliID: DFSClient_-160421265, offset: 0, srvID: DS-1934982319-67.195.138.9-60758-1237821916114, blockid: blk_-8430342522692710799_1001
    [junit] 2009-03-23 15:25:16,350 INFO  hdfs.StateChange (FSNamesystem.java:addStoredBlock(2998)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60758 is added to blk_-8430342522692710799_1001 size 4096
    [junit] 2009-03-23 15:25:16,350 INFO  datanode.DataNode (BlockReceiver.java:run(993)) - PacketResponder 1 for block blk_-8430342522692710799_1001 terminating
    [junit] init: server=localhost;port=;service=DataNode;localVMUrl=null
    [junit] 
    [junit] Domains:
    [junit] 	Domain = JMImplementation
    [junit] 	Domain = com.sun.management
    [junit] 	Domain = hadoop
    [junit] 	Domain = java.lang
    [junit] 	Domain = java.util.logging
    [junit] 
    [junit] MBeanServer default domain = DefaultDomain
    [junit] 
    [junit] MBean count = 26
    [junit] 
    [junit] Query MBeanServer MBeans:
    [junit] hadoop services: hadoop:service=DataNode,name=DataNodeActivity-UndefinedStorageId-1264933398
    [junit] hadoop services: hadoop:service=DataNode,name=DataNodeActivity-UndefinedStorageId2117327036
    [junit] hadoop services: hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1505281722
    [junit] hadoop services: hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId396960251
    [junit] hadoop services: hadoop:service=DataNode,name=RpcActivityForPort40259
    [junit] hadoop services: hadoop:service=DataNode,name=RpcActivityForPort48922
    [junit] Info: key = bytes_written; val = 0
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-03-23 15:25:16,456 INFO  ipc.Server (Server.java:stop(1098)) - Stopping server on 48922
    [junit] 2009-03-23 15:25:16,456 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 48922: exiting
    [junit] 2009-03-23 15:25:16,457 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 48922: exiting
    [junit] 2009-03-23 15:25:16,457 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 48922: exiting
    [junit] 2009-03-23 15:25:16,457 INFO  ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
    [junit] 2009-03-23 15:25:16,457 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 48922
    [junit] 2009-03-23 15:25:16,457 WARN  datanode.DataNode (DataXceiverServer.java:run(137)) - DatanodeRegistration(127.0.0.1:47789, storageID=DS-2052225438-67.195.138.9-47789-1237821916248, infoPort=39493, ipcPort=48922):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] 2009-03-23 15:25:16,457 INFO  datanode.DataNode (DataNode.java:shutdown(587)) - Waiting for threadgroup to exit, active threads is 1
    [junit] 2009-03-23 15:25:16,458 INFO  datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
    [junit] 2009-03-23 15:25:16,459 INFO  datanode.DataNode (DataNode.java:run(1199)) - DatanodeRegistration(127.0.0.1:47789, storageID=DS-2052225438-67.195.138.9-47789-1237821916248, infoPort=39493, ipcPort=48922):Finishing DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/test/data/dfs/data/data4/current'} 
    [junit] 2009-03-23 15:25:16,459 INFO  ipc.Server (Server.java:stop(1098)) - Stopping server on 48922
    [junit] 2009-03-23 15:25:16,459 INFO  datanode.DataNode (DataNode.java:shutdown(587)) - Waiting for threadgroup to exit, active threads is 0
    [junit] Shutting down DataNode 0
    [junit] 2009-03-23 15:25:16,560 INFO  ipc.Server (Server.java:stop(1098)) - Stopping server on 40259
    [junit] 2009-03-23 15:25:16,561 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 40259: exiting
    [junit] 2009-03-23 15:25:16,561 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 40259: exiting
    [junit] 2009-03-23 15:25:16,561 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 40259: exiting
    [junit] 2009-03-23 15:25:16,561 WARN  datanode.DataNode (DataXceiverServer.java:run(137)) - DatanodeRegistration(127.0.0.1:60758, storageID=DS-1934982319-67.195.138.9-60758-1237821916114, infoPort=56213, ipcPort=40259):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] 2009-03-23 15:25:16,561 INFO  datanode.DataNode (DataNode.java:shutdown(587)) - Waiting for threadgroup to exit, active threads is 1
    [junit] 2009-03-23 15:25:16,561 INFO  ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
    [junit] 2009-03-23 15:25:16,561 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 40259
    [junit] 2009-03-23 15:25:16,562 INFO  datanode.DataBlockScanner (DataBlockScanner.java:run(603)) - Exiting DataBlockScanner thread.
    [junit] 2009-03-23 15:25:16,563 INFO  datanode.DataNode (DataNode.java:run(1199)) - DatanodeRegistration(127.0.0.1:60758, storageID=DS-1934982319-67.195.138.9-60758-1237821916114, infoPort=56213, ipcPort=40259):Finishing DataNode in: FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-trunk/trunk/build/test/data/dfs/data/data2/current'} 
    [junit] 2009-03-23 15:25:16,563 INFO  ipc.Server (Server.java:stop(1098)) - Stopping server on 40259
    [junit] 2009-03-23 15:25:16,563 INFO  datanode.DataNode (DataNode.java:shutdown(587)) - Waiting for threadgroup to exit, active threads is 0
    [junit] 2009-03-23 15:25:16,665 WARN  namenode.FSNamesystem (FSNamesystem.java:run(2346)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 2009-03-23 15:25:16,665 WARN  namenode.DecommissionManager (DecommissionManager.java:run(67)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
    [junit] 2009-03-23 15:25:16,665 INFO  namenode.FSNamesystem (FSEditLog.java:printStatistics(1044)) - Number of transactions: 3 Total time for transactions(ms): 1Number of transactions batched in Syncs: 0 Number of syncs: 2 SyncTimes(ms): 22 0 
    [junit] 2009-03-23 15:25:16,666 INFO  ipc.Server (Server.java:stop(1098)) - Stopping server on 55190
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 0 on 55190: exiting
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(536)) - Stopping IPC Server Responder
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 2 on 55190: exiting
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(352)) - Stopping IPC Server listener on 55190
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 3 on 55190: exiting
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 1 on 55190: exiting
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 9 on 55190: exiting
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 8 on 55190: exiting
    [junit] 2009-03-23 15:25:16,667 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 5 on 55190: exiting
    [junit] 2009-03-23 15:25:16,668 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 4 on 55190: exiting
    [junit] 2009-03-23 15:25:16,668 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 6 on 55190: exiting
    [junit] 2009-03-23 15:25:16,668 INFO  ipc.Server (Server.java:run(992)) - IPC Server handler 7 on 55190: exiting
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 2.99 sec
    [junit] Running org.apache.hadoop.util.TestCyclicIteration
    [junit] 
    [junit] 
    [junit] integers=[]
    [junit] map={}
    [junit] start=-1, iteration=[]
    [junit] 
    [junit] 
    [junit] integers=[0]
    [junit] map={0=0}
    [junit] start=-1, iteration=[0]
    [junit] start=0, iteration=[0]
    [junit] start=1, iteration=[0]
    [junit] 
    [junit] 
    [junit] integers=[0, 2]
    [junit] map={0=0, 2=2}
    [junit] start=-1, iteration=[0, 2]
    [junit] start=0, iteration=[2, 0]
    [junit] start=1, iteration=[2, 0]
    [junit] start=2, iteration=[0, 2]
    [junit] start=3, iteration=[0, 2]
    [junit] 
    [junit] 
    [junit] integers=[0, 2, 4]
    [junit] map={0=0, 2=2, 4=4}
    [junit] start=-1, iteration=[0, 2, 4]
    [junit] start=0, iteration=[2, 4, 0]
    [junit] start=1, iteration=[2, 4, 0]
    [junit] start=2, iteration=[4, 0, 2]
    [junit] start=3, iteration=[4, 0, 2]
    [junit] start=4, iteration=[0, 2, 4]
    [junit] start=5, iteration=[0, 2, 4]
    [junit] 
    [junit] 
    [junit] integers=[0, 2, 4, 6]
    [junit] map={0=0, 2=2, 4=4, 6=6}
    [junit] start=-1, iteration=[0, 2, 4, 6]
    [junit] start=0, iteration=[2, 4, 6, 0]
    [junit] start=1, iteration=[2, 4, 6, 0]
    [junit] start=2, iteration=[4, 6, 0, 2]
    [junit] start=3, iteration=[4, 6, 0, 2]
    [junit] start=4, iteration=[6, 0, 2, 4]
    [junit] start=5, iteration=[6, 0, 2, 4]
    [junit] start=6, iteration=[0, 2, 4, 6]
    [junit] start=7, iteration=[0, 2, 4, 6]
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.087 sec
    [junit] Running org.apache.hadoop.util.TestGenericsUtil
    [junit] 2009-03-23 15:25:17,535 WARN  conf.Configuration (Configuration.java:<clinit>(175)) - 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] 2009-03-23 15:25:17,548 WARN  util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(377)) - options parsing failed: Missing argument for option:jt
    [junit] usage: general options are:
    [junit]  -archives <paths>             comma separated archives to be unarchived
    [junit]                                on the compute machines.
    [junit]  -conf <configuration file>    specify an application configuration file
    [junit]  -D <property=value>           use value for given property
    [junit]  -files <paths>                comma separated files to be copied to the
    [junit]                                map reduce cluster
    [junit]  -fs <local|namenode:port>     specify a namenode
    [junit]  -jt <local|jobtracker:port>   specify a job tracker
    [junit]  -libjars <paths>              comma separated jar files to include in the
    [junit]                                classpath.
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.184 sec
    [junit] Running org.apache.hadoop.util.TestIndexedSort
    [junit] sortRandom seed: -5411018515350781436(org.apache.hadoop.util.QuickSort)
    [junit] testSorted seed: -6700718619101624343(org.apache.hadoop.util.QuickSort)
    [junit] testAllEqual setting min/max at 199/243(org.apache.hadoop.util.QuickSort)
    [junit] sortWritable seed: 5132276802605659297(org.apache.hadoop.util.QuickSort)
    [junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
    [junit] sortRandom seed: 6070363857780481961(org.apache.hadoop.util.HeapSort)
    [junit] testSorted seed: -948472724062047030(org.apache.hadoop.util.HeapSort)
    [junit] testAllEqual setting min/max at 37/290(org.apache.hadoop.util.HeapSort)
    [junit] sortWritable seed: 7675845987300348479(org.apache.hadoop.util.HeapSort)
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1 sec
    [junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
    [junit] 2009-03-23 15:25:19,288 INFO  util.ProcessTree (ProcessTree.java:isSetsidSupported(54)) - setsid exited with exit code 0
    [junit] 2009-03-23 15:25:19,794 INFO  util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(141)) - Root process pid: 18604
    [junit] 2009-03-23 15:25:19,865 INFO  util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(146)) - ProcessTree: [ 18607 18606 18604 ]
    [junit] 2009-03-23 15:25:26,395 INFO  util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(159)) - ProcessTree: [ 18606 18620 18604 18618 18616 18614 18612 18610 18608 ]
    [junit] 2009-03-23 15:25:26,408 INFO  util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(64)) - Shell Command exit with a non-zero exit code. This is expected as we are killing the subprocesses of the task intentionally. org.apache.hadoop.util.Shell$ExitCodeException: 
    [junit] 2009-03-23 15:25:26,408 INFO  util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:run(70)) - Exit code: 143
    [junit] 2009-03-23 15:25:26,411 INFO  util.ProcessTree (ProcessTree.java:destroyProcessGroup(160)) - Killing all processes in the process group 18604 with SIGTERM. Exit code 0
    [junit] 2009-03-23 15:25:26,501 INFO  util.TestProcfsBasedProcessTree (TestProcfsBasedProcessTree.java:testProcessTree(173)) - RogueTaskThread successfully joined.
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 7.295 sec
    [junit] Running org.apache.hadoop.util.TestReflectionUtils
    [junit] 2009-03-23 15:25:27,369 WARN  conf.Configuration (Configuration.java:<clinit>(175)) - 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: 5, Failures: 0, Errors: 0, Time elapsed: 0.579 sec
    [junit] Running org.apache.hadoop.util.TestShell
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.187 sec
    [junit] Running org.apache.hadoop.util.TestStringUtils
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.087 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/build.xml :769: Tests failed!

Total time: 191 minutes 48 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...


Hudson build is back to normal: Hadoop-trunk #789

Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/789/changes