See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/352/changes
Changes: [stack] HADOOP-2505 formatter classes missing apache license ------------------------------------------ [...truncated 49270 lines...] [junit] 2008-01-01 12:47:06,234 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting r2,,1199191625921/e (2130032715/e) (no reconstruction log) [junit] 2008-01-01 12:47:06,236 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore r2,,1199191625921/e (2130032715/e) is -1 [junit] 2008-01-01 12:47:06,237 DEBUG [IPC Server handler 2 on 60000] hbase.HRegion(300): Next sequence id for region r2,,1199191625921 is 0 [junit] 2008-01-01 12:47:06,239 INFO [IPC Server handler 2 on 60000] hbase.HRegion(328): region r2,,1199191625921 available [junit] 2008-01-01 12:47:06,240 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed r2,,1199191625921/e (2130032715/e) [junit] 2008-01-01 12:47:06,241 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed r2,,1199191625921/d (2130032715/d) [junit] 2008-01-01 12:47:06,241 INFO [IPC Server handler 2 on 60000] hbase.HRegion(430): closed r2,,1199191625921 [junit] 2008-01-01 12:47:06,241 DEBUG [IPC Server handler 2 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_2130032715/log [junit] 2008-01-01 12:47:06,734 INFO [IPC Server handler 2 on 60000] hbase.HMaster(2547): created table r2 [junit] 2008-01-01 12:47:06,735 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find... [junit] 2008-01-01 12:47:06,738 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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] 2008-01-01 12:47:06,739 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table r2 not currently being served. [junit] 2008-01-01 12:47:07,013 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0 [junit] 2008-01-01 12:47:07,054 INFO [IPC Server handler 1 on 60000] hbase.HMaster(1858): assigning region r2,,1199191625921 to the only server 140.211.11.75:54586 [junit] 2008-01-01 12:47:07,055 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : r2,,1199191625921 [junit] 2008-01-01 12:47:07,060 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199191625921/d (2130032715/d) (no reconstruction log) [junit] 2008-01-01 12:47:07,062 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199191625921/d (2130032715/d) is -1 [junit] 2008-01-01 12:47:07,068 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199191625921/e (2130032715/e) (no reconstruction log) [junit] 2008-01-01 12:47:07,071 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199191625921/e (2130032715/e) is -1 [junit] 2008-01-01 12:47:07,072 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region r2,,1199191625921 is 0 [junit] 2008-01-01 12:47:07,085 INFO [RegionServer:0.worker] hbase.HRegion(328): region r2,,1199191625921 available [junit] 2008-01-01 12:47:07,342 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 70236052/info/3579751099364642485 with 2 entries, sequence id 23, and size 230.0 for -ROOT-,,0/info [junit] 2008-01-01 12:47:07,342 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 329ms, sequenceid=23 [junit] 2008-01-01 12:47:07,343 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore -ROOT-,,0/info not needed. [junit] 2008-01-01 12:47:07,343 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region -ROOT-,,0 does not need compaction [junit] 2008-01-01 12:47:08,013 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} [junit] 2008-01-01 12:47:08,048 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:08,051 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} complete [junit] 2008-01-01 12:47:08,064 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : r2,,1199191625921 from 140.211.11.75:54586 [junit] 2008-01-01 12:47:08,064 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : r2,,1199191625921 from 140.211.11.75:54586 [junit] 2008-01-01 12:47:08,065 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1577): 140.211.11.75:54586 serving r2,,1199191625921 [junit] 2008-01-01 12:47:08,065 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:54586 [junit] 2008-01-01 12:47:08,066 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586 [junit] 2008-01-01 12:47:08,066 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1 [junit] 2008-01-01 12:47:08,066 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row r2,,1199191625921 in table .META.,,1 with startcode 1199191616628 and server 140.211.11.75:54586 [junit] 2008-01-01 12:47:08,993 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 472.0 [junit] 2008-01-01 12:47:09,396 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/3309222824595699538 with 6 entries, sequence id 26, and size 666.0 for .META.,,1/info [junit] 2008-01-01 12:47:09,396 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 403ms, sequenceid=26 [junit] 2008-01-01 12:47:09,397 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed. [junit] 2008-01-01 12:47:09,397 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction [junit] 2008-01-01 12:47:10,003 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} [junit] 2008-01-01 12:47:10,034 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199191616648, startKey: <>, encodedName(1778447438) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:10,036 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:10,038 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} complete [junit] 2008-01-01 12:47:10,038 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned [junit] 2008-01-01 12:47:11,033 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r1,,1199191616648. Size 310.0 [junit] 2008-01-01 12:47:11,375 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1778447438/a/7291194348426289937 with 5 entries, sequence id 27, and size 270.0 for r1,,1199191616648/a [junit] 2008-01-01 12:47:11,736 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1778447438/b/637892970681593511 with 5 entries, sequence id 27, and size 270.0 for r1,,1199191616648/b [junit] 2008-01-01 12:47:11,742 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table r2 [junit] 2008-01-01 12:47:11,761 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for r2 at address: 140.211.11.75:54586, 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] 2008-01-01 12:47:12,017 INFO [IPC Server handler 1 on 60000] hbase.HLog(313): new log writer created at /hbase/hregion_61750783/log/hlog.dat.000 [junit] 2008-01-01 12:47:12,158 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/a (61750783/a) (no reconstruction log) [junit] 2008-01-01 12:47:12,160 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/a (61750783/a) is -1 [junit] 2008-01-01 12:47:12,206 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1778447438/c/1839596736998806994 with 5 entries, sequence id 27, and size 310.0 for r1,,1199191616648/c [junit] 2008-01-01 12:47:12,206 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r1,,1199191616648 in 1174ms, sequenceid=27 [junit] 2008-01-01 12:47:12,207 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199191616648/a not needed. [junit] 2008-01-01 12:47:12,207 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199191616648/b not needed. [junit] 2008-01-01 12:47:12,207 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199191616648/c not needed. [junit] 2008-01-01 12:47:12,208 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r1,,1199191616648 does not need compaction [junit] 2008-01-01 12:47:12,258 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/b (61750783/b) (no reconstruction log) [junit] 2008-01-01 12:47:12,260 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/b (61750783/b) is -1 [junit] 2008-01-01 12:47:12,330 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/c (61750783/c) (no reconstruction log) [junit] 2008-01-01 12:47:12,332 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/c (61750783/c) is -1 [junit] 2008-01-01 12:47:12,405 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/d (61750783/d) (no reconstruction log) [junit] 2008-01-01 12:47:12,407 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/d (61750783/d) is -1 [junit] 2008-01-01 12:47:12,480 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/e (61750783/e) (no reconstruction log) [junit] 2008-01-01 12:47:12,482 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/e (61750783/e) is -1 [junit] 2008-01-01 12:47:12,484 DEBUG [IPC Server handler 1 on 60000] hbase.HRegion(300): Next sequence id for region result_table,,1199191631766 is 0 [junit] 2008-01-01 12:47:12,486 INFO [IPC Server handler 1 on 60000] hbase.HRegion(328): region result_table,,1199191631766 available [junit] 2008-01-01 12:47:12,487 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/e (61750783/e) [junit] 2008-01-01 12:47:12,488 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/d (61750783/d) [junit] 2008-01-01 12:47:12,488 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/a (61750783/a) [junit] 2008-01-01 12:47:12,488 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/b (61750783/b) [junit] 2008-01-01 12:47:12,489 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/c (61750783/c) [junit] 2008-01-01 12:47:12,489 INFO [IPC Server handler 1 on 60000] hbase.HRegion(430): closed result_table,,1199191631766 [junit] 2008-01-01 12:47:12,489 DEBUG [IPC Server handler 1 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_61750783/log [junit] 2008-01-01 12:47:12,579 INFO [IPC Server handler 1 on 60000] hbase.HMaster(2547): created table result_table [junit] 2008-01-01 12:47:12,580 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing a find... [junit] 2008-01-01 12:47:12,597 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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] 2008-01-01 12:47:12,599 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table result_table not currently being served. [junit] 2008-01-01 12:47:13,113 INFO [IPC Server handler 0 on 60000] hbase.HMaster(1858): assigning region result_table,,1199191631766 to the only server 140.211.11.75:54586 [junit] 2008-01-01 12:47:13,115 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1199191631766 [junit] 2008-01-01 12:47:13,120 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/a (61750783/a) (no reconstruction log) [junit] 2008-01-01 12:47:13,123 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/a (61750783/a) is -1 [junit] 2008-01-01 12:47:13,127 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/b (61750783/b) (no reconstruction log) [junit] 2008-01-01 12:47:13,129 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/b (61750783/b) is -1 [junit] 2008-01-01 12:47:13,133 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/c (61750783/c) (no reconstruction log) [junit] 2008-01-01 12:47:13,135 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/c (61750783/c) is -1 [junit] 2008-01-01 12:47:13,139 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/d (61750783/d) (no reconstruction log) [junit] 2008-01-01 12:47:13,141 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/d (61750783/d) is -1 [junit] 2008-01-01 12:47:13,146 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/e (61750783/e) (no reconstruction log) [junit] 2008-01-01 12:47:13,148 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/e (61750783/e) is -1 [junit] 2008-01-01 12:47:13,149 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region result_table,,1199191631766 is 0 [junit] 2008-01-01 12:47:13,151 INFO [RegionServer:0.worker] hbase.HRegion(328): region result_table,,1199191631766 available [junit] 2008-01-01 12:47:14,124 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : result_table,,1199191631766 from 140.211.11.75:54586 [junit] 2008-01-01 12:47:14,124 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1199191631766 from 140.211.11.75:54586 [junit] 2008-01-01 12:47:14,124 INFO [IPC Server handler 3 on 60000] hbase.HMaster(1577): 140.211.11.75:54586 serving result_table,,1199191631766 [junit] 2008-01-01 12:47:14,125 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:54586 [junit] 2008-01-01 12:47:14,126 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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:54586 [junit] 2008-01-01 12:47:14,126 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1 [junit] 2008-01-01 12:47:14,126 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1199191631766 in table .META.,,1 with startcode 1199191616628 and server 140.211.11.75:54586 [junit] 2008-01-01 12:47:17,092 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r2,,1199191625921. Size 132.0 [junit] 2008-01-01 12:47:17,434 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 2130032715/e/7830696023592587082 with 3 entries, sequence id 37, and size 218.0 for r2,,1199191625921/e [junit] 2008-01-01 12:47:17,601 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table result_table [junit] 2008-01-01 12:47:17,633 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for result_table at address: 140.211.11.75:54586, 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] 2008-01-01 12:47:17,761 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 2130032715/d/4493033548954924806 with 3 entries, sequence id 37, and size 218.0 for r2,,1199191625921/d [junit] 2008-01-01 12:47:17,761 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r2,,1199191625921 in 669ms, sequenceid=37 [junit] 2008-01-01 12:47:17,763 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199191625921/e not needed. [junit] 2008-01-01 12:47:17,763 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199191625921/d not needed. [junit] 2008-01-01 12:47:17,763 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r2,,1199191625921 does not need compaction [junit] 2008-01-01 12:47:17,894 WARN [IPC Server handler 9 on 54567] dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system because it does not exist [junit] 2008-01-01 12:47:18,021 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} [junit] 2008-01-01 12:47:18,045 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:18,047 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} complete [junit] 2008-01-01 12:47:19,401 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 331.0 [junit] 2008-01-01 12:47:19,754 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/2655026151830195016 with 3 entries, sequence id 38, and size 483.0 for .META.,,1/info [junit] 2008-01-01 12:47:19,755 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 354ms, sequenceid=38 [junit] 2008-01-01 12:47:19,756 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed. [junit] 2008-01-01 12:47:19,756 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction [junit] 2008-01-01 12:47:19,774 WARN [IPC Server handler 0 on 54567] 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] 2008-01-01 12:47:19,775 WARN [main] mapred.JobClient(496): No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String). [junit] 2008-01-01 12:47:19,807 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find... [junit] 2008-01-01 12:47:19,847 DEBUG [main] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r1 [junit] 2008-01-01 12:47:19,880 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,, [junit] 2008-01-01 12:47:20,011 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} [junit] 2008-01-01 12:47:20,034 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199191616648, startKey: <>, encodedName(1778447438) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:20,036 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:20,039 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:20,042 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} complete [junit] 2008-01-01 12:47:20,042 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned [junit] 2008-01-01 12:47:20,259 DEBUG [Thread-199] mapred.TableInputFormat(175): split: 0->r1,, [junit] 2008-01-01 12:47:20,525 DEBUG [Thread-199] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find... [junit] 2008-01-01 12:47:20,547 DEBUG [Thread-199] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r2 [junit] 2008-01-01 12:47:22,350 WARN [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting... [junit] 2008-01-01 12:47:23,161 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region result_table,,1199191631766. Size 306.0 [junit] 2008-01-01 12:47:23,525 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/e/8628481918409188180 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199191631766/e [junit] 2008-01-01 12:47:23,891 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/d/802572002926107038 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199191631766/d [junit] 2008-01-01 12:47:24,315 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/a/8509768190983818557 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199191631766/a [junit] 2008-01-01 12:47:24,663 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/b/1278437776700412546 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199191631766/b [junit] 2008-01-01 12:47:24,989 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/c/4518460444595148452 with 3 entries, sequence id 54, and size 230.0 for result_table,,1199191631766/c [junit] 2008-01-01 12:47:24,990 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region result_table,,1199191631766 in 1829ms, sequenceid=54 [junit] 2008-01-01 12:47:24,990 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/e not needed. [junit] 2008-01-01 12:47:24,990 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/d not needed. [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/a not needed. [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/b not needed. [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/c not needed. [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region result_table,,1199191631766 does not need compaction [junit] 2008-01-01 12:47:28,030 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} [junit] 2008-01-01 12:47:28,053 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:28,055 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} complete [junit] 2008-01-01 12:47:30,020 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} [junit] 2008-01-01 12:47:30,041 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199191616648, startKey: <>, encodedName(1778447438) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:30,043 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:30,045 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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:54586, startCode: 1199191616628 [junit] 2008-01-01 12:47:30,047 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} complete [junit] 2008-01-01 12:47:30,047 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned [junit] 2008-01-01 12:47:32,435 INFO [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5 [junit] 2008-01-01 12:47:32,437 INFO [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5 [junit] 2008-01-01 12:47:32,439 INFO [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5 [junit] 2008-01-01 12:47:32,441 INFO [main] algebra.TestTableJoinMapReduce(244): result_table.row.count: 3 [junit] 2008-01-01 12:47:32,441 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down HBase Cluster [junit] 2008-01-01 12:47:32,442 INFO [main] hbase.HMaster(2526): Cluster shutdown requested. Starting to quiesce servers [junit] 2008-01-01 12:47:33,451 INFO [RegionServer:0] hbase.HRegionServer(774): Got quiesce server message [junit] 2008-01-01 12:47:33,452 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE : [junit] 2008-01-01 12:47:33,452 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r1,,1199191616648 [junit] 2008-01-01 12:47:33,453 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199191616648/a (1778447438/a) [junit] 2008-01-01 12:47:33,453 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199191616648/b (1778447438/b) [junit] 2008-01-01 12:47:33,453 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199191616648/c (1778447438/c) [junit] 2008-01-01 12:47:33,453 INFO [RegionServer:0.worker] hbase.HRegion(430): closed r1,,1199191616648 [junit] 2008-01-01 12:47:33,454 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r2,,1199191625921 [junit] 2008-01-01 12:47:33,454 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199191625921/e (2130032715/e) [junit] 2008-01-01 12:47:33,454 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199191625921/d (2130032715/d) [junit] 2008-01-01 12:47:33,454 INFO [RegionServer:0.worker] hbase.HRegion(430): closed r2,,1199191625921 [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region result_table,,1199191631766 [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/e (61750783/e) [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/d (61750783/d) [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/a (61750783/a) [junit] 2008-01-01 12:47:33,456 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/b (61750783/b) [junit] 2008-01-01 12:47:33,456 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/c (61750783/c) [junit] 2008-01-01 12:47:33,456 INFO [RegionServer:0.worker] hbase.HRegion(430): closed result_table,,1199191631766 [junit] 2008-01-01 12:47:34,460 INFO [IPC Server handler 3 on 60000] hbase.HMaster(1372): Region server 140.211.11.75:54586 quiesced [junit] 2008-01-01 12:47:34,460 INFO [IPC Server handler 3 on 60000] hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown [junit] 2008-01-01 12:47:34,461 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1206): telling root scanner to stop [junit] 2008-01-01 12:47:34,461 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1214): telling meta scanner to stop [junit] 2008-01-01 12:47:34,461 INFO [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner exiting [junit] 2008-01-01 12:47:34,461 INFO [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner exiting [junit] 2008-01-01 12:47:34,461 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1222): meta and root scanners notified [junit] 2008-01-01 12:47:34,463 INFO [RegionServer:0] hbase.HRegionServer(768): Got regionserver stop message [junit] 2008-01-01 12:47:34,463 INFO [RegionServer:0] hbase.Leases(109): RegionServer:0 closing leases [junit] 2008-01-01 12:47:34,464 INFO [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker exiting [junit] 2008-01-01 12:47:34,464 INFO [RegionServer:0] hbase.Leases(125): RegionServer:0 closed leases [junit] 2008-01-01 12:47:34,465 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region -ROOT-,,0 [junit] 2008-01-01 12:47:34,466 DEBUG [RegionServer:0] hbase.HStore(880): closed -ROOT-,,0/info (70236052/info) [junit] 2008-01-01 12:47:34,466 INFO [RegionServer:0.splitter] hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting [junit] 2008-01-01 12:47:34,467 INFO [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0 [junit] 2008-01-01 12:47:34,465 INFO [RegionServer:0.compactor] hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting [junit] 2008-01-01 12:47:34,468 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region .META.,,1 [junit] 2008-01-01 12:47:34,465 INFO [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting [junit] 2008-01-01 12:47:34,468 DEBUG [RegionServer:0] hbase.HStore(880): closed .META.,,1/info (1028785192/info) [junit] 2008-01-01 12:47:34,469 INFO [RegionServer:0] hbase.HRegion(430): closed .META.,,1 [junit] 2008-01-01 12:47:34,469 DEBUG [RegionServer:0] hbase.HLog(399): closing log writer in /hbase/log_140.211.11.75_1199191616628_54586 [junit] 2008-01-01 12:47:34,605 INFO [RegionServer:0] hbase.HRegionServer(888): telling master that region server is shutting down at: 140.211.11.75:54586 [junit] 2008-01-01 12:47:34,607 DEBUG [IPC Server handler 2 on 60000] hbase.HMaster(1339): Region server 140.211.11.75:54586: MSG_REPORT_EXITING -- cancelling lease [junit] 2008-01-01 12:47:34,607 INFO [IPC Server handler 2 on 60000] hbase.HMaster(1481): Cancelling lease for 140.211.11.75:54586 [junit] 2008-01-01 12:47:34,607 INFO [IPC Server handler 2 on 60000] hbase.HMaster(1346): Region server 140.211.11.75:54586: MSG_REPORT_EXITING -- lease cancelled [junit] 2008-01-01 12:47:34,608 INFO [RegionServer:0] hbase.HRegionServer(895): stopping server at: 140.211.11.75:54586 [junit] 2008-01-01 12:47:35,359 DEBUG [HMaster] hbase.HMaster(1206): telling root scanner to stop [junit] 2008-01-01 12:47:35,359 DEBUG [HMaster] hbase.HMaster(1214): telling meta scanner to stop [junit] 2008-01-01 12:47:35,360 DEBUG [HMaster] hbase.HMaster(1222): meta and root scanners notified [junit] 2008-01-01 12:47:35,360 INFO [HMaster] hbase.Leases(109): HMaster closing leases [junit] 2008-01-01 12:47:35,361 INFO [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker exiting [junit] 2008-01-01 12:47:35,361 INFO [HMaster] hbase.Leases(125): HMaster closed leases [junit] 2008-01-01 12:47:35,361 INFO [HMaster] hbase.HMaster(1139): HMaster main thread exiting [junit] 2008-01-01 12:47:35,469 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1202): worker thread exiting [junit] 2008-01-01 12:47:35,469 INFO [RegionServer:0] hbase.HRegionServer(900): RegionServer:0 exiting [junit] 2008-01-01 12:47:35,470 INFO [main] hbase.LocalHBaseCluster(238): Shutdown HMaster 1 region server(s) [junit] 2008-01-01 12:47:35,656 INFO [main] hbase.StaticTestEnvironment(133): Shutting down FileSystem [junit] 2008-01-01 12:47:36,369 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: 54.37 sec [junit] 2008-01-01 12:47:37,181 INFO [main] hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread. [junit] 2008-01-01 12:47:37,181 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.236 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.048 sec [junit] Running org.onelab.test.TestFilter [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.054 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: 92 minutes 50 seconds Recording fingerprints Publishing Javadoc Recording test results Updating HADOOP-2505