See http://hudson.zones.apache.org/hudson/job/HBase-Patch/220/changes
Changes:
[jimk] HBASE-737 Scanner: every cell in a row has the same timestamp
Changed InternalScanner API:
from
{code}
boolean next(HStoreKey, SortedMap<byte[], byte>)
{code}
to
{code}
boolean next(HStoreKey, SortedMap<byte[], Cell>)
{code}
Files changed as a result of API change:
HMerge.java, ColumnValueFilter, PageRowFilter, RegExpRowFilter,
RowFilterInterface, RowFilterSet, StopRowFilter, WhileMatchRowFilter,
HAbstractScanner, HRegion$HScanner, HRegionServer, HStoreScanner,
InternalScanner, Memcache, StoreFileScanner, MetaUtils, HBaseTestCase,
TestScannerAPI, TimestampTestBase, TestRegExpRowFilter,
TestRowFilterAfterWrite, TestRowFilterOnMultipleFamilies, TestRowFilterSet,
TestGet2, TestHMemcache, TestHRegion, TestScanner, TestSplit
Update comments in Flusher
HRegion.internalFlushCache, HStore.flushCache, HStore.internalFlushCache now
returns true only if a flush was completed and a compaction is needed.
HRegion.internalFlushCache now includes region name in DroppedSnapshotException
When creating a reader during compaction, don't bother with bloom filter since
we won't use it.
StoreFileScanner locks the store for read while it is in the constructor. It
also does not load the bloom filter when it opens readers on the store files.
TestScannerTimes - new regression test for HBASE-737
------------------------------------------
[...truncated 24760 lines...]
[junit] 2008-07-16 00:51:53,168 INFO [RegionManager.rootScanner]
master.BaseScanner(229): RegionManager.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 127.0.0.1:62408} complete
[junit] 2008-07-16 00:51:53,176 INFO [RegionServer:0.compactor]
regionserver.HRegion(889): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2008-07-16 00:51:53,327 INFO [main] util.TestMigrate(169):
Iterated over 3000 rows.
[junit] 2008-07-16 00:51:53,570 INFO [main] util.TestMigrate(169):
Iterated over 4000 rows.
[junit] 2008-07-16 00:51:53,777 INFO [main] util.TestMigrate(169):
Iterated over 5000 rows.
[junit] 2008-07-16 00:51:54,075 INFO [main] util.TestMigrate(169):
Iterated over 6000 rows.
[junit] 2008-07-16 00:51:54,134 DEBUG [IPC Server handler 0 on 60000]
master.ServerManager(570): Total Load: 4, Num Servers: 1, Avg Load: 4.0
[junit] 2008-07-16 00:51:54,224 INFO [main] util.TestMigrate(169):
Iterated over 7000 rows.
[junit] 2008-07-16 00:51:54,275 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HRegion(996): Started memcache flush for region .META.,,1. Current
region memcache size 938.0
[junit] 2008-07-16 00:51:54,409 INFO [main] util.TestMigrate(169):
Iterated over 8000 rows.
[junit] 2008-07-16 00:51:54,466 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HStore(630): Added
/user/hudson/hbase/.META./1028785192/historian/mapfiles/6724966819461504710
with 6 entries, sequence id 35187, data size 630.0, file size 824.0
[junit] 2008-07-16 00:51:54,531 DEBUG [main]
client.HTable$ClientScanner(1244): Advancing forward from region REGION =>
{NAME => 'TestUpgrade,,1199736362468', STARTKEY => '', ENDKEY => '[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENCODED => 341377241, TABLE => {NAME =>
'TestUpgrade', FAMILIES => [{NAME => 'column_a', VERSIONS => 3, COMPRESSION =>
'NONE', IN_MEMORY => false, BLOCKCACHE => false, LENGTH => 2147483647, TTL =>
FOREVER, BLOOMFILTER => false}, {NAME => 'column_b', VERSIONS => 3, COMPRESSION
=> 'NONE', IN_MEMORY => false, BLOCKCACHE => false, LENGTH => 2147483647, TTL
=> FOREVER, BLOOMFILTER => false}]}
[junit] 2008-07-16 00:51:54,531 DEBUG [main]
client.HTable$ClientScanner(1260): Advancing internal scanner to startKey at
'[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|'
[junit] 2008-07-16 00:51:54,568 WARN [EMAIL PROTECTED]
dfs.DataNode$DataXceiver(1062): 127.0.0.1:62229:Got exception while serving
blk_-4578263017280907822 to /127.0.0.1:
[junit] 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-16 00:51:54,569 ERROR [EMAIL PROTECTED]
dfs.DataNode$DataXceiver(999): 127.0.0.1:62229: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-16 00:51:54,594 WARN [EMAIL PROTECTED]
dfs.DataNode$DataXceiver(1062): 127.0.0.1:62227:Got exception while serving
blk_2198127321680577875 to /127.0.0.1:
[junit] 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-16 00:51:54,595 ERROR [EMAIL PROTECTED]
dfs.DataNode$DataXceiver(999): 127.0.0.1:62227: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-16 00:51:54,662 INFO [main] util.TestMigrate(169):
Iterated over 9000 rows.
[junit] 2008-07-16 00:51:54,873 INFO [main] util.TestMigrate(169):
Iterated over 10000 rows.
[junit] 2008-07-16 00:51:54,992 INFO [main] util.TestMigrate(169):
Iterated over 11000 rows.
[junit] 2008-07-16 00:51:55,095 INFO [main] util.TestMigrate(169):
Iterated over 12000 rows.
[junit] 2008-07-16 00:51:55,195 INFO [main] util.TestMigrate(169):
Iterated over 13000 rows.
[junit] 2008-07-16 00:51:55,219 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HStore(630): Added
/user/hudson/hbase/.META./1028785192/info/mapfiles/9046479873949066782 with 4
entries, sequence id 35187, data size 308.0, file size 474.0
[junit] 2008-07-16 00:51:55,222 DEBUG [IPC Server handler 3 on 60000]
master.ServerManager(570): Total Load: 4, Num Servers: 1, Avg Load: 4.0
[junit] 2008-07-16 00:51:55,224 DEBUG [RegionServer:0.cacheFlusher]
regionserver.HRegion(1068): Finished memcache flush for region .META.,,1 in
949ms, sequence id=35187, compaction requested=false
[junit] 2008-07-16 00:51:55,516 INFO [main] util.TestMigrate(169):
Iterated over 14000 rows.
[junit] 2008-07-16 00:51:55,606 INFO [main] util.TestMigrate(169):
Iterated over 15000 rows.
[junit] 2008-07-16 00:51:55,713 INFO [main] util.TestMigrate(169):
Iterated over 16000 rows.
[junit] 2008-07-16 00:51:55,882 INFO [main] util.TestMigrate(169):
Iterated over 17000 rows.
[junit] 2008-07-16 00:51:55,955 DEBUG [main]
client.HTable$ClientScanner(1244): Advancing forward from region REGION =>
{NAME => 'TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468',
STARTKEY => '[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENDKEY => '', ENCODED =>
1971203659, TABLE => {NAME => 'TestUpgrade', FAMILIES => [{NAME => 'column_a',
VERSIONS => 3, COMPRESSION => 'NONE', IN_MEMORY => false, BLOCKCACHE => false,
LENGTH => 2147483647, TTL => FOREVER, BLOOMFILTER => false}, {NAME =>
'column_b', VERSIONS => 3, COMPRESSION => 'NONE', IN_MEMORY => false,
BLOCKCACHE => false, LENGTH => 2147483647, TTL => FOREVER, BLOOMFILTER =>
false}]}
[junit] 2008-07-16 00:51:55,956 DEBUG [main] hbase.LocalHBaseCluster(253):
Shutting down HBase Cluster
[junit] 2008-07-16 00:51:55,956 INFO [main] master.HMaster(582): Cluster
shutdown requested. Starting to quiesce servers
[junit] 2008-07-16 00:51:56,225 INFO [RegionServer:0]
regionserver.HRegionServer(332): MSG_REGIONSERVER_QUIESCE
[junit] 2008-07-16 00:51:56,227 INFO [RegionServer:0.worker]
regionserver.HRegionServer$Worker(808): MSG_REGIONSERVER_QUIESCE
[junit] 2008-07-16 00:51:56,228 DEBUG [RegionServer:0.worker]
regionserver.HRegionServer(979): closing region TestUpgrade,,1199736362468
[junit] 2008-07-16 00:51:56,228 DEBUG [RegionServer:0.worker]
regionserver.HRegion(559): Compactions and cache flushes disabled for region
TestUpgrade,,1199736362468
[junit] 2008-07-16 00:51:56,228 DEBUG [RegionServer:0.worker]
regionserver.HRegion(574): Updates and scanners disabled for region
TestUpgrade,,1199736362468
[junit] 2008-07-16 00:51:56,228 DEBUG [RegionServer:0.worker]
regionserver.HRegion(589): No more active scanners for region
TestUpgrade,,1199736362468
[junit] 2008-07-16 00:51:56,228 DEBUG [RegionServer:0.worker]
regionserver.HRegion(595): No more row locks outstanding on region
TestUpgrade,,1199736362468
[junit] 2008-07-16 00:51:56,228 DEBUG [RegionServer:0.worker]
regionserver.HRegion(996): Started memcache flush for region
TestUpgrade,,1199736362468. Current region memcache size 0.0
[junit] 2008-07-16 00:51:56,231 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1068): Finished memcache flush for region
TestUpgrade,,1199736362468 in 3ms, sequence id=35188, compaction requested=false
[junit] 2008-07-16 00:51:56,231 DEBUG [RegionServer:0.worker]
regionserver.HStore(530): closed 341377241/column_b
[junit] 2008-07-16 00:51:56,232 DEBUG [RegionServer:0.worker]
regionserver.HStore(530): closed 341377241/column_a
[junit] 2008-07-16 00:51:56,233 INFO [RegionServer:0.worker]
regionserver.HRegion(608): closed TestUpgrade,,1199736362468
[junit] 2008-07-16 00:51:56,233 DEBUG [RegionServer:0.worker]
regionserver.HRegionServer(979): closing region TestUpgrade,[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-16 00:51:56,233 DEBUG [RegionServer:0.worker]
regionserver.HRegion(559): Compactions and cache flushes disabled for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-16 00:51:56,233 DEBUG [RegionServer:0.worker]
regionserver.HRegion(574): Updates and scanners disabled for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-16 00:51:56,233 DEBUG [RegionServer:0.worker]
regionserver.HRegion(589): No more active scanners for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-16 00:51:56,233 DEBUG [RegionServer:0.worker]
regionserver.HRegion(595): No more row locks outstanding on region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-16 00:51:56,233 DEBUG [RegionServer:0.worker]
regionserver.HRegion(996): Started memcache flush for region TestUpgrade,[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468. Current region memcache size 0.0
[junit] 2008-07-16 00:51:56,234 DEBUG [RegionServer:0.worker]
regionserver.HRegion(1068): Finished memcache flush for region
TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468 in 1ms,
sequence id=35189, compaction requested=false
[junit] 2008-07-16 00:51:56,235 DEBUG [RegionServer:0.worker]
regionserver.HStore(530): closed 1971203659/column_b
[junit] 2008-07-16 00:51:56,235 DEBUG [RegionServer:0.worker]
regionserver.HStore(530): closed 1971203659/column_a
[junit] 2008-07-16 00:51:56,235 INFO [RegionServer:0.worker]
regionserver.HRegion(608): closed TestUpgrade,[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468
[junit] 2008-07-16 00:51:56,984 INFO [RegionManager.metaScanner]
master.BaseScanner(149): RegionManager.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 127.0.0.1:62408}
[junit] 2008-07-16 00:51:57,001 DEBUG [RegionManager.metaScanner]
master.BaseScanner(175): RegionManager.metaScannerREGION => {NAME =>
'TestUpgrade,,1199736332093', STARTKEY => '', ENDKEY => '', ENCODED =>
1396626490, OFFLINE => true, SPLIT => true, TABLE => {NAME => 'TestUpgrade',
FAMILIES => [{NAME => 'column_a', VERSIONS => 3, COMPRESSION => 'NONE',
IN_MEMORY => false, BLOCKCACHE => false, LENGTH => 2147483647, TTL => FOREVER,
BLOOMFILTER => false}, {NAME => 'column_b', VERSIONS => 3, COMPRESSION =>
'NONE', IN_MEMORY => false, BLOCKCACHE => false, LENGTH => 2147483647, TTL =>
FOREVER, BLOOMFILTER => false}]}}, SERVER => '10.69.80.3:1845', STARTCODE =>
1199736332062
[junit] 2008-07-16 00:51:57,002 DEBUG [RegionManager.metaScanner]
master.BaseScanner(175): RegionManager.metaScannerREGION => {NAME =>
'TestUpgrade,,1199736362468', STARTKEY => '', ENDKEY => '[EMAIL
PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENCODED => 341377241, TABLE => {NAME =>
'TestUpgrade', FAMILIES => [{NAME => 'column_a', VERSIONS => 3, COMPRESSION =>
'NONE', IN_MEMORY => false, BLOCKCACHE => false, LENGTH => 2147483647, TTL =>
FOREVER, BLOOMFILTER => false}, {NAME => 'column_b', VERSIONS => 3, COMPRESSION
=> 'NONE', IN_MEMORY => false, BLOCKCACHE => false, LENGTH => 2147483647, TTL
=> FOREVER, BLOOMFILTER => false}]}}, SERVER => '127.0.0.1:62408', STARTCODE =>
1216169501509
[junit] 2008-07-16 00:51:57,003 DEBUG [RegionManager.metaScanner]
master.BaseScanner(175): RegionManager.metaScannerREGION => {NAME =>
'TestUpgrade,[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|,1199736362468', STARTKEY
=> '[EMAIL PROTECTED]&*()-_+=:;',.<>/?[]{}|', ENDKEY => '', ENCODED =>
1971203659, TABLE => {NAME => 'TestUpgrade', FAMILIES => [{NAME => 'column_a',
VERSIONS => 3, COMPRESSION => 'NONE', IN_MEMORY => false, BLOCKCACHE => false,
LENGTH => 2147483647, TTL => FOREVER, BLOOMFILTER => false}, {NAME =>
'column_b', VERSIONS => 3, COMPRESSION => 'NONE', IN_MEMORY => false,
BLOCKCACHE => false, LENGTH => 2147483647, TTL => FOREVER, BLOOMFILTER =>
false}]}}, SERVER => '127.0.0.1:62408', STARTCODE => 1216169501509
[junit] 2008-07-16 00:51:57,005 DEBUG [RegionManager.metaScanner]
master.BaseScanner(280): Checked TestUpgrade,,1199736332093 for references:
splitA: true, splitB: true
[junit] 2008-07-16 00:51:57,006 INFO [RegionManager.metaScanner]
master.BaseScanner(229): RegionManager.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 127.0.0.1:62408} complete
[junit] 2008-07-16 00:51:57,006 INFO [RegionManager.metaScanner]
master.MetaScanner(138): all meta regions scanned
[junit] 2008-07-16 00:51:57,234 INFO [IPC Server handler 4 on 60000]
master.ServerManager(165): Region server 127.0.0.1:62408 quiesced
[junit] 2008-07-16 00:51:57,235 INFO [IPC Server handler 4 on 60000]
master.ServerManager(174): All user tables quiesced. Proceeding with shutdown
[junit] 2008-07-16 00:51:57,235 DEBUG [IPC Server handler 4 on 60000]
master.RegionManager(453): telling root scanner to stop
[junit] 2008-07-16 00:51:57,236 DEBUG [IPC Server handler 4 on 60000]
master.RegionManager(457): telling meta scanner to stop
[junit] 2008-07-16 00:51:57,236 INFO [RegionManager.rootScanner]
hbase.Chore(74): RegionManager.rootScanner exiting
[junit] 2008-07-16 00:51:57,237 DEBUG [IPC Server handler 4 on 60000]
master.RegionManager(461): meta and root scanners notified
[junit] 2008-07-16 00:51:57,237 INFO [RegionManager.metaScanner]
hbase.Chore(74): RegionManager.metaScanner exiting
[junit] 2008-07-16 00:51:57,237 INFO [RegionServer:0]
regionserver.HRegionServer(332): MSG_REGIONSERVER_STOP
[junit] 2008-07-16 00:51:57,237 DEBUG [RegionServer:0]
hbase.RegionHistorian(316): Offlined
[junit] 2008-07-16 00:51:57,239 DEBUG [RegionServer:0]
regionserver.HRegionServer(945): closing region .META.,,1
[junit] 2008-07-16 00:51:57,239 DEBUG [RegionServer:0]
regionserver.HRegion(559): Compactions and cache flushes disabled for region
.META.,,1
[junit] 2008-07-16 00:51:57,239 DEBUG [RegionServer:0]
regionserver.HRegion(574): Updates and scanners disabled for region .META.,,1
[junit] 2008-07-16 00:51:57,239 DEBUG [RegionServer:0]
regionserver.HRegion(589): No more active scanners for region .META.,,1
[junit] 2008-07-16 00:51:57,240 DEBUG [RegionServer:0]
regionserver.HRegion(595): No more row locks outstanding on region .META.,,1
[junit] 2008-07-16 00:51:57,240 DEBUG [RegionServer:0]
regionserver.HRegion(996): Started memcache flush for region .META.,,1. Current
region memcache size 0.0
[junit] 2008-07-16 00:51:57,241 DEBUG [RegionServer:0]
regionserver.HRegion(1068): Finished memcache flush for region .META.,,1 in
1ms, sequence id=35190, compaction requested=false
[junit] 2008-07-16 00:51:57,241 INFO [RegionServer:0.cacheFlusher]
regionserver.Flusher(109): RegionServer:0.cacheFlusher exiting
[junit] 2008-07-16 00:51:57,243 INFO [RegionServer:0.logRoller]
regionserver.LogRoller(93): LogRoller exiting.
[junit] 2008-07-16 00:51:57,243 INFO [RegionServer:0.compactor]
regionserver.CompactSplitThread(112): RegionServer:0.compactor exiting
[junit] 2008-07-16 00:51:57,245 DEBUG [RegionServer:0]
regionserver.HStore(530): closed 1028785192/historian
[junit] 2008-07-16 00:51:57,245 INFO [RegionServer:0.worker]
regionserver.HRegionServer$Worker(862): worker thread exiting
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HStore(530): closed 1028785192/info
[junit] 2008-07-16 00:51:57,248 INFO [RegionServer:0]
regionserver.HRegion(608): closed .META.,,1
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HRegionServer(945): closing region -ROOT-,,0
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HRegion(559): Compactions and cache flushes disabled for region
-ROOT-,,0
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HRegion(574): Updates and scanners disabled for region -ROOT-,,0
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HRegion(589): No more active scanners for region -ROOT-,,0
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HRegion(595): No more row locks outstanding on region -ROOT-,,0
[junit] 2008-07-16 00:51:57,248 DEBUG [RegionServer:0]
regionserver.HRegion(996): Started memcache flush for region -ROOT-,,0. Current
region memcache size 0.0
[junit] 2008-07-16 00:51:57,249 DEBUG [RegionServer:0]
regionserver.HRegion(1068): Finished memcache flush for region -ROOT-,,0 in
1ms, sequence id=35191, compaction requested=false
[junit] 2008-07-16 00:51:57,251 DEBUG [RegionServer:0]
regionserver.HStore(530): closed 70236052/info
[junit] 2008-07-16 00:51:57,251 INFO [RegionServer:0]
regionserver.HRegion(608): closed -ROOT-,,0
[junit] 2008-07-16 00:51:57,252 DEBUG [RegionServer:0]
regionserver.HLog(337): closing log writer in
hdfs://localhost:62223/user/hudson/hbase/log_127.0.0.1_1216169501509_62408
[junit] 2008-07-16 00:51:57,321 INFO [RegionServer:0]
regionserver.HRegionServer(477): telling master that region server is shutting
down at: 127.0.0.1:62408
[junit] 2008-07-16 00:51:57,322 INFO [IPC Server handler 1 on 60000]
master.ServerManager(524): Cancelling lease for 127.0.0.1:62408
[junit] 2008-07-16 00:51:57,322 INFO [IPC Server handler 1 on 60000]
master.ServerManager(242): Region server 127.0.0.1:62408: MSG_REPORT_EXITING --
lease cancelled
[junit] 2008-07-16 00:51:57,322 INFO [RegionServer:0]
regionserver.HRegionServer(484): stopping server at: 127.0.0.1:62408
[junit] 2008-07-16 00:51:57,322 INFO [RegionServer:0]
regionserver.HRegionServer(488): RegionServer:0 exiting
[junit] 2008-07-16 00:51:58,174 DEBUG [HMaster] master.RegionManager(453):
telling root scanner to stop
[junit] 2008-07-16 00:51:58,174 DEBUG [HMaster] master.RegionManager(457):
telling meta scanner to stop
[junit] 2008-07-16 00:51:58,174 DEBUG [HMaster] master.RegionManager(461):
meta and root scanners notified
[junit] 2008-07-16 00:51:58,174 DEBUG [HMaster] hbase.RegionHistorian(316):
Offlined
[junit] 2008-07-16 00:51:58,175 INFO [RegionServer:0.leaseChecker]
hbase.Leases(115): RegionServer:0.leaseChecker closing leases
[junit] 2008-07-16 00:51:58,175 INFO [RegionServer:0.leaseChecker]
hbase.Leases(122): RegionServer:0.leaseChecker closed leases
[junit] 2008-07-16 00:51:58,177 INFO [HMaster] hbase.Leases(115): HMaster
closing leases
[junit] 2008-07-16 00:51:58,178 INFO [HMaster] hbase.Leases(122): HMaster
closed leases
[junit] 2008-07-16 00:51:58,179 INFO [HMaster] master.HMaster(385):
HMaster main thread exiting
[junit] 2008-07-16 00:51:58,324 INFO [main] hbase.LocalHBaseCluster(282):
Shutdown HMaster 1 region server(s)
[junit] 2008-07-16 00:51:58,324 INFO [main] hbase.HBaseTestCase(614):
Shutting down FileSystem
[junit] 2008-07-16 00:51:59,124 INFO [main] hbase.HBaseTestCase(621):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2008-07-16 00:51:59,125 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=62231]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=62231]
[junit] 2008-07-16 00:51:59,127 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:62231
[junit] 2008-07-16 00:51:59,215 INFO [main] util.Container(156): Stopped
HttpContext[/static,/static]
[junit] 2008-07-16 00:51:59,216 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-16 00:51:59,381 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2008-07-16 00:51:59,381 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-16 00:51:59,934 INFO [ServerManager.leaseChecker]
hbase.Leases(115): ServerManager.leaseChecker closing leases
[junit] 2008-07-16 00:51:59,934 INFO [ServerManager.leaseChecker]
hbase.Leases(122): ServerManager.leaseChecker closed leases
[junit] Shutting down DataNode 0
[junit] 2008-07-16 00:52:00,384 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=62228]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=62228]
[junit] 2008-07-16 00:52:00,385 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:62228
[junit] 2008-07-16 00:52:00,462 INFO [main] util.Container(156): Stopped
HttpContext[/static,/static]
[junit] 2008-07-16 00:52:00,462 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-16 00:52:00,540 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2008-07-16 00:52:00,540 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-16 00:52:00,645 INFO [main] http.SocketListener(212):
Stopped SocketListener on 0.0.0.0:62224
[junit] 2008-07-16 00:52:00,714 INFO [main] util.Container(156): Stopped
HttpContext[/static,/static]
[junit] 2008-07-16 00:52:00,715 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-16 00:52:00,796 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2008-07-16 00:52:00,796 INFO [main] util.Container(156): Stopped
[EMAIL PROTECTED]
[junit] 2008-07-16 00:52:00,797 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: 43.388 sec
[junit] 2008-07-16 00:52:00,841 INFO [Thread-333]
regionserver.HRegionServer$ShutdownThread(185): Starting shutdown thread.
[junit] 2008-07-16 00:52:00,842 INFO [Thread-333]
regionserver.HRegionServer$ShutdownThread(190): Shutdown thread complete
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2008-07-16 00:52:01,443 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.058 sec
[junit] Running org.onelab.test.TestFilter
[junit] 2008-07-16 00:52:01,944 INFO [main] test.TestFilter(242): Checking
for false negatives
[junit] 2008-07-16 00:52:01,950 INFO [main] test.TestFilter(253): Checking
for false positives
[junit] 2008-07-16 00:52:01,951 INFO [main] test.TestFilter(260): Success!
[junit] 2008-07-16 00:52:01,952 INFO [main] test.TestFilter(224): Checking
serialization/deserialization
[junit] 2008-07-16 00:52:01,954 INFO [main] test.TestFilter(242): Checking
for false negatives
[junit] 2008-07-16 00:52:01,956 INFO [main] test.TestFilter(253): Checking
for false positives
[junit] 2008-07-16 00:52:01,957 INFO [main] test.TestFilter(260): Success!
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.071 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:389:
Tests failed!
Total time: 34 minutes 42 seconds
Recording test results
