See http://hudson.zones.apache.org/hudson/job/HBase-Patch/642/changes
Changes:
[apurtell] new package org.apache.hadoop.hbase.mapreduce
[apurtell] HBASE-1526 mapreduce fixup; commit trivial fix for HBaseTestCase
[apurtell] HBASE-1526 mapreduce fixup; put back test, deprecate mapred package
------------------------------------------
[...truncated 23419 lines...]
[junit] 2009-06-16 04:51:28,636 DEBUG [main] regionserver.Store(526): Added
hdfs://localhost:50708/user/hudson/testscanner/1967000418/info/5892965919808509604,
entries=1, sequenceid=4, memsize=189.0, filesize=441.0 to
testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,636 DEBUG [main] regionserver.HRegion(961):
Finished memcache flush of ~189.0 for region testscanner,,1245127888392 in
35ms, sequence id=4, compaction requested=false
[junit] 2009-06-16 04:51:28,640 DEBUG [main] regionserver.HRegion(436):
Closing testscanner,,1245127888392: compactions & flushes disabled
[junit] 2009-06-16 04:51:28,640 DEBUG [main] regionserver.HRegion(466):
Updates disabled for region, no outstanding scanners on
testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,640 DEBUG [main] regionserver.HRegion(473): No
more row locks outstanding on region testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,640 DEBUG [main] regionserver.Store(445):
closed info
[junit] 2009-06-16 04:51:28,641 INFO [main] regionserver.HRegion(485):
Closed testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,641 DEBUG [main] regionserver.HRegion(264):
Opening region testscanner,,1245127888392, encoded=1967000418
[junit] 2009-06-16 04:51:28,651 DEBUG [main] regionserver.Store(380):
loaded /user/hudson/testscanner/1967000418/info/5892965919808509604,
isReference=false, sequence id=4, length=441, majorCompaction=false
[junit] 2009-06-16 04:51:28,657 DEBUG [main] regionserver.Store(380):
loaded /user/hudson/testscanner/1967000418/info/9179089758498057969,
isReference=false, sequence id=2, length=708, majorCompaction=false
[junit] 2009-06-16 04:51:28,660 INFO [main] regionserver.HRegion(339):
region testscanner,,1245127888392/1967000418 available; sequence id is 5
[junit] 2009-06-16 04:51:28,719 DEBUG [main] regionserver.HRegion(884):
Started memcache flush for region testscanner,,1245127888392. Current region
memcache size 189.0
[junit] 2009-06-16 04:51:28,783 DEBUG [main] regionserver.Store(526): Added
hdfs://localhost:50708/user/hudson/testscanner/1967000418/info/7577263491514053116,
entries=1, sequenceid=6, memsize=189.0, filesize=441.0 to
testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,784 DEBUG [main] regionserver.HRegion(961):
Finished memcache flush of ~189.0 for region testscanner,,1245127888392 in
65ms, sequence id=6, compaction requested=true
[junit] 2009-06-16 04:51:28,787 DEBUG [main] regionserver.HRegion(436):
Closing testscanner,,1245127888392: compactions & flushes disabled
[junit] 2009-06-16 04:51:28,788 DEBUG [main] regionserver.HRegion(466):
Updates disabled for region, no outstanding scanners on
testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,789 DEBUG [main] regionserver.HRegion(473): No
more row locks outstanding on region testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,790 DEBUG [main] regionserver.Store(445):
closed info
[junit] 2009-06-16 04:51:28,791 INFO [main] regionserver.HRegion(485):
Closed testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,792 DEBUG [main] regionserver.HRegion(264):
Opening region testscanner,,1245127888392, encoded=1967000418
[junit] 2009-06-16 04:51:28,801 DEBUG [main] regionserver.Store(380):
loaded /user/hudson/testscanner/1967000418/info/5892965919808509604,
isReference=false, sequence id=4, length=441, majorCompaction=false
[junit] 2009-06-16 04:51:28,808 DEBUG [main] regionserver.Store(380):
loaded /user/hudson/testscanner/1967000418/info/7577263491514053116,
isReference=false, sequence id=6, length=441, majorCompaction=false
[junit] 2009-06-16 04:51:28,815 DEBUG [main] regionserver.Store(380):
loaded /user/hudson/testscanner/1967000418/info/9179089758498057969,
isReference=false, sequence id=2, length=708, majorCompaction=false
[junit] 2009-06-16 04:51:28,830 INFO [main] regionserver.HRegion(339):
region testscanner,,1245127888392/1967000418 available; sequence id is 7
[junit] 2009-06-16 04:51:28,834 DEBUG [main] regionserver.HRegion(436):
Closing testscanner,,1245127888392: compactions & flushes disabled
[junit] 2009-06-16 04:51:28,834 DEBUG [main] regionserver.HRegion(466):
Updates disabled for region, no outstanding scanners on
testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,835 DEBUG [main] regionserver.HRegion(473): No
more row locks outstanding on region testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,836 DEBUG [main] regionserver.Store(445):
closed info
[junit] 2009-06-16 04:51:28,837 INFO [main] regionserver.HRegion(485):
Closed testscanner,,1245127888392
[junit] 2009-06-16 04:51:28,837 DEBUG [main] regionserver.HLog(456):
closing hlog writer in
hdfs://localhost:50708/user/hudson/testscanner/1967000418/.logs
[junit] 2009-06-16 04:51:28,878 INFO [main] hbase.HBaseTestCase(597):
Shutting down FileSystem
[junit] 2009-06-16 04:51:28,879 INFO [main] hbase.HBaseTestCase(604):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-16 04:51:29,013 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@144b9ff]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:48524,
storageID=DS-1848419670-67.195.138.9-48524-1245127888277, infoPort=33146,
ipcPort=33026):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] Shutting down DataNode 0
[junit] 2009-06-16 04:51:29,117 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1be1041]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:37983,
storageID=DS-1542429253-67.195.138.9-37983-1245127887517, infoPort=37148,
ipcPort=34004):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-06-16 04:51:29,320 WARN
[org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@1f2f70a]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread
received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2009-06-16 04:51:30.202::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:30.208::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/hdfs
to /tmp/Jetty_localhost_43903_hdfs____.m23gvt/webapp
[junit] 2009-06-16 04:51:30.385::INFO: Started
selectchannelconnec...@localhost:43903
[junit] Starting DataNode 0 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2
[junit] 2009-06-16 04:51:30.863::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:30.869::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
to /tmp/Jetty_localhost_36638_datanode____psfc1r/webapp
[junit] 2009-06-16 04:51:31.039::INFO: Started
selectchannelconnec...@localhost:36638
[junit] Starting DataNode 1 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4
[junit] 2009-06-16 04:51:31.566::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:31.572::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
to /tmp/Jetty_localhost_49860_datanode____ferid0/webapp
[junit] 2009-06-16 04:51:31.737::INFO: Started
selectchannelconnec...@localhost:49860
[junit] 2009-06-16 04:51:31,956 INFO [main] regionserver.HLog(209): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=100, optionallogflushinternal=10000ms
[junit] 2009-06-16 04:51:31,968 INFO [main] regionserver.HLog(299): New
hlog /user/hudson/testscanner/1293891361/.logs/hlog.dat.1245127891956
[junit] 2009-06-16 04:51:31,969 DEBUG [main] regionserver.HRegion(264):
Opening region testscanner,,1245127891926, encoded=1293891361
[junit] 2009-06-16 04:51:31,999 INFO [main] regionserver.HRegion(339):
region testscanner,,1245127891926/1293891361 available; sequence id is 0
[junit] 2009-06-16 04:51:33,002 INFO [main] regionserver.TestScanner(373):
Added: 17576
[junit] 2009-06-16 04:51:33,003 INFO [main] regionserver.TestScanner(396):
Taking out counting scan
[junit] 2009-06-16 04:51:33,950 INFO [main] regionserver.TestScanner(410):
Found 17575 items
[junit] 2009-06-16 04:51:33,950 INFO [main] regionserver.TestScanner(396):
Taking out counting scan
[junit] 2009-06-16 04:51:33,954 INFO [main] regionserver.TestScanner(404):
Starting flush at flush index 100
[junit] 2009-06-16 04:51:33,954 DEBUG [main] regionserver.HRegion(884):
Started memcache flush for region testscanner,,1245127891926. Current region
memcache size 2.6m
[junit] 2009-06-16 04:51:34,177 DEBUG [main] regionserver.Store(526): Added
hdfs://localhost:58746/user/hudson/testscanner/1293891361/info/1273300408184304485,
entries=17576, sequenceid=17577, memsize=2.6m, filesize=691.0k to
testscanner,,1245127891926
[junit] 2009-06-16 04:51:34,179 DEBUG [main] regionserver.HRegion(961):
Finished memcache flush of ~2.6m for region testscanner,,1245127891926 in
225ms, sequence id=17577, compaction requested=false
[junit] 2009-06-16 04:51:34,179 INFO [main] regionserver.TestScanner(406):
Finishing flush
[junit] 2009-06-16 04:51:34,853 INFO [main] regionserver.TestScanner(410):
Found 17575 items
[junit] 2009-06-16 04:51:34,853 INFO [main] regionserver.TestScanner(396):
Taking out counting scan
[junit] 2009-06-16 04:51:35,004 INFO [main] regionserver.TestScanner(410):
Found 17575 items
[junit] 2009-06-16 04:51:35,004 INFO [main] regionserver.TestScanner(396):
Taking out counting scan
[junit] 2009-06-16 04:51:35,131 INFO [main] regionserver.TestScanner(404):
Starting flush at flush index 17574
[junit] 2009-06-16 04:51:35,131 INFO [main] regionserver.TestScanner(406):
Finishing flush
[junit] 2009-06-16 04:51:35,131 INFO [main] regionserver.TestScanner(410):
Found 17575 items
[junit] 2009-06-16 04:51:35,132 DEBUG [main] regionserver.HRegion(436):
Closing testscanner,,1245127891926: compactions & flushes disabled
[junit] 2009-06-16 04:51:35,132 DEBUG [main] regionserver.HRegion(466):
Updates disabled for region, no outstanding scanners on
testscanner,,1245127891926
[junit] 2009-06-16 04:51:35,132 DEBUG [main] regionserver.HRegion(473): No
more row locks outstanding on region testscanner,,1245127891926
[junit] 2009-06-16 04:51:35,132 DEBUG [main] regionserver.Store(445):
closed info
[junit] 2009-06-16 04:51:35,133 INFO [main] regionserver.HRegion(485):
Closed testscanner,,1245127891926
[junit] 2009-06-16 04:51:35,133 DEBUG [main] regionserver.HLog(456):
closing hlog writer in
hdfs://localhost:58746/user/hudson/testscanner/1293891361/.logs
[junit] 2009-06-16 04:51:35,161 INFO [main] hbase.HBaseTestCase(597):
Shutting down FileSystem
[junit] 2009-06-16 04:51:35,161 INFO [main] hbase.HBaseTestCase(604):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-16 04:51:35,269 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1c0cb76]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:36172,
storageID=DS-971477446-67.195.138.9-36172-1245127891789, infoPort=49860,
ipcPort=52471):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] Shutting down DataNode 0
[junit] 2009-06-16 04:51:35,380 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1220fd1]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:60491,
storageID=DS-51279014-67.195.138.9-60491-1245127891050, infoPort=36638,
ipcPort=47785):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-06-16 04:51:36,584 WARN
[org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@1092447]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread
received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 44.322 sec
[junit] Test org.apache.hadoop.hbase.regionserver.TestScanner FAILED
[junit] Running org.apache.hadoop.hbase.regionserver.TestStore
[junit] 2009-06-16 04:51:37,379 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testGet_FromFilesOnly/765969642/family/8559987214280378820,
entries=2, sequenceid=1245127896936, memsize=298.0, filesize=385.0 to
table,,1245127897318
[junit] 2009-06-16 04:51:37,404 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testGet_FromFilesOnly/765969642/family/7352701082463247235,
entries=2, sequenceid=1245127896937, memsize=298.0, filesize=385.0 to
table,,1245127897318
[junit] 2009-06-16 04:51:37,418 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testGet_FromFilesOnly/765969642/family/1036103773396864171,
entries=2, sequenceid=1245127896938, memsize=298.0, filesize=385.0 to
table,,1245127897318
[junit] 2009-06-16 04:51:37,487 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testGet_FromMemCacheAndFiles/734820654/family/7261121030056538587,
entries=2, sequenceid=1245127896936, memsize=298.0, filesize=385.0 to
table,,1245127897474
[junit] 2009-06-16 04:51:37,499 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testGet_FromMemCacheAndFiles/734820654/family/7434593909907870434,
entries=2, sequenceid=1245127896937, memsize=298.0, filesize=385.0 to
table,,1245127897474
[junit] 2009-06-16 04:51:37,663 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testIncrementColumnValue_UpdatingFromSF/1421902002/family/4658127124829829815,
entries=2, sequenceid=1245127896936, memsize=314.0, filesize=401.0 to
table,,1245127897638
[junit] 2009-06-16 04:51:37,711 DEBUG [main] regionserver.Store(526): Added
test/build/data/TestStore/testIncrementColumnValue_AddingNewAfterSFCheck/338304098/family/2519601743591320029,
entries=2, sequenceid=1245127896936, memsize=314.0, filesize=401.0 to
table,,1245127897696
[junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.776 sec
[junit] Running org.apache.hadoop.hbase.regionserver.TestStoreFile
[junit] 2009-06-16 04:51:39.308::INFO: Logging to STDERR via
org.mortbay.log.StdErrLog
[junit] 2009-06-16 04:51:39.356::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:39.386::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/hdfs
to /tmp/Jetty_localhost_49713_hdfs____.o3djdu/webapp
[junit] 2009-06-16 04:51:39.825::INFO: Started
selectchannelconnec...@localhost:49713
[junit] Starting DataNode 0 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2
[junit] 2009-06-16 04:51:40.360::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:40.367::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
to /tmp/Jetty_localhost_41785_datanode____h1gmda/webapp
[junit] 2009-06-16 04:51:40.598::INFO: Started
selectchannelconnec...@localhost:41785
[junit] Starting DataNode 1 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4
[junit] 2009-06-16 04:51:41.139::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:41.166::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
to /tmp/Jetty_localhost_48216_datanode____t0h8vu/webapp
[junit] 2009-06-16 04:51:41.479::INFO: Started
selectchannelconnec...@localhost:48216
[junit] 2009-06-16 04:51:41,937 INFO [main]
regionserver.TestStoreFile(164): Midkey:
mitestBasicHalfMapFiletestBasicHalfMapFile !?h?
[junit] 2009-06-16 04:51:41,947 INFO [main]
regionserver.TestStoreFile(183): First in top:
aatestBasicHalfMapFiletestBasicHalfMapFile !?h?
[junit] 2009-06-16 04:51:41,954 INFO [main]
regionserver.TestStoreFile(186): Last in top:
zztestBasicHalfMapFiletestBasicHalfMapFile !?h?
[junit] 2009-06-16 04:51:42,102 INFO [main]
regionserver.TestStoreFile(237): First top when key < bottom:
/aa/1473914524603146611
[junit] 2009-06-16 04:51:42,108 INFO [main]
regionserver.TestStoreFile(245): Last top when key < bottom:
/zz/1473914524603146611
[junit] 2009-06-16 04:51:42,399 INFO [main]
regionserver.TestStoreFile(269): First bottom when key > top:
/aa/1473914524603146611
[junit] 2009-06-16 04:51:42,406 INFO [main]
regionserver.TestStoreFile(277): Last bottom when key > top:
/zz/1473914524603146611
[junit] 2009-06-16 04:51:42,416 INFO [main] hbase.HBaseTestCase(597):
Shutting down FileSystem
[junit] 2009-06-16 04:51:42,416 INFO [main] hbase.HBaseTestCase(604):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-16 04:51:42,419 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1867df9]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:56721,
storageID=DS-278729148-67.195.138.9-56721-1245127901482, infoPort=48216,
ipcPort=51986):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] Shutting down DataNode 0
[junit] 2009-06-16 04:51:42,437 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@10ca208]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:56367,
storageID=DS-999774085-67.195.138.9-56367-1245127900603, infoPort=41785,
ipcPort=34408):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-06-16 04:51:43,639 WARN
[org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@f0b7f8]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread
received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2009-06-16 04:51:44.550::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:44.556::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/hdfs
to /tmp/Jetty_localhost_59471_hdfs____tgmpw4/webapp
[junit] 2009-06-16 04:51:44.750::INFO: Started
selectchannelconnec...@localhost:59471
[junit] Starting DataNode 0 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2
[junit] 2009-06-16 04:51:45.259::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:45.265::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
to /tmp/Jetty_localhost_41933_datanode____.nnaonx/webapp
[junit] 2009-06-16 04:51:45.442::INFO: Started
selectchannelconnec...@localhost:41933
[junit] Starting DataNode 1 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4
[junit] 2009-06-16 04:51:45.954::INFO: jetty-6.1.14
[junit] 2009-06-16 04:51:45.961::INFO: Extract
jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
to /tmp/Jetty_localhost_37699_datanode____xcwyxl/webapp
[junit] 2009-06-16 04:51:46.137::INFO: Started
selectchannelconnec...@localhost:37699
[junit] 2009-06-16 04:51:46,420 INFO [main] hbase.HBaseTestCase(597):
Shutting down FileSystem
[junit] 2009-06-16 04:51:46,421 INFO [main] hbase.HBaseTestCase(604):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-16 04:51:46,525 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@b3ae5c]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:33854,
storageID=DS-1786318118-67.195.138.9-33854-1245127906142, infoPort=37699,
ipcPort=42013):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] Shutting down DataNode 0
[junit] 2009-06-16 04:51:47,637 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1f07597]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:43470,
storageID=DS-1592532892-67.195.138.9-43470-1245127905445, infoPort=41933,
ipcPort=48608):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-06-16 04:51:48,739 WARN
[org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@191777e]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread
received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 10.649 sec
[junit] Running org.apache.hadoop.hbase.regionserver.TestStoreScanner
[junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 0.071 sec
[junit] Running
org.apache.hadoop.hbase.regionserver.TestWildcardColumnTracker
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.315 sec
[junit] Running org.apache.hadoop.hbase.util.TestBase64
[junit]
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.139 sec
[junit] Running org.apache.hadoop.hbase.util.TestBytes
[junit] AAA
[junit] CCC
[junit] EEE
[junit] AAA
[junit] BBB
[junit] CCC
[junit] DDD
[junit] http://A
[junit] http://]
[junit] http://z
[junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 0.09 sec
[junit] Running org.apache.hadoop.hbase.util.TestKeying
[junit] Original url
http://abc:[email protected]/index.html?query=something#middle, Transformed
url r:http://abc:[email protected]/index.html?query=something#middle
[junit] Original url file:///usr/bin/java, Transformed url
file:///usr/bin/java
[junit] Original url dns:www.powerset.com, Transformed url
dns:www.powerset.com
[junit] Original url dns://dns.powerset.com/www.powerset.com, Transformed
url r:dns://com.powerset.dns/www.powerset.com
[junit] Original url http://one.two.three/index.html, Transformed url
r:http://three.two.one/index.html
[junit] Original url https://one.two.three:9443/index.html, Transformed url
r:https://three.two.one:9443/index.html
[junit] Original url ftp://one.two.three/index.html, Transformed url
r:ftp://three.two.one/index.html
[junit] Original url filename, Transformed url filename
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.079 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-06-16 04:51:51,470 INFO [main] util.TestRootPath(60): Got
expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not contain a scheme
[junit] at
org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:212)
[junit] at
org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at junit.framework.TestCase.runTest(TestCase.java:154)
[junit] at junit.framework.TestCase.runBare(TestCase.java:127)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:124)
[junit] at junit.framework.TestResult.run(TestResult.java:109)
[junit] at junit.framework.TestCase.run(TestCase.java:118)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
[junit] at junit.framework.TestSuite.run(TestSuite.java:203)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.071 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :460:
Tests failed!
Total time: 34 minutes 16 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...
