See http://hudson.zones.apache.org/hudson/job/HBase-Patch/227/changes
Changes:
[jimk] HBASE-679 Regionserver addresses are still not right in the new tables
page
Passed all regression tests and PerformanceEvaluation running with multiple
region servers. table.jsp now displays the correct information
HRegionInfo:
- removed getTableNameFromRegionName and parseMetaRegionRow we have the
information in the meta table, just use it.
HServerInfo:
- I had originally made some changes here but removed them. The only remaining
changes are javadoc
MetaScanner:
- build region name using ZEROES instead of NINES. When you scan you need a row
name that sorts before the first row rather than after the last row.
- scan using COLUMN_FAMILY_ARRAY instead of COL_REGIONINFO_ARRAY. This way you
also get the server name and start code
- change api for MetaScannerVisitor so that processRow only gets the RowResult.
If you have the RowResult you have everything you need.
HConnectionManager:
- change listTables' MetaScannerVisitor to conform to new processRow api
HTable:
- change getStartKeys' MetaScannerVisitor to conform to new processRow api
- getRegionsInfo: use new processRow api, and get the server address out of the
RowResult, rather than relying on the one that is cached in HConnectionManager
ScannerCallable:
- make constructor public, add javadoc
HMaster
- change createTable to get the HRegionInfo out of the RowResult and compare
table name from HRegionInfo with that in the HRegionInfo of the table being
created, instead of parsing the table name out of the row
TestHTable
- modify test's MetaScannerVisitor to conform to the new processRow api
TestHRegionInfo
- remove testParse as parse method no longer exists
table.jsp
- change catch of IOException to catch Exception and print stack trace. At
least you'll be able to see why the server crashes if it does.
------------------------------------------
[...truncated 31256 lines...]
[junit] at
org.apache.hadoop.dfs.DataNode$DataXceiver.run(DataNode.java:968)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-07-19 20:59:43,358 ERROR [EMAIL PROTECTED]
dfs.DataNode$DataXceiver(999): 127.0.0.1:59541:DataXceiver:
java.io.IOException: Broken pipe
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at
sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at
org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:53)
[junit] at
org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:140)
[junit] at
org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:144)
[junit] at
org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:105)
[junit] at
java.io.BufferedOutputStream.write(BufferedOutputStream.java:105)
[junit] at java.io.DataOutputStream.write(DataOutputStream.java:90)
[junit] at
org.apache.hadoop.dfs.DataNode$BlockSender.sendChunks(DataNode.java:1774)
[junit] at
org.apache.hadoop.dfs.DataNode$BlockSender.sendBlock(DataNode.java:1813)
[junit] at
org.apache.hadoop.dfs.DataNode$DataXceiver.readBlock(DataNode.java:1039)
[junit] at
org.apache.hadoop.dfs.DataNode$DataXceiver.run(DataNode.java:968)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-07-19 20:59:43,434 INFO [main] util.TestMigrate(174):
Iterated over 9000 rows.
[junit] 2008-07-19 20:59:43,568 INFO [main] util.TestMigrate(174):
Iterated over 10000 rows.
[junit] 2008-07-19 20:59:43,697 INFO [main] util.TestMigrate(174):
Iterated over 11000 rows.
[junit] 2008-07-19 20:59:43,824 INFO [main] util.TestMigrate(174):
Iterated over 12000 rows.
[junit] 2008-07-19 20:59:44,010 DEBUG [IPC Server handler 4 on 60000]
master.ServerManager(570): Total Load: 5, Num Servers: 1, Avg Load: 5.0
[junit] 2008-07-19 20:59:44,028 INFO [main] util.TestMigrate(174):
Iterated over 13000 rows.
[junit] 2008-07-19 20:59:44,141 INFO [main] util.TestMigrate(174):
Iterated over 14000 rows.
[junit] 2008-07-19 20:59:44,297 INFO [main] util.TestMigrate(174):
Iterated over 15000 rows.
[junit] 2008-07-19 20:59:44,395 INFO [main] util.TestMigrate(174):
Iterated over 16000 rows.
[junit] 2008-07-19 20:59:44,471 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HStore(636): Added
/user/hudson/hbase/.META./1028785192/historian/mapfiles/7196617386948605394
with 9 entries, sequence id 35200, data size 897.0, file size 1.1k
[junit] 2008-07-19 20:59:44,498 INFO [main] util.TestMigrate(174):
Iterated over 17000 rows.
[junit] 2008-07-19 20:59:44,581 DEBUG [main]
client.HTable$ClientScanner(1261): Advancing forward from region REGION =>
{NAME => 'TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468',
STARTKEY => '[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENDKEY => '', ENCODED =>
1971203659, TABLE => {{NAME => 'TestUpgrade', IS_ROOT => 'false', IS_META =>
'false', FAMILIES => [{NAME => 'column_a', BLOOMFILTER => 'false', VERSIONS =>
'2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1',
IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'column_b', BLOOMFILTER
=> 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH =>
'2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-07-19 20:59:44,582 DEBUG [main] hbase.LocalHBaseCluster(253):
Shutting down HBase Cluster
[junit] 2008-07-19 20:59:44,582 INFO [main] master.HMaster(582): Cluster
shutdown requested. Starting to quiesce servers
[junit] 2008-07-19 20:59:45,018 INFO [RegionServer:0]
regionserver.HRegionServer(332): MSG_REGIONSERVER_QUIESCE
[junit] 2008-07-19 20:59:45,019 INFO [RegionServer:0.worker]
regionserver.HRegionServer$Worker(808): MSG_REGIONSERVER_QUIESCE
[junit] 2008-07-19 20:59:45,020 DEBUG [RegionServer:0.worker]
regionserver.HRegionServer(979): closing region TestUpgrade,,1199736362468
[junit] 2008-07-19 20:59:45,020 DEBUG [RegionServer:0.worker]
regionserver.HRegion(566): Compactions and cache flushes disabled for region
TestUpgrade,,1199736362468
[junit] 2008-07-19 20:59:45,020 DEBUG [RegionServer:0.worker]
regionserver.HRegion(581): Updates and scanners disabled for region
TestUpgrade,,1199736362468
[junit] 2008-07-19 20:59:45,020 DEBUG [RegionServer:0.worker]
regionserver.HRegion(596): No more active scanners for region
TestUpgrade,,1199736362468
[junit] 2008-07-19 20:59:45,020 DEBUG [RegionServer:0.worker]
regionserver.HRegion(602): No more row locks outstanding on region
TestUpgrade,,1199736362468
[junit] 2008-07-19 20:59:45,020 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1003): Started memcache flush for region
TestUpgrade,,1199736362468. Current region memcache size 0.0
[junit] 2008-07-19 20:59:45,038 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HStore(636): Added
/user/hudson/hbase/.META./1028785192/info/mapfiles/5040505316266387170 with 6
entries, sequence id 35200, data size 430.0, file size 624.0
[junit] 2008-07-19 20:59:45,038 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HRegion(1075): Finished memcache flush for region .META.,,1 in
1701ms, sequence id=35200, compaction requested=true
[junit] 2008-07-19 20:59:45,039 DEBUG [RegionServer:0.cacheFlusher]
regionserver.CompactSplitThread(119): Compaction requested for region: .META.,,1
[junit] 2008-07-19 20:59:45,039 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1075): Finished memcache flush for region
TestUpgrade,,1199736362468 in 19ms, sequence id=35201, compaction
requested=false
[junit] 2008-07-19 20:59:45,039 INFO [RegionServer:0.compactor]
regionserver.HRegion(882): starting compaction on region .META.,,1
[junit] 2008-07-19 20:59:45,040 DEBUG [RegionServer:0.worker]
regionserver.HStore(535): closed 341377241/column_b
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HStore(535): closed 341377241/column_a
[junit] 2008-07-19 20:59:45,043 INFO [RegionServer:0.worker]
regionserver.HRegion(615): closed TestUpgrade,,1199736362468
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegionServer(979): closing region TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegion(566): Compactions and cache flushes disabled for region
TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegion(581): Updates and scanners disabled for region
TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegion(596): No more active scanners for region
TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegion(602): No more row locks outstanding on region
TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1003): Started memcache flush for region
TestUpgrade,,1199736332093. Current region memcache size 0.0
[junit] 2008-07-19 20:59:45,043 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1075): Finished memcache flush for region
TestUpgrade,,1199736332093 in 0ms, sequence id=35202, compaction requested=false
[junit] 2008-07-19 20:59:45,045 DEBUG [RegionServer:0.worker]
regionserver.HStore(535): closed 1396626490/column_b
[junit] 2008-07-19 20:59:45,046 DEBUG [RegionServer:0.worker]
regionserver.HStore(535): closed 1396626490/column_a
[junit] 2008-07-19 20:59:45,046 INFO [RegionServer:0.worker]
regionserver.HRegion(615): closed TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,046 DEBUG [RegionServer:0.worker]
regionserver.HRegionServer(979): closing region TestUpgrade,[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-19 20:59:45,046 DEBUG [RegionServer:0.worker]
regionserver.HRegion(566): Compactions and cache flushes disabled for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-19 20:59:45,046 DEBUG [RegionServer:0.worker]
regionserver.HRegion(581): Updates and scanners disabled for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-19 20:59:45,046 DEBUG [RegionServer:0.worker]
regionserver.HRegion(596): No more active scanners for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-19 20:59:45,046 DEBUG [RegionServer:0.worker]
regionserver.HRegion(602): No more row locks outstanding on region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-19 20:59:45,047 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1003): Started memcache flush for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468. Current
region memcache size 0.0
[junit] 2008-07-19 20:59:45,048 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1075): Finished memcache flush for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468 in 1ms,
sequence id=35203, compaction requested=false
[junit] 2008-07-19 20:59:45,050 DEBUG [RegionServer:0.worker]
regionserver.HStore(535): closed 1971203659/column_b
[junit] 2008-07-19 20:59:45,051 DEBUG [RegionServer:0.worker]
regionserver.HStore(535): closed 1971203659/column_a
[junit] 2008-07-19 20:59:45,051 INFO [RegionServer:0.worker]
regionserver.HRegion(615): closed TestUpgrade,[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-19 20:59:45,087 DEBUG [RegionServer:0.compactor]
regionserver.HStore(780): started compaction of 3 files into
/user/hudson/hbase/.META./compaction.dir/1028785192/info/mapfiles/8623175031624719667
[junit] 2008-07-19 20:59:45,144 WARN [RegionServer:0.compactor]
regionserver.HStore(919): Exception closing reader for 1028785192/info
[junit] java.io.IOException: Stream closed
[junit] at
org.apache.hadoop.dfs.DFSClient$DFSInputStream.close(DFSClient.java:1319)
[junit] at java.io.FilterInputStream.close(FilterInputStream.java:155)
[junit] at
org.apache.hadoop.io.SequenceFile$Reader.close(SequenceFile.java:1581)
[junit] at org.apache.hadoop.io.MapFile$Reader.close(MapFile.java:577)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.closeCompactionReaders(HStore.java:917)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.compactHStoreFiles(HStore.java:910)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.compact(HStore.java:787)
[junit] at
org.apache.hadoop.hbase.regionserver.HRegion.compactStores(HRegion.java:887)
[junit] at
org.apache.hadoop.hbase.regionserver.HRegion.compactStores(HRegion.java:847)
[junit] at
org.apache.hadoop.hbase.regionserver.CompactSplitThread.run(CompactSplitThread.java:84)
[junit] 2008-07-19 20:59:45,145 WARN [RegionServer:0.compactor]
regionserver.HStore(919): Exception closing reader for 1028785192/info
[junit] java.io.IOException: Stream closed
[junit] at
org.apache.hadoop.dfs.DFSClient$DFSInputStream.close(DFSClient.java:1319)
[junit] at java.io.FilterInputStream.close(FilterInputStream.java:155)
[junit] at
org.apache.hadoop.io.SequenceFile$Reader.close(SequenceFile.java:1581)
[junit] at org.apache.hadoop.io.MapFile$Reader.close(MapFile.java:577)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.closeCompactionReaders(HStore.java:917)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.compactHStoreFiles(HStore.java:910)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.compact(HStore.java:787)
[junit] at
org.apache.hadoop.hbase.regionserver.HRegion.compactStores(HRegion.java:887)
[junit] at
org.apache.hadoop.hbase.regionserver.HRegion.compactStores(HRegion.java:847)
[junit] at
org.apache.hadoop.hbase.regionserver.CompactSplitThread.run(CompactSplitThread.java:84)
[junit] 2008-07-19 20:59:45,145 WARN [RegionServer:0.compactor]
regionserver.HStore(919): Exception closing reader for 1028785192/info
[junit] java.io.IOException: Stream closed
[junit] at
org.apache.hadoop.dfs.DFSClient$DFSInputStream.close(DFSClient.java:1319)
[junit] at java.io.FilterInputStream.close(FilterInputStream.java:155)
[junit] at
org.apache.hadoop.io.SequenceFile$Reader.close(SequenceFile.java:1581)
[junit] at org.apache.hadoop.io.MapFile$Reader.close(MapFile.java:577)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.closeCompactionReaders(HStore.java:917)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.compactHStoreFiles(HStore.java:910)
[junit] at
org.apache.hadoop.hbase.regionserver.HStore.compact(HStore.java:787)
[junit] at
org.apache.hadoop.hbase.regionserver.HRegion.compactStores(HRegion.java:887)
[junit] at
org.apache.hadoop.hbase.regionserver.HRegion.compactStores(HRegion.java:847)
[junit] at
org.apache.hadoop.hbase.regionserver.CompactSplitThread.run(CompactSplitThread.java:84)
[junit] 2008-07-19 20:59:45,245 DEBUG [RegionServer:0.compactor]
regionserver.HStore(1000): moving
/user/hudson/hbase/.META./compaction.dir/1028785192/info/mapfiles/8623175031624719667
to /user/hudson/hbase/.META./1028785192/info/mapfiles/5956727266877143341
[junit] 2008-07-19 20:59:45,460 DEBUG [RegionServer:0.compactor]
regionserver.HStore(799): Completed compaction of 1028785192/info store size is
3.8k
[junit] 2008-07-19 20:59:45,491 INFO [RegionServer:0.compactor]
regionserver.HRegion(896): compaction completed on region .META.,,1 in 0sec
[junit] 2008-07-19 20:59:45,687 INFO [RegionManager.metaScanner]
master.BaseScanner(149): RegionManager.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 127.0.0.1:59729}
[junit] 2008-07-19 20:59:45,695 DEBUG [RegionManager.metaScanner]
master.BaseScanner(175): RegionManager.metaScannerREGION => {NAME =>
'TestUpgrade,,1199736332093', STARTKEY => '', ENDKEY => '', ENCODED =>
1396626490, SPLIT => true, TABLE => {{NAME => 'TestUpgrade', IS_ROOT =>
'false', IS_META => 'false', FAMILIES => [{NAME => 'column_a', BLOOMFILTER =>
'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH =>
'2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME
=> 'column_b', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION =>
'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE
=> 'false'}]}}}, SERVER => '127.0.0.1:59729', STARTCODE => 1216501170450
[junit] 2008-07-19 20:59:45,695 WARN [RegionManager.metaScanner]
master.BaseScanner(242): Region is split but not offline:
TestUpgrade,,1199736332093
[junit] 2008-07-19 20:59:45,696 DEBUG [RegionManager.metaScanner]
master.BaseScanner(175): RegionManager.metaScannerREGION => {NAME =>
'TestUpgrade,,1199736362468', STARTKEY => '', ENDKEY => '[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENCODED => 341377241, TABLE => {{NAME =>
'TestUpgrade', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME =>
'column_a', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION =>
'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE
=> 'false'}, {NAME => 'column_b', BLOOMFILTER => 'false', VERSIONS =>
'2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1',
IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}}, SERVER => '127.0.0.1:59729',
STARTCODE => 1216501170450
[junit] 2008-07-19 20:59:45,697 DEBUG [RegionManager.metaScanner]
master.BaseScanner(175): RegionManager.metaScannerREGION => {NAME =>
'TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468', STARTKEY
=> '[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENDKEY => '', ENCODED =>
1971203659, TABLE => {{NAME => 'TestUpgrade', IS_ROOT => 'false', IS_META =>
'false', FAMILIES => [{NAME => 'column_a', BLOOMFILTER => 'false', VERSIONS =>
'2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1',
IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'column_b', BLOOMFILTER
=> 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH =>
'2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}},
SERVER => '127.0.0.1:59729', STARTCODE => 1216501170450
[junit] 2008-07-19 20:59:45,697 WARN [RegionManager.metaScanner]
master.MetaScanner(75): Scan one META region: {regionname: .META.,,1, startKey:
<>, server: 127.0.0.1:59729}
[junit] A record version mismatch occured. Expecting v0, found v32
[junit] at
org.apache.hadoop.io.VersionedWritable.readFields(VersionedWritable.java:46)
[junit] at
org.apache.hadoop.hbase.HRegionInfo.readFields(HRegionInfo.java:368)
[junit] at
org.apache.hadoop.hbase.util.Writables.getWritable(Writables.java:84)
[junit] at
org.apache.hadoop.hbase.util.Writables.getHRegionInfo(Writables.java:98)
[junit] at
org.apache.hadoop.hbase.util.Writables.getHRegionInfo(Writables.java:122)
[junit] at
org.apache.hadoop.hbase.master.BaseScanner.hasReferences(BaseScanner.java:304)
[junit] at
org.apache.hadoop.hbase.master.BaseScanner.cleanupSplits(BaseScanner.java:265)
[junit] at
org.apache.hadoop.hbase.master.BaseScanner.scanRegion(BaseScanner.java:226)
[junit] at
org.apache.hadoop.hbase.master.MetaScanner.scanOneMetaRegion(MetaScanner.java:69)
[junit] at
org.apache.hadoop.hbase.master.MetaScanner.maintenanceScan(MetaScanner.java:124)
[junit] at
org.apache.hadoop.hbase.master.BaseScanner.chore(BaseScanner.java:139)
[junit] at org.apache.hadoop.hbase.Chore.run(Chore.java:63)
[junit] 2008-07-19 20:59:45,698 INFO [RegionManager.metaScanner]
master.MetaScanner(138): all meta regions scanned
[junit] 2008-07-19 20:59:46,028 INFO [IPC Server handler 0 on 60000]
master.ServerManager(165): Region server 127.0.0.1:59729 quiesced
[junit] 2008-07-19 20:59:46,029 INFO [IPC Server handler 0 on 60000]
master.ServerManager(174): All user tables quiesced. Proceeding with shutdown
[junit] 2008-07-19 20:59:46,029 DEBUG [IPC Server handler 0 on 60000]
master.RegionManager(453): telling root scanner to stop
[junit] 2008-07-19 20:59:46,029 DEBUG [IPC Server handler 0 on 60000]
master.RegionManager(457): telling meta scanner to stop
[junit] 2008-07-19 20:59:46,030 INFO [RegionManager.rootScanner]
hbase.Chore(74): RegionManager.rootScanner exiting
[junit] 2008-07-19 20:59:46,030 DEBUG [IPC Server handler 0 on 60000]
master.RegionManager(461): meta and root scanners notified
[junit] 2008-07-19 20:59:46,030 INFO [RegionManager.metaScanner]
hbase.Chore(74): RegionManager.metaScanner exiting
[junit] 2008-07-19 20:59:46,031 INFO [RegionServer:0]
regionserver.HRegionServer(332): MSG_REGIONSERVER_STOP
[junit] 2008-07-19 20:59:46,031 DEBUG [RegionServer:0]
hbase.RegionHistorian(316): Offlined
[junit] 2008-07-19 20:59:46,032 DEBUG [RegionServer:0]
regionserver.HRegionServer(945): closing region .META.,,1
[junit] 2008-07-19 20:59:46,033 DEBUG [RegionServer:0]
regionserver.HRegion(566): Compactions and cache flushes disabled for region
.META.,,1
[junit] 2008-07-19 20:59:46,033 DEBUG [RegionServer:0]
regionserver.HRegion(581): Updates and scanners disabled for region .META.,,1
[junit] 2008-07-19 20:59:46,033 DEBUG [RegionServer:0]
regionserver.HRegion(596): No more active scanners for region .META.,,1
[junit] 2008-07-19 20:59:46,033 DEBUG [RegionServer:0]
regionserver.HRegion(602): No more row locks outstanding on region .META.,,1
[junit] 2008-07-19 20:59:46,033 DEBUG [RegionServer:0]
regionserver.HRegion(1003): Started memcache flush for region .META.,,1.
Current region memcache size 0.0
[junit] 2008-07-19 20:59:46,034 INFO [RegionServer:0.logRoller]
regionserver.LogRoller(93): LogRoller exiting.
[junit] 2008-07-19 20:59:46,037 INFO [RegionServer:0.cacheFlusher]
regionserver.Flusher(109): RegionServer:0.cacheFlusher exiting
[junit] 2008-07-19 20:59:46,037 DEBUG [RegionServer:0]
regionserver.HRegion(1075): Finished memcache flush for region .META.,,1 in
4ms, sequence id=35204, compaction requested=false
[junit] 2008-07-19 20:59:46,034 INFO [RegionServer:0.compactor]
regionserver.CompactSplitThread(112): RegionServer:0.compactor exiting
[junit] 2008-07-19 20:59:46,039 DEBUG [RegionServer:0]
regionserver.HStore(535): closed 1028785192/historian
[junit] 2008-07-19 20:59:46,040 DEBUG [RegionServer:0]
regionserver.HStore(535): closed 1028785192/info
[junit] 2008-07-19 20:59:46,040 INFO [RegionServer:0]
regionserver.HRegion(615): closed .META.,,1
[junit] 2008-07-19 20:59:46,040 DEBUG [RegionServer:0]
regionserver.HRegionServer(945): closing region -ROOT-,,0
[junit] 2008-07-19 20:59:46,040 DEBUG [RegionServer:0]
regionserver.HRegion(566): Compactions and cache flushes disabled for region
-ROOT-,,0
[junit] 2008-07-19 20:59:46,041 DEBUG [RegionServer:0]
regionserver.HRegion(581): Updates and scanners disabled for region -ROOT-,,0
[junit] 2008-07-19 20:59:46,041 DEBUG [RegionServer:0]
regionserver.HRegion(596): No more active scanners for region -ROOT-,,0
[junit] 2008-07-19 20:59:46,041 DEBUG [RegionServer:0]
regionserver.HRegion(602): No more row locks outstanding on region -ROOT-,,0
[junit] 2008-07-19 20:59:46,041 DEBUG [RegionServer:0]
regionserver.HRegion(1003): Started memcache flush for region -ROOT-,,0.
Current region memcache size 0.0
[junit] 2008-07-19 20:59:46,041 DEBUG [RegionServer:0]
regionserver.HRegion(1075): Finished memcache flush for region -ROOT-,,0 in
0ms, sequence id=35205, compaction requested=false
[junit] 2008-07-19 20:59:46,042 DEBUG [RegionServer:0]
regionserver.HStore(535): closed 70236052/info
[junit] 2008-07-19 20:59:46,042 INFO [RegionServer:0]
regionserver.HRegion(615): closed -ROOT-,,0
[junit] 2008-07-19 20:59:46,042 DEBUG [RegionServer:0]
regionserver.HLog(337): closing log writer in
hdfs://localhost:59537/user/hudson/hbase/log_127.0.0.1_1216501170450_59729
[junit] 2008-07-19 20:59:46,057 INFO [RegionServer:0.worker]
regionserver.HRegionServer$Worker(862): worker thread exiting
[junit] 2008-07-19 20:59:46,090 INFO [RegionServer:0]
regionserver.HRegionServer(477): telling master that region server is shutting
down at: 127.0.0.1:59729
[junit] 2008-07-19 20:59:46,090 INFO [IPC Server handler 3 on 60000]
master.ServerManager(524): Cancelling lease for 127.0.0.1:59729
[junit] 2008-07-19 20:59:46,090 INFO [IPC Server handler 3 on 60000]
master.ServerManager(242): Region server 127.0.0.1:59729: MSG_REPORT_EXITING --
lease cancelled
[junit] 2008-07-19 20:59:46,091 INFO [RegionServer:0]
regionserver.HRegionServer(484): stopping server at: 127.0.0.1:59729
[junit] 2008-07-19 20:59:46,091 INFO [RegionServer:0]
regionserver.HRegionServer(488): RegionServer:0 exiting
[junit] 2008-07-19 20:59:46,987 DEBUG [HMaster] master.RegionManager(453):
telling root scanner to stop
[junit] 2008-07-19 20:59:46,987 DEBUG [HMaster] master.RegionManager(457):
telling meta scanner to stop
[junit] 2008-07-19 20:59:46,987 DEBUG [HMaster] master.RegionManager(461):
meta and root scanners notified
[junit] 2008-07-19 20:59:46,987 DEBUG [HMaster] hbase.RegionHistorian(316):
Offlined
[junit] 2008-07-19 20:59:46,991 INFO [HMaster] hbase.Leases(115): HMaster
closing leases
[junit] 2008-07-19 20:59:46,991 INFO [HMaster] hbase.Leases(122): HMaster
closed leases
[junit] 2008-07-19 20:59:46,991 INFO [HMaster] master.HMaster(385):
HMaster main thread exiting
[junit] 2008-07-19 20:59:47,027 INFO [RegionServer:0.leaseChecker]
hbase.Leases(115): RegionServer:0.leaseChecker closing leases
[junit] 2008-07-19 20:59:47,027 INFO [RegionServer:0.leaseChecker]
hbase.Leases(122): RegionServer:0.leaseChecker closed leases
[junit] 2008-07-19 20:59:47,097 INFO [main] hbase.LocalHBaseCluster(282):
Shutdown HMaster 1 region server(s)
[junit] 2008-07-19 20:59:47,097 INFO [main] hbase.HBaseTestCase(614):
Shutting down FileSystem
[junit] 2008-07-19 20:59:47,767 INFO [main] hbase.HBaseTestCase(621):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2008-07-19 20:59:47,768 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=59544]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=59544]
[junit] 2008-07-19 20:59:47,771 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:59544
[junit] 2008-07-19 20:59:47,890 INFO [main] util.Container(156): Stopped
HttpContext[/static,/static]
[junit] 2008-07-19 20:59:47,891 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-19 20:59:47,971 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2008-07-19 20:59:47,971 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-19 20:59:48,887 INFO [ServerManager.leaseChecker]
hbase.Leases(115): ServerManager.leaseChecker closing leases
[junit] 2008-07-19 20:59:48,887 INFO [ServerManager.leaseChecker]
hbase.Leases(122): ServerManager.leaseChecker closed leases
[junit] Shutting down DataNode 0
[junit] 2008-07-19 20:59:48,977 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=59542]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=59542]
[junit] 2008-07-19 20:59:48,978 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:59542
[junit] 2008-07-19 20:59:49,054 INFO [main] util.Container(156): Stopped
HttpContext[/static,/static]
[junit] 2008-07-19 20:59:49,055 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-19 20:59:49,131 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2008-07-19 20:59:49,132 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-19 20:59:49,238 INFO [main] http.SocketListener(212):
Stopped SocketListener on 0.0.0.0:59538
[junit] 2008-07-19 20:59:49,321 INFO [main] util.Container(156): Stopped
HttpContext[/static,/static]
[junit] 2008-07-19 20:59:49,322 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-19 20:59:49,396 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2008-07-19 20:59:49,397 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-19 20:59:49,397 ERROR [EMAIL PROTECTED]
dfs.FSNamesystem$ResolutionMonitor(1950): java.lang.InterruptedException
[junit] at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899)
[junit] at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1934)
[junit] at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:358)
[junit] at
org.apache.hadoop.dfs.FSNamesystem$ResolutionMonitor.run(FSNamesystem.java:1927)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 42.939 sec
[junit] 2008-07-19 20:59:49,441 INFO [Thread-416]
regionserver.HRegionServer$ShutdownThread(185): Starting shutdown thread.
[junit] 2008-07-19 20:59:49,441 INFO [Thread-416]
regionserver.HRegionServer$ShutdownThread(190): Shutdown thread complete
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2008-07-19 20:59:49,966 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:160)
[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.059 sec
[junit] Running org.onelab.test.TestFilter
[junit] 2008-07-19 20:59:50,444 INFO [main] test.TestFilter(242): Checking
for false negatives
[junit] 2008-07-19 20:59:50,451 INFO [main] test.TestFilter(253): Checking
for false positives
[junit] 2008-07-19 20:59:50,451 INFO [main] test.TestFilter(260): Success!
[junit] 2008-07-19 20:59:50,452 INFO [main] test.TestFilter(224): Checking
serialization/deserialization
[junit] 2008-07-19 20:59:50,454 INFO [main] test.TestFilter(242): Checking
for false negatives
[junit] 2008-07-19 20:59:50,456 INFO [main] test.TestFilter(253): Checking
for false positives
[junit] 2008-07-19 20:59:50,457 INFO [main] test.TestFilter(260): Success!
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.07 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:389:
Tests failed!
Total time: 42 minutes 33 seconds
Recording test results
