See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/348/changes
Changes: [stack] HADOOP-2485 Make mapfile index interval configurable [shv] Line breaks corrected to unix style vs windows introduced by HADOOP-2149. Contributed by Konstantin Shvachko ------------------------------------------ [...truncated 48838 lines...] [junit] 2007-12-28 12:31:18,246 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting r2,,1198845077965/e (371709688/e) (no reconstruction log) [junit] 2007-12-28 12:31:18,249 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore r2,,1198845077965/e (371709688/e) is -1 [junit] 2007-12-28 12:31:18,250 DEBUG [IPC Server handler 1 on 60000] hbase.HRegion(300): Next sequence id for region r2,,1198845077965 is 0 [junit] 2007-12-28 12:31:18,251 INFO [IPC Server handler 1 on 60000] hbase.HRegion(328): region r2,,1198845077965 available [junit] 2007-12-28 12:31:18,253 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed r2,,1198845077965/e (371709688/e) [junit] 2007-12-28 12:31:18,253 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed r2,,1198845077965/d (371709688/d) [junit] 2007-12-28 12:31:18,253 INFO [IPC Server handler 1 on 60000] hbase.HRegion(430): closed r2,,1198845077965 [junit] 2007-12-28 12:31:18,254 DEBUG [IPC Server handler 1 on 60000] hbase.HLog(388): closing log writer in /hbase/hregion_371709688/log [junit] 2007-12-28 12:31:18,354 INFO [IPC Server handler 1 on 60000] hbase.HMaster(2547): created table r2 [junit] 2007-12-28 12:31:18,356 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find... [junit] 2007-12-28 12:31:18,359 DEBUG [main] hbase.HConnectionManager$TableServers(782): no server address for regionname: r2,,1198845077965, startKey: <>, encodedName(371709688) 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}}} [junit] 2007-12-28 12:31:18,360 DEBUG [main] hbase.HConnectionManager$TableServers(680): Sleeping. Table r2 not currently being served. [junit] 2007-12-28 12:31:18,457 INFO [IPC Server handler 2 on 60000] hbase.HMaster(1858): assigning region r2,,1198845077965 to the only server 140.211.11.75:36498 [junit] 2007-12-28 12:31:18,458 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : r2,,1198845077965 [junit] 2007-12-28 12:31:18,463 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1198845077965/d (371709688/d) (no reconstruction log) [junit] 2007-12-28 12:31:18,465 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1198845077965/d (371709688/d) is -1 [junit] 2007-12-28 12:31:18,470 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1198845077965/e (371709688/e) (no reconstruction log) [junit] 2007-12-28 12:31:18,472 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1198845077965/e (371709688/e) is -1 [junit] 2007-12-28 12:31:18,473 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region r2,,1198845077965 is 0 [junit] 2007-12-28 12:31:18,475 INFO [RegionServer:0.worker] hbase.HRegion(328): region r2,,1198845077965 available [junit] 2007-12-28 12:31:19,406 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0 [junit] 2007-12-28 12:31:19,468 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : r2,,1198845077965 from 140.211.11.75:36498 [junit] 2007-12-28 12:31:19,468 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : r2,,1198845077965 from 140.211.11.75:36498 [junit] 2007-12-28 12:31:19,469 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1577): 140.211.11.75:36498 serving r2,,1198845077965 [junit] 2007-12-28 12:31:19,469 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:36498 [junit] 2007-12-28 12:31:19,470 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: r2,,1198845077965, startKey: <>, encodedName(371709688) 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}}} open on 140.211.11.75:36498 [junit] 2007-12-28 12:31:19,470 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1 [junit] 2007-12-28 12:31:19,475 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row r2,,1198845077965 in table .META.,,1 with startcode 1198845069249 and server 140.211.11.75:36498 [junit] 2007-12-28 12:31:19,807 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 70236052/info/7184208188855845893 with 2 entries, sequence id 23, and size 230.0 for -ROOT-,,0/info [junit] 2007-12-28 12:31:19,808 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 402ms, sequenceid=23 [junit] 2007-12-28 12:31:19,808 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore -ROOT-,,0/info not needed. [junit] 2007-12-28 12:31:19,809 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region -ROOT-,,0 does not need compaction [junit] 2007-12-28 12:31:20,436 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:36498} [junit] 2007-12-28 12:31:20,461 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:20,464 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:36498} complete [junit] 2007-12-28 12:31:21,406 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 472.0 [junit] 2007-12-28 12:31:21,798 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/5837344334734537516 with 6 entries, sequence id 26, and size 666.0 for .META.,,1/info [junit] 2007-12-28 12:31:21,799 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 393ms, sequenceid=26 [junit] 2007-12-28 12:31:21,799 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed. [junit] 2007-12-28 12:31:21,799 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction [junit] 2007-12-28 12:31:22,425 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:36498} [junit] 2007-12-28 12:31:22,441 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1198845069276, startKey: <>, encodedName(25535445) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:22,443 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1198845077965, startKey: <>, encodedName(371709688) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:22,445 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:36498} complete [junit] 2007-12-28 12:31:22,446 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned [junit] 2007-12-28 12:31:23,365 DEBUG [main] hbase.HConnectionManager$TableServers(689): Wake. Retry finding table r2 [junit] 2007-12-28 12:31:23,380 DEBUG [main] hbase.HConnectionManager$TableServers(732): Found 1 region(s) for r2 at address: 140.211.11.75:36498, 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-28 12:31:23,527 INFO [IPC Server handler 2 on 60000] hbase.HLog(302): new log writer created at /hbase/hregion_1881616539/log/hlog.dat.000 [junit] 2007-12-28 12:31:23,535 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r1,,1198845069276. Size 310.0 [junit] 2007-12-28 12:31:23,655 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1198845083386/a (1881616539/a) (no reconstruction log) [junit] 2007-12-28 12:31:23,657 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/a (1881616539/a) is -1 [junit] 2007-12-28 12:31:23,812 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1198845083386/b (1881616539/b) (no reconstruction log) [junit] 2007-12-28 12:31:23,814 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/b (1881616539/b) is -1 [junit] 2007-12-28 12:31:23,984 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1198845083386/c (1881616539/c) (no reconstruction log) [junit] 2007-12-28 12:31:23,988 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/c (1881616539/c) is -1 [junit] 2007-12-28 12:31:24,356 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 25535445/a/6910921786770068074 with 5 entries, sequence id 33, and size 270.0 for r1,,1198845069276/a [junit] 2007-12-28 12:31:24,538 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1198845083386/d (1881616539/d) (no reconstruction log) [junit] 2007-12-28 12:31:24,540 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/d (1881616539/d) is -1 [junit] 2007-12-28 12:31:25,058 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1198845083386/e (1881616539/e) (no reconstruction log) [junit] 2007-12-28 12:31:25,060 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/e (1881616539/e) is -1 [junit] 2007-12-28 12:31:25,061 DEBUG [IPC Server handler 2 on 60000] hbase.HRegion(300): Next sequence id for region result_table,,1198845083386 is 0 [junit] 2007-12-28 12:31:25,062 INFO [IPC Server handler 2 on 60000] hbase.HRegion(328): region result_table,,1198845083386 available [junit] 2007-12-28 12:31:25,066 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1198845083386/e (1881616539/e) [junit] 2007-12-28 12:31:25,066 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1198845083386/d (1881616539/d) [junit] 2007-12-28 12:31:25,067 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1198845083386/a (1881616539/a) [junit] 2007-12-28 12:31:25,067 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1198845083386/b (1881616539/b) [junit] 2007-12-28 12:31:25,067 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1198845083386/c (1881616539/c) [junit] 2007-12-28 12:31:25,067 INFO [IPC Server handler 2 on 60000] hbase.HRegion(430): closed result_table,,1198845083386 [junit] 2007-12-28 12:31:25,068 DEBUG [IPC Server handler 2 on 60000] hbase.HLog(388): closing log writer in /hbase/hregion_1881616539/log [junit] 2007-12-28 12:31:25,321 INFO [IPC Server handler 2 on 60000] hbase.HMaster(2547): created table result_table [junit] 2007-12-28 12:31:25,322 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing a find... [junit] 2007-12-28 12:31:25,334 DEBUG [main] hbase.HConnectionManager$TableServers(782): no server address for regionname: result_table,,1198845083386, startKey: <>, encodedName(1881616539) 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-28 12:31:25,335 DEBUG [main] hbase.HConnectionManager$TableServers(680): Sleeping. Table result_table not currently being served. [junit] 2007-12-28 12:31:25,464 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 25535445/b/3432013825519979259 with 5 entries, sequence id 33, and size 270.0 for r1,,1198845069276/b [junit] 2007-12-28 12:31:25,527 INFO [IPC Server handler 3 on 60000] hbase.HMaster(1858): assigning region result_table,,1198845083386 to the only server 140.211.11.75:36498 [junit] 2007-12-28 12:31:25,530 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1198845083386 [junit] 2007-12-28 12:31:25,534 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1198845083386/a (1881616539/a) (no reconstruction log) [junit] 2007-12-28 12:31:25,537 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/a (1881616539/a) is -1 [junit] 2007-12-28 12:31:25,541 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1198845083386/b (1881616539/b) (no reconstruction log) [junit] 2007-12-28 12:31:25,543 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/b (1881616539/b) is -1 [junit] 2007-12-28 12:31:25,548 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1198845083386/c (1881616539/c) (no reconstruction log) [junit] 2007-12-28 12:31:25,551 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/c (1881616539/c) is -1 [junit] 2007-12-28 12:31:25,555 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1198845083386/d (1881616539/d) (no reconstruction log) [junit] 2007-12-28 12:31:25,557 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/d (1881616539/d) is -1 [junit] 2007-12-28 12:31:25,562 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1198845083386/e (1881616539/e) (no reconstruction log) [junit] 2007-12-28 12:31:25,563 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1198845083386/e (1881616539/e) is -1 [junit] 2007-12-28 12:31:25,564 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region result_table,,1198845083386 is 0 [junit] 2007-12-28 12:31:25,566 INFO [RegionServer:0.worker] hbase.HRegion(328): region result_table,,1198845083386 available [junit] 2007-12-28 12:31:26,263 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 25535445/c/8611322316008325712 with 5 entries, sequence id 33, and size 310.0 for r1,,1198845069276/c [junit] 2007-12-28 12:31:26,264 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r1,,1198845069276 in 2729ms, sequenceid=33 [junit] 2007-12-28 12:31:26,264 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1198845069276/a not needed. [junit] 2007-12-28 12:31:26,265 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1198845069276/b not needed. [junit] 2007-12-28 12:31:26,265 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1198845069276/c not needed. [junit] 2007-12-28 12:31:26,265 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r1,,1198845069276 does not need compaction [junit] 2007-12-28 12:31:26,536 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : result_table,,1198845083386 from 140.211.11.75:36498 [junit] 2007-12-28 12:31:26,536 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1198845083386 from 140.211.11.75:36498 [junit] 2007-12-28 12:31:26,537 INFO [IPC Server handler 1 on 60000] hbase.HMaster(1577): 140.211.11.75:36498 serving result_table,,1198845083386 [junit] 2007-12-28 12:31:26,537 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:36498 [junit] 2007-12-28 12:31:26,537 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1198845083386, startKey: <>, encodedName(1881616539) 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:36498 [junit] 2007-12-28 12:31:26,538 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1 [junit] 2007-12-28 12:31:26,538 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1198845083386 in table .META.,,1 with startcode 1198845069249 and server 140.211.11.75:36498 [junit] 2007-12-28 12:31:28,484 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r2,,1198845077965. Size 132.0 [junit] 2007-12-28 12:31:29,736 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 371709688/e/8911969066425333868 with 3 entries, sequence id 37, and size 218.0 for r2,,1198845077965/e [junit] 2007-12-28 12:31:30,344 DEBUG [main] hbase.HConnectionManager$TableServers(689): Wake. Retry finding table result_table [junit] 2007-12-28 12:31:30,359 DEBUG [main] hbase.HConnectionManager$TableServers(732): Found 1 region(s) for result_table at address: 140.211.11.75:36498, 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-28 12:31:30,389 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 371709688/d/224609502205374475 with 3 entries, sequence id 37, and size 218.0 for r2,,1198845077965/d [junit] 2007-12-28 12:31:30,390 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r2,,1198845077965 in 1906ms, sequenceid=37 [junit] 2007-12-28 12:31:30,390 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1198845077965/e not needed. [junit] 2007-12-28 12:31:30,390 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1198845077965/d not needed. [junit] 2007-12-28 12:31:30,390 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r2,,1198845077965 does not need compaction [junit] 2007-12-28 12:31:30,444 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:36498} [junit] 2007-12-28 12:31:30,469 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:30,471 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:36498} complete [junit] 2007-12-28 12:31:30,618 WARN [IPC Server handler 7 on 36472] dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system because it does not exist [junit] 2007-12-28 12:31:31,803 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 331.0 [junit] 2007-12-28 12:31:32,433 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:36498} [junit] 2007-12-28 12:31:32,457 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1198845069276, startKey: <>, encodedName(25535445) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:32,459 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1198845077965, startKey: <>, encodedName(371709688) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:32,461 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:36498} complete [junit] 2007-12-28 12:31:32,461 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned [junit] 2007-12-28 12:31:32,487 WARN [IPC Server handler 4 on 36472] 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-28 12:31:32,489 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-28 12:31:32,521 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find... [junit] 2007-12-28 12:31:32,540 DEBUG [main] hbase.HConnectionManager$TableServers(763): Found 1 servers for table r1 [junit] 2007-12-28 12:31:32,575 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,, [junit] 2007-12-28 12:31:33,176 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/9092856611308821904 with 3 entries, sequence id 38, and size 483.0 for .META.,,1/info [junit] 2007-12-28 12:31:33,177 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 1374ms, sequenceid=38 [junit] 2007-12-28 12:31:33,177 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed. [junit] 2007-12-28 12:31:33,178 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction [junit] 2007-12-28 12:31:33,204 DEBUG [Thread-190] mapred.TableInputFormat(175): split: 0->r1,, [junit] 2007-12-28 12:31:33,342 DEBUG [Thread-190] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find... [junit] 2007-12-28 12:31:33,380 DEBUG [Thread-190] hbase.HConnectionManager$TableServers(763): Found 1 servers for table r2 [junit] 2007-12-28 12:31:35,371 WARN [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting... [junit] 2007-12-28 12:31:35,573 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region result_table,,1198845083386. Size 306.0 [junit] 2007-12-28 12:31:35,980 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1881616539/e/8700702418701548965 with 3 entries, sequence id 54, and size 212.0 for result_table,,1198845083386/e [junit] 2007-12-28 12:31:36,399 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1881616539/d/8640422777972987547 with 3 entries, sequence id 54, and size 212.0 for result_table,,1198845083386/d [junit] 2007-12-28 12:31:36,784 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1881616539/a/367261922665512601 with 3 entries, sequence id 54, and size 206.0 for result_table,,1198845083386/a [junit] 2007-12-28 12:31:37,195 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1881616539/b/962108619943811215 with 3 entries, sequence id 54, and size 206.0 for result_table,,1198845083386/b [junit] 2007-12-28 12:31:37,674 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1881616539/c/8461663110252761383 with 3 entries, sequence id 54, and size 230.0 for result_table,,1198845083386/c [junit] 2007-12-28 12:31:37,675 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region result_table,,1198845083386 in 2103ms, sequenceid=54 [junit] 2007-12-28 12:31:37,675 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1198845083386/e not needed. [junit] 2007-12-28 12:31:37,675 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1198845083386/d not needed. [junit] 2007-12-28 12:31:37,676 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1198845083386/a not needed. [junit] 2007-12-28 12:31:37,676 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1198845083386/b not needed. [junit] 2007-12-28 12:31:37,676 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1198845083386/c not needed. [junit] 2007-12-28 12:31:37,676 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region result_table,,1198845083386 does not need compaction [junit] 2007-12-28 12:31:40,451 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:36498} [junit] 2007-12-28 12:31:40,479 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:40,480 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:36498} complete [junit] 2007-12-28 12:31:42,441 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:36498} [junit] 2007-12-28 12:31:42,462 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1198845069276, startKey: <>, encodedName(25535445) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:42,464 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1198845077965, startKey: <>, encodedName(371709688) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:42,466 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1198845083386, startKey: <>, encodedName(1881616539) 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:36498, startCode: 1198845069249 [junit] 2007-12-28 12:31:42,467 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:36498} complete [junit] 2007-12-28 12:31:42,467 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned [junit] 2007-12-28 12:31:45,432 INFO [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5 [junit] 2007-12-28 12:31:45,434 INFO [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5 [junit] 2007-12-28 12:31:45,436 INFO [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5 [junit] 2007-12-28 12:31:45,438 INFO [main] algebra.TestTableJoinMapReduce(244): result_table.row.count: 3 [junit] 2007-12-28 12:31:45,438 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down HBase Cluster [junit] 2007-12-28 12:31:45,438 INFO [main] hbase.HMaster(2526): Cluster shutdown requested. Starting to quiesce servers [junit] 2007-12-28 12:31:45,722 INFO [RegionServer:0] hbase.HRegionServer(774): Got quiesce server message [junit] 2007-12-28 12:31:45,723 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE : [junit] 2007-12-28 12:31:45,723 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r1,,1198845069276 [junit] 2007-12-28 12:31:45,724 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1198845069276/a (25535445/a) [junit] 2007-12-28 12:31:45,724 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1198845069276/b (25535445/b) [junit] 2007-12-28 12:31:45,724 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1198845069276/c (25535445/c) [junit] 2007-12-28 12:31:45,724 INFO [RegionServer:0.worker] hbase.HRegion(430): closed r1,,1198845069276 [junit] 2007-12-28 12:31:45,725 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r2,,1198845077965 [junit] 2007-12-28 12:31:45,725 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1198845077965/e (371709688/e) [junit] 2007-12-28 12:31:45,725 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1198845077965/d (371709688/d) [junit] 2007-12-28 12:31:45,725 INFO [RegionServer:0.worker] hbase.HRegion(430): closed r2,,1198845077965 [junit] 2007-12-28 12:31:45,726 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region result_table,,1198845083386 [junit] 2007-12-28 12:31:45,726 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1198845083386/e (1881616539/e) [junit] 2007-12-28 12:31:45,726 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1198845083386/d (1881616539/d) [junit] 2007-12-28 12:31:45,726 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1198845083386/a (1881616539/a) [junit] 2007-12-28 12:31:45,726 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1198845083386/b (1881616539/b) [junit] 2007-12-28 12:31:45,727 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1198845083386/c (1881616539/c) [junit] 2007-12-28 12:31:45,727 INFO [RegionServer:0.worker] hbase.HRegion(430): closed result_table,,1198845083386 [junit] 2007-12-28 12:31:46,731 INFO [IPC Server handler 1 on 60000] hbase.HMaster(1372): Region server 140.211.11.75:36498 quiesced [junit] 2007-12-28 12:31:46,731 INFO [IPC Server handler 1 on 60000] hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown [junit] 2007-12-28 12:31:46,732 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1206): telling root scanner to stop [junit] 2007-12-28 12:31:46,732 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1214): telling meta scanner to stop [junit] 2007-12-28 12:31:46,732 INFO [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner exiting [junit] 2007-12-28 12:31:46,732 INFO [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner exiting [junit] 2007-12-28 12:31:46,732 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1222): meta and root scanners notified [junit] 2007-12-28 12:31:46,734 INFO [RegionServer:0] hbase.HRegionServer(768): Got regionserver stop message [junit] 2007-12-28 12:31:46,734 INFO [RegionServer:0] hbase.Leases(109): RegionServer:0 closing leases [junit] 2007-12-28 12:31:46,734 INFO [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker exiting [junit] 2007-12-28 12:31:46,734 INFO [RegionServer:0] hbase.Leases(125): RegionServer:0 closed leases [junit] 2007-12-28 12:31:46,735 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region -ROOT-,,0 [junit] 2007-12-28 12:31:46,736 INFO [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting [junit] 2007-12-28 12:31:46,736 INFO [RegionServer:0.compactor] hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting [junit] 2007-12-28 12:31:46,736 DEBUG [RegionServer:0] hbase.HStore(880): closed -ROOT-,,0/info (70236052/info) [junit] 2007-12-28 12:31:46,736 INFO [RegionServer:0.splitter] hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting [junit] 2007-12-28 12:31:46,737 INFO [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0 [junit] 2007-12-28 12:31:46,738 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region .META.,,1 [junit] 2007-12-28 12:31:46,738 DEBUG [RegionServer:0] hbase.HStore(880): closed .META.,,1/info (1028785192/info) [junit] 2007-12-28 12:31:46,738 INFO [RegionServer:0] hbase.HRegion(430): closed .META.,,1 [junit] 2007-12-28 12:31:46,739 DEBUG [RegionServer:0] hbase.HLog(388): closing log writer in /hbase/log_140.211.11.75_1198845069249_36498 [junit] 2007-12-28 12:31:46,880 INFO [RegionServer:0] hbase.HRegionServer(888): telling master that region server is shutting down at: 140.211.11.75:36498 [junit] 2007-12-28 12:31:46,882 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1339): Region server 140.211.11.75:36498: MSG_REPORT_EXITING -- cancelling lease [junit] 2007-12-28 12:31:46,882 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1481): Cancelling lease for 140.211.11.75:36498 [junit] 2007-12-28 12:31:46,882 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1346): Region server 140.211.11.75:36498: MSG_REPORT_EXITING -- lease cancelled [junit] 2007-12-28 12:31:46,883 INFO [RegionServer:0] hbase.HRegionServer(895): stopping server at: 140.211.11.75:36498 [junit] 2007-12-28 12:31:47,740 DEBUG [HMaster] hbase.HMaster(1206): telling root scanner to stop [junit] 2007-12-28 12:31:47,740 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1202): worker thread exiting [junit] 2007-12-28 12:31:47,740 DEBUG [HMaster] hbase.HMaster(1214): telling meta scanner to stop [junit] 2007-12-28 12:31:47,741 DEBUG [HMaster] hbase.HMaster(1222): meta and root scanners notified [junit] 2007-12-28 12:31:47,741 INFO [RegionServer:0] hbase.HRegionServer(900): RegionServer:0 exiting [junit] 2007-12-28 12:31:47,741 INFO [HMaster] hbase.Leases(109): HMaster closing leases [junit] 2007-12-28 12:31:47,742 INFO [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker exiting [junit] 2007-12-28 12:31:47,743 INFO [HMaster] hbase.Leases(125): HMaster closed leases [junit] 2007-12-28 12:31:47,743 INFO [HMaster] hbase.HMaster(1139): HMaster main thread exiting [junit] 2007-12-28 12:31:47,743 INFO [main] hbase.LocalHBaseCluster(238): Shutdown HMaster 1 region server(s) [junit] 2007-12-28 12:31:47,932 INFO [main] hbase.StaticTestEnvironment(133): Shutting down FileSystem [junit] 2007-12-28 12:31:47,980 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: 47.221 sec [junit] 2007-12-28 12:31:48,750 INFO [main] hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread. [junit] 2007-12-28 12:31:48,750 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.267 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.049 sec [junit] Running org.onelab.test.TestFilter [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.051 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: 77 minutes 44 seconds Recording fingerprints Publishing Javadoc Recording test results Updating HADOOP-2485 Updating HADOOP-2149