See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/346/changes
Changes:
[ddas] HADOOP-2486. When the list of files from the InMemoryFileSystem is
obtained for merging, this patch will ensure that only those files whose
checksums have also got created (renamed) are returned. Contributed by Devaraj
Das.
------------------------------------------
[...truncated 49531 lines...]
[junit] 2007-12-26 13:31:19,073 DEBUG [main]
hbase.HConnectionManager$TableServers(732): Found 1 region(s) for r2 at
address: 140.211.11.75:42962, regioninfo: regionname: .META.,,1, startKey: <>,
encodedName(1028785192) tableDesc: {name: .META., families: {info:={name: info,
max versions: 1, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}}}
[junit] 2007-12-26 13:31:20,266 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:42962}
[junit] 2007-12-26 13:31:20,290 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:20,292 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:42962} complete
[junit] 2007-12-26 13:31:21,160 INFO [IPC Server handler 2 on 60000]
hbase.HLog(302): new log writer created at
/hbase/hregion_229975933/log/hlog.dat.000
[junit] 2007-12-26 13:31:21,513 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 81074072/b/1926594640398865128 with sequence id 27 and
size 270.0 for r1,,1198675858998/b
[junit] 2007-12-26 13:31:21,578 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(601): starting result_table,,1198675879079/a (229975933/a) (no
reconstruction log)
[junit] 2007-12-26 13:31:21,580 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/a
(229975933/a) is -1
[junit] 2007-12-26 13:31:21,861 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(601): starting result_table,,1198675879079/b (229975933/b) (no
reconstruction log)
[junit] 2007-12-26 13:31:21,863 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/b
(229975933/b) is -1
[junit] 2007-12-26 13:31:22,155 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(601): starting result_table,,1198675879079/c (229975933/c) (no
reconstruction log)
[junit] 2007-12-26 13:31:22,158 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/c
(229975933/c) is -1
[junit] 2007-12-26 13:31:22,256 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:42962}
[junit] 2007-12-26 13:31:22,270 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198675858998, startKey: <>, encodedName(81074072) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:42962,
startCode: 1198675858969
[junit] 2007-12-26 13:31:22,272 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r2,,1198675872004, startKey: <>, encodedName(326268488) tableDesc: {name: r2,
families: {d:={name: d, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:22,276 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:42962} complete
[junit] 2007-12-26 13:31:22,277 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-26 13:31:22,471 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(601): starting result_table,,1198675879079/d (229975933/d) (no
reconstruction log)
[junit] 2007-12-26 13:31:22,473 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/d
(229975933/d) is -1
[junit] 2007-12-26 13:31:22,539 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 81074072/c/5989020303893464796 with sequence id 27 and
size 310.0 for r1,,1198675858998/c
[junit] 2007-12-26 13:31:22,540 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region r1,,1198675858998 in
5194ms, sequenceid=27
[junit] 2007-12-26 13:31:22,541 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore r1,,1198675858998/a not needed.
[junit] 2007-12-26 13:31:22,541 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 227.0
[junit] 2007-12-26 13:31:22,541 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore r1,,1198675858998/b not needed.
[junit] 2007-12-26 13:31:22,542 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore r1,,1198675858998/c not needed.
[junit] 2007-12-26 13:31:22,542 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region r1,,1198675858998 does not need compaction
[junit] 2007-12-26 13:31:22,758 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(601): starting result_table,,1198675879079/e (229975933/e) (no
reconstruction log)
[junit] 2007-12-26 13:31:22,760 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/e
(229975933/e) is -1
[junit] 2007-12-26 13:31:22,761 DEBUG [IPC Server handler 2 on 60000]
hbase.HRegion(300): Next sequence id for region result_table,,1198675879079 is 0
[junit] 2007-12-26 13:31:22,765 INFO [IPC Server handler 2 on 60000]
hbase.HRegion(328): region result_table,,1198675879079 available
[junit] 2007-12-26 13:31:22,767 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198675879079/e (229975933/e)
[junit] 2007-12-26 13:31:22,768 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198675879079/d (229975933/d)
[junit] 2007-12-26 13:31:22,769 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198675879079/a (229975933/a)
[junit] 2007-12-26 13:31:22,769 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198675879079/b (229975933/b)
[junit] 2007-12-26 13:31:22,770 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198675879079/c (229975933/c)
[junit] 2007-12-26 13:31:22,770 INFO [IPC Server handler 2 on 60000]
hbase.HRegion(430): closed result_table,,1198675879079
[junit] 2007-12-26 13:31:22,770 DEBUG [IPC Server handler 2 on 60000]
hbase.HLog(388): closing log writer in /hbase/hregion_229975933/log
[junit] 2007-12-26 13:31:23,121 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(2547): created table result_table
[junit] 2007-12-26 13:31:23,122 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing
a find...
[junit] 2007-12-26 13:31:23,140 DEBUG [main]
hbase.HConnectionManager$TableServers(782): no server address for regionname:
result_table,,1198675879079, startKey: <>, encodedName(229975933) tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, b:={name:
b, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
[junit] 2007-12-26 13:31:23,141 DEBUG [main]
hbase.HConnectionManager$TableServers(680): Sleeping. Table result_table not
currently being served.
[junit] 2007-12-26 13:31:23,466 INFO [IPC Server handler 4 on 60000]
hbase.HMaster(1858): assigning region result_table,,1198675879079 to the only
server 140.211.11.75:42962
[junit] 2007-12-26 13:31:23,468 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1198675879079
[junit] 2007-12-26 13:31:23,472 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198675879079/a (229975933/a) (no
reconstruction log)
[junit] 2007-12-26 13:31:23,475 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/a
(229975933/a) is -1
[junit] 2007-12-26 13:31:23,482 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198675879079/b (229975933/b) (no
reconstruction log)
[junit] 2007-12-26 13:31:23,484 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/b
(229975933/b) is -1
[junit] 2007-12-26 13:31:23,489 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198675879079/c (229975933/c) (no
reconstruction log)
[junit] 2007-12-26 13:31:23,491 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/c
(229975933/c) is -1
[junit] 2007-12-26 13:31:23,494 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198675879079/d (229975933/d) (no
reconstruction log)
[junit] 2007-12-26 13:31:23,497 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/d
(229975933/d) is -1
[junit] 2007-12-26 13:31:23,501 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198675879079/e (229975933/e) (no
reconstruction log)
[junit] 2007-12-26 13:31:23,503 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198675879079/e
(229975933/e) is -1
[junit] 2007-12-26 13:31:23,504 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region result_table,,1198675879079 is 0
[junit] 2007-12-26 13:31:23,505 INFO [RegionServer:0.worker]
hbase.HRegion(328): region result_table,,1198675879079 available
[junit] 2007-12-26 13:31:23,592 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 1028785192/info/5340297685816944268 with sequence id
34 and size 379.0 for .META.,,1/info
[junit] 2007-12-26 13:31:23,593 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 1052ms,
sequenceid=34
[junit] 2007-12-26 13:31:23,594 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore .META.,,1/info not needed.
[junit] 2007-12-26 13:31:23,594 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region .META.,,1 does not need compaction
[junit] 2007-12-26 13:31:24,406 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region r2,,1198675872004. Size
132.0
[junit] 2007-12-26 13:31:24,477 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN :
result_table,,1198675879079 from 140.211.11.75:42962
[junit] 2007-12-26 13:31:24,478 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1198675879079
from 140.211.11.75:42962
[junit] 2007-12-26 13:31:24,478 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1577): 140.211.11.75:42962 serving result_table,,1198675879079
[junit] 2007-12-26 13:31:24,479 DEBUG [HMaster] hbase.HMaster(1055): Main
processing loop: PendingOpenOperation from 140.211.11.75:42962
[junit] 2007-12-26 13:31:24,479 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1198675879079,
startKey: <>, encodedName(229975933) tableDesc: {name: result_table, families:
{a:={name: a, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, c:={name:
c, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, d:={name: d, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, e:={name:
e, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}}} open on 140.211.11.75:42962
[junit] 2007-12-26 13:31:24,480 DEBUG [HMaster]
hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1,
onlineMetaRegions.size(): 1
[junit] 2007-12-26 13:31:24,480 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1198675879079
in table .META.,,1 with startcode 1198675858969 and server 140.211.11.75:42962
[junit] 2007-12-26 13:31:25,591 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 326268488/e/3364216969856721391 with sequence id 36
and size 218.0 for r2,,1198675872004/e
[junit] 2007-12-26 13:31:26,292 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 326268488/d/1411185453579377350 with sequence id 36
and size 218.0 for r2,,1198675872004/d
[junit] 2007-12-26 13:31:26,293 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region r2,,1198675872004 in
1888ms, sequenceid=36
[junit] 2007-12-26 13:31:26,294 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore r2,,1198675872004/e not needed.
[junit] 2007-12-26 13:31:26,294 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore r2,,1198675872004/d not needed.
[junit] 2007-12-26 13:31:26,294 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region r2,,1198675872004 does not need compaction
[junit] 2007-12-26 13:31:28,145 DEBUG [main]
hbase.HConnectionManager$TableServers(689): Wake. Retry finding table
result_table
[junit] 2007-12-26 13:31:28,203 DEBUG [main]
hbase.HConnectionManager$TableServers(732): Found 1 region(s) for result_table
at address: 140.211.11.75:42962, regioninfo: regionname: .META.,,1, startKey:
<>, encodedName(1028785192) tableDesc: {name: .META., families: {info:={name:
info, max versions: 1, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}}}
[junit] 2007-12-26 13:31:28,746 WARN [IPC Server handler 2 on 42941]
dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove
/user/hudson/build/contrib/hbase/test/system because it does not exist
[junit] 2007-12-26 13:31:30,307 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:42962}
[junit] 2007-12-26 13:31:30,357 WARN [IPC Server handler 7 on 42941]
dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove
/user/hudson/build/contrib/hbase/test/system/job_local_1 because it does not
exist
[junit] 2007-12-26 13:31:30,358 WARN [main] mapred.JobClient(496): No job
jar file set. User classes may not be found. See JobConf(Class) or
JobConf#setJar(String).
[junit] 2007-12-26 13:31:30,393 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find...
[junit] 2007-12-26 13:31:30,738 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:30,741 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:42962} complete
[junit] 2007-12-26 13:31:30,801 DEBUG [main]
hbase.HConnectionManager$TableServers(763): Found 1 servers for table r1
[junit] 2007-12-26 13:31:30,836 DEBUG [main] mapred.TableInputFormat(175):
split: 0->r1,,
[junit] 2007-12-26 13:31:32,264 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:42962}
[junit] 2007-12-26 13:31:32,312 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198675858998, startKey: <>, encodedName(81074072) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:42962,
startCode: 1198675858969
[junit] 2007-12-26 13:31:32,315 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r2,,1198675872004, startKey: <>, encodedName(326268488) tableDesc: {name: r2,
families: {d:={name: d, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:32,317 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
result_table,,1198675879079, startKey: <>, encodedName(229975933) tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, b:={name:
b, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:32,319 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:42962} complete
[junit] 2007-12-26 13:31:32,319 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-26 13:31:32,576 DEBUG [Thread-213]
mapred.TableInputFormat(175): split: 0->r1,,
[junit] 2007-12-26 13:31:32,748 DEBUG [Thread-213]
hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
[junit] 2007-12-26 13:31:32,780 DEBUG [Thread-213]
hbase.HConnectionManager$TableServers(763): Found 1 servers for table r2
[junit] 2007-12-26 13:31:33,516 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region
result_table,,1198675879079. Size 306.0
[junit] 2007-12-26 13:31:34,675 WARN [Task Commit Thread]
mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting...
[junit] 2007-12-26 13:31:37,359 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 229975933/e/1287247000067178993 with sequence id 54
and size 212.0 for result_table,,1198675879079/e
[junit] 2007-12-26 13:31:40,313 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:42962}
[junit] 2007-12-26 13:31:40,658 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:40,660 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:42962} complete
[junit] 2007-12-26 13:31:41,855 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 229975933/d/2210086376931613884 with sequence id 54
and size 212.0 for result_table,,1198675879079/d
[junit] 2007-12-26 13:31:42,273 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:42962}
[junit] 2007-12-26 13:31:42,309 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198675858998, startKey: <>, encodedName(81074072) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:42962,
startCode: 1198675858969
[junit] 2007-12-26 13:31:42,311 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r2,,1198675872004, startKey: <>, encodedName(326268488) tableDesc: {name: r2,
families: {d:={name: d, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:42,314 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
result_table,,1198675879079, startKey: <>, encodedName(229975933) tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, b:={name:
b, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:42962, startCode: 1198675858969
[junit] 2007-12-26 13:31:42,316 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:42962} complete
[junit] 2007-12-26 13:31:42,316 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-26 13:31:42,599 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 229975933/a/7090879152418303241 with sequence id 54
and size 206.0 for result_table,,1198675879079/a
[junit] 2007-12-26 13:31:43,426 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 229975933/b/1460270395567052794 with sequence id 54
and size 206.0 for result_table,,1198675879079/b
[junit] 2007-12-26 13:31:44,906 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 229975933/c/4295106480978426870 with sequence id 54
and size 230.0 for result_table,,1198675879079/c
[junit] 2007-12-26 13:31:44,907 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region
result_table,,1198675879079 in 11392ms, sequenceid=54
[junit] 2007-12-26 13:31:44,907 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore result_table,,1198675879079/e not
needed.
[junit] 2007-12-26 13:31:44,907 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore result_table,,1198675879079/d not
needed.
[junit] 2007-12-26 13:31:44,908 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore result_table,,1198675879079/a not
needed.
[junit] 2007-12-26 13:31:44,907 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 331.0
[junit] 2007-12-26 13:31:44,908 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore result_table,,1198675879079/b not
needed.
[junit] 2007-12-26 13:31:44,909 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore result_table,,1198675879079/c not
needed.
[junit] 2007-12-26 13:31:44,909 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region result_table,,1198675879079 does not need compaction
[junit] 2007-12-26 13:31:45,227 INFO [main]
algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
[junit] 2007-12-26 13:31:45,229 INFO [main]
algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
[junit] 2007-12-26 13:31:45,231 INFO [main]
algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
[junit] 2007-12-26 13:31:45,323 INFO [main]
algebra.TestTableJoinMapReduce(244): result_table.row.count: 3
[junit] 2007-12-26 13:31:45,324 DEBUG [main] hbase.LocalHBaseCluster(212):
Shutting down HBase Cluster
[junit] 2007-12-26 13:31:45,324 INFO [main] hbase.HMaster(2526): Cluster
shutdown requested. Starting to quiesce servers
[junit] 2007-12-26 13:31:46,094 INFO [RegionServer:0]
hbase.HRegionServer(774): Got quiesce server message
[junit] 2007-12-26 13:31:46,094 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE :
[junit] 2007-12-26 13:31:46,095 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1295): closing region r1,,1198675858998
[junit] 2007-12-26 13:31:46,095 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r1,,1198675858998/a (81074072/a)
[junit] 2007-12-26 13:31:46,095 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r1,,1198675858998/b (81074072/b)
[junit] 2007-12-26 13:31:46,096 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r1,,1198675858998/c (81074072/c)
[junit] 2007-12-26 13:31:46,096 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed r1,,1198675858998
[junit] 2007-12-26 13:31:46,096 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1295): closing region r2,,1198675872004
[junit] 2007-12-26 13:31:46,096 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r2,,1198675872004/e (326268488/e)
[junit] 2007-12-26 13:31:46,097 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r2,,1198675872004/d (326268488/d)
[junit] 2007-12-26 13:31:46,097 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed r2,,1198675872004
[junit] 2007-12-26 13:31:46,097 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1295): closing region result_table,,1198675879079
[junit] 2007-12-26 13:31:46,097 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198675879079/e (229975933/e)
[junit] 2007-12-26 13:31:46,098 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198675879079/d (229975933/d)
[junit] 2007-12-26 13:31:46,098 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198675879079/a (229975933/a)
[junit] 2007-12-26 13:31:46,098 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198675879079/b (229975933/b)
[junit] 2007-12-26 13:31:46,098 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198675879079/c (229975933/c)
[junit] 2007-12-26 13:31:46,099 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed result_table,,1198675879079
[junit] 2007-12-26 13:31:46,453 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 1028785192/info/8661471617739015453 with sequence id
55 and size 483.0 for .META.,,1/info
[junit] 2007-12-26 13:31:46,454 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 1547ms,
sequenceid=55
[junit] 2007-12-26 13:31:46,455 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore .META.,,1/info needed.
[junit] 2007-12-26 13:31:46,457 DEBUG [RegionServer:0.compactor]
hbase.HRegion(700): 1028785192/info needs compaction
[junit] 2007-12-26 13:31:46,458 INFO [RegionServer:0.compactor]
hbase.HRegion(749): starting compaction on region .META.,,1
[junit] 2007-12-26 13:31:46,458 DEBUG [RegionServer:0.compactor]
hbase.HStore(1024): started compaction of 3 files using
/hbase/hregion_1028785192/compaction.dir/hregion_1028785192/info for
.META.,,1/info
[junit] 2007-12-26 13:31:47,103 INFO [IPC Server handler 3 on 60000]
hbase.HMaster(1372): Region server 140.211.11.75:42962 quiesced
[junit] 2007-12-26 13:31:47,104 INFO [IPC Server handler 3 on 60000]
hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown
[junit] 2007-12-26 13:31:47,104 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1206): telling root scanner to stop
[junit] 2007-12-26 13:31:47,104 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1214): telling meta scanner to stop
[junit] 2007-12-26 13:31:47,105 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1222): meta and root scanners notified
[junit] 2007-12-26 13:31:47,105 INFO [HMaster.metaScanner]
hbase.Chore(66): HMaster.metaScanner exiting
[junit] 2007-12-26 13:31:47,105 INFO [HMaster.rootScanner]
hbase.Chore(66): HMaster.rootScanner exiting
[junit] 2007-12-26 13:31:47,105 INFO [RegionServer:0]
hbase.HRegionServer(768): Got regionserver stop message
[junit] 2007-12-26 13:31:47,106 INFO [RegionServer:0] hbase.Leases(109):
RegionServer:0 closing leases
[junit] 2007-12-26 13:31:47,106 INFO [RegionServer:0.leaseChecker]
hbase.Chore(66): RegionServer:0.leaseChecker exiting
[junit] 2007-12-26 13:31:47,106 INFO [RegionServer:0] hbase.Leases(125):
RegionServer:0 closed leases
[junit] 2007-12-26 13:31:47,107 DEBUG [RegionServer:0]
hbase.HRegionServer(1261): closing region -ROOT-,,0
[junit] 2007-12-26 13:31:47,107 INFO [RegionServer:0.cacheFlusher]
hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting
[junit] 2007-12-26 13:31:47,108 INFO [RegionServer:0.splitter]
hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
[junit] 2007-12-26 13:31:47,108 DEBUG [RegionServer:0] hbase.HStore(880):
closed -ROOT-,,0/info (70236052/info)
[junit] 2007-12-26 13:31:47,109 INFO [RegionServer:0] hbase.HRegion(430):
closed -ROOT-,,0
[junit] 2007-12-26 13:31:47,109 DEBUG [RegionServer:0]
hbase.HRegionServer(1261): closing region .META.,,1
[junit] 2007-12-26 13:31:47,110 DEBUG [RegionServer:0] hbase.HStore(880):
closed .META.,,1/info (1028785192/info)
[junit] 2007-12-26 13:31:47,110 INFO [RegionServer:0] hbase.HRegion(430):
closed .META.,,1
[junit] 2007-12-26 13:31:47,110 DEBUG [RegionServer:0] hbase.HLog(388):
closing log writer in /hbase/log_140.211.11.75_1198675858969_42962
[junit] 2007-12-26 13:31:47,115 ERROR [RegionServer:0.compactor]
hbase.HRegionServer$Compactor(418): Compaction failed for region .META.,,1
[junit] java.lang.reflect.UndeclaredThrowableException
[junit] at org.apache.hadoop.dfs.$Proxy0.create(Unknown Source)
[junit] at sun.reflect.GeneratedMethodAccessor14.invoke(Unknown Source)
[junit] at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:585)
[junit] at
org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:82)
[junit] at
org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:59)
[junit] at org.apache.hadoop.dfs.$Proxy0.create(Unknown Source)
[junit] at
org.apache.hadoop.dfs.DFSClient$DFSOutputStream.<init>(DFSClient.java:1424)
[junit] at org.apache.hadoop.dfs.DFSClient.create(DFSClient.java:354)
[junit] at
org.apache.hadoop.dfs.DistributedFileSystem.create(DistributedFileSystem.java:122)
[junit] at org.apache.hadoop.fs.FileSystem.create(FileSystem.java:390)
[junit] at org.apache.hadoop.fs.FileSystem.create(FileSystem.java:290)
[junit] at
org.apache.hadoop.io.SequenceFile$Writer.<init>(SequenceFile.java:815)
[junit] at
org.apache.hadoop.io.SequenceFile.createWriter(SequenceFile.java:384)
[junit] at
org.apache.hadoop.io.SequenceFile.createWriter(SequenceFile.java:414)
[junit] at org.apache.hadoop.io.MapFile$Writer.<init>(MapFile.java:153)
[junit] at org.apache.hadoop.io.MapFile$Writer.<init>(MapFile.java:132)
[junit] at org.apache.hadoop.io.MapFile$Writer.<init>(MapFile.java:124)
[junit] at org.apache.hadoop.io.MapFile$Writer.<init>(MapFile.java:109)
[junit] at
org.apache.hadoop.hbase.HStoreFile$BloomFilterMapFile$Writer.<init>(HStoreFile.java:890)
[junit] at
org.apache.hadoop.hbase.HStoreFile.getWriter(HStoreFile.java:964)
[junit] at org.apache.hadoop.hbase.HStore.compact(HStore.java:1061)
[junit] at
org.apache.hadoop.hbase.HRegion.compactStores(HRegion.java:753)
[junit] at
org.apache.hadoop.hbase.HRegion.compactIfNeeded(HRegion.java:712)
[junit] at
org.apache.hadoop.hbase.HRegionServer$Compactor.run(HRegionServer.java:405)
[junit] Caused by: java.lang.InterruptedException
[junit] at java.lang.Object.wait(Native Method)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:504)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
[junit] ... 25 more
[junit] 2007-12-26 13:31:47,120 INFO [RegionServer:0.compactor]
hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
[junit] 2007-12-26 13:31:47,912 DEBUG [HMaster] hbase.HMaster(1206):
telling root scanner to stop
[junit] 2007-12-26 13:31:47,912 DEBUG [HMaster] hbase.HMaster(1214):
telling meta scanner to stop
[junit] 2007-12-26 13:31:47,913 DEBUG [HMaster] hbase.HMaster(1222): meta
and root scanners notified
[junit] 2007-12-26 13:31:47,913 INFO [HMaster] hbase.HMaster(1241):
Waiting on following regionserver(s) to go down (or region server lease
expiration, whichever happens first): [address: 140.211.11.75:42962, startcode:
1198675858969, load: (requests: 1 regions: 5)]
[junit] 2007-12-26 13:31:48,112 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1202): worker thread exiting
[junit] 2007-12-26 13:31:48,507 INFO [RegionServer:0]
hbase.HRegionServer(888): telling master that region server is shutting down
at: 140.211.11.75:42962
[junit] 2007-12-26 13:31:48,509 DEBUG [IPC Server handler 2 on 60000]
hbase.HMaster(1339): Region server 140.211.11.75:42962: MSG_REPORT_EXITING --
cancelling lease
[junit] 2007-12-26 13:31:48,509 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(1481): Cancelling lease for 140.211.11.75:42962
[junit] 2007-12-26 13:31:48,509 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(1346): Region server 140.211.11.75:42962: MSG_REPORT_EXITING --
lease cancelled
[junit] 2007-12-26 13:31:48,510 INFO [RegionServer:0]
hbase.HRegionServer(895): stopping server at: 140.211.11.75:42962
[junit] 2007-12-26 13:31:48,510 INFO [HMaster] hbase.Leases(109): HMaster
closing leases
[junit] 2007-12-26 13:31:48,510 INFO [RegionServer:0]
hbase.HRegionServer(900): RegionServer:0 exiting
[junit] 2007-12-26 13:31:48,511 INFO [HMaster.leaseChecker]
hbase.Chore(66): HMaster.leaseChecker exiting
[junit] 2007-12-26 13:31:48,513 INFO [HMaster] hbase.Leases(125): HMaster
closed leases
[junit] 2007-12-26 13:31:48,513 INFO [HMaster] hbase.HMaster(1139):
HMaster main thread exiting
[junit] 2007-12-26 13:31:48,514 INFO [main] hbase.LocalHBaseCluster(238):
Shutdown HMaster 1 region server(s)
[junit] 2007-12-26 13:31:49,272 INFO [main]
hbase.StaticTestEnvironment(133): Shutting down FileSystem
[junit] 2007-12-26 13:31:49,652 INFO [main]
hbase.StaticTestEnvironment(140): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 0
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 61.642 sec
[junit] 2007-12-26 13:31:50,849 INFO [main]
hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
[junit] 2007-12-26 13:31:50,849 INFO [main]
hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
[junit] Running org.apache.hadoop.hbase.util.TestBase64
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.314 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.056 sec
[junit] Running org.onelab.test.TestFilter
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.058 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml
:515: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build.xml
:31: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build-contrib.xml
:206: Tests failed!
Total time: 137 minutes 24 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2486
