See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/363/changes
Changes:
[dhruba] HADOOP-2540. fsck reports missing blocks incorrectly. (dhruba)
[rangadi] HADOOP-2464. Unit tests for chmod, chown, and chgrp using DFS. (Raghu
Angadi)
[cutting] HADOOP-2567. Add FileSystem#getHomeDirectory().
[cutting] HADOOP-1873. Replace tabs with spaces.
[acmurthy] HADOOP-1965. Interleave sort/spill in teh map-task along with calls
to the Mapper.map method. This is done by splitting the 'io.sort.mb' buffer
into two and using one half for collecting map-outputs and the other half for
sort/spill. Contributed by Amar Kamat.
[dhruba] HADOOP-2562. globPaths supports {ab,cd}. (Hairong Kuang via dhruba)
[shv] HADOOP-1873. Implement user permissions for Map/Reduce. (Hairong Kuang
via shv)
[stack] HADOOP-2558 fixes for build up on hudson
Part 5. Fix NPE in make multiregion.
[stack] HADOOP-2558 fixes for build up on hudson
[stack] HADOOP-2389 Provide multiple language bindings for HBase (Thrift)
Added thrift jar in advance so HADOOP-2389 patch will build on hudson.
[acmurthy] HADOOP-2131. Allow finer-grained control over speculative-execution.
Now users can set it for maps and reduces independently. Contributed by
Amareshwari Sri Ramadasu.
[acmurthy] HADOOP-2487. Added an option to get statuses for all submitted/run
jobs. This information can be used to develop tools for analysing jobs.
Contributed by Amareshwari Sri Ramadasu.
------------------------------------------
[...truncated 52741 lines...]
[junit] 2008-01-12 15:07:30,101 INFO [main] hbase.HLog(313): new log
writer created at /hbase/hregion_1028785192/log/hlog.dat.000
[junit] 2008-01-12 15:07:30,961 DEBUG [main] hbase.HStore(599): starting
.META.,,1/info (1028785192/info) (no reconstruction log)
[junit] 2008-01-12 15:07:30,965 DEBUG [main] hbase.HStore(627): maximum
sequence id for hstore .META.,,1/info (1028785192/info) is -1
[junit] 2008-01-12 15:07:30,966 DEBUG [main] hbase.HRegion(300): Next
sequence id for region .META.,,1 is 0
[junit] 2008-01-12 15:07:30,969 INFO [main] hbase.HRegion(328): region
.META.,,1 available
[junit] 2008-01-12 15:07:30,971 DEBUG [main] hbase.HRegion(837): Started
memcache flush for region -ROOT-,,0. Size 92.0
[junit] 2008-01-12 15:07:36,782 DEBUG [main] hbase.HStore(970): Added
70236052/info/4250053859867491066 with 1 entries, sequence id 1, and size 216.0
for -ROOT-,,0/info
[junit] 2008-01-12 15:07:36,785 DEBUG [main] hbase.HRegion(943): Finished
memcache flush for region -ROOT-,,0 in 5815ms, sequenceid=1
[junit] 2008-01-12 15:07:36,786 DEBUG [main] hbase.HStore(878): closed
-ROOT-,,0/info (70236052/info)
[junit] 2008-01-12 15:07:36,786 INFO [main] hbase.HRegion(430): closed
-ROOT-,,0
[junit] 2008-01-12 15:07:36,787 DEBUG [main] hbase.HLog(399): closing log
writer in /hbase/hregion_70236052/log
[junit] 2008-01-12 15:07:38,886 DEBUG [main] hbase.HStore(878): closed
.META.,,1/info (1028785192/info)
[junit] 2008-01-12 15:07:38,886 INFO [main] hbase.HRegion(430): closed
.META.,,1
[junit] 2008-01-12 15:07:38,887 DEBUG [main] hbase.HLog(399): closing log
writer in /hbase/hregion_1028785192/log
[junit] 2008-01-12 15:07:41,076 INFO [main] hbase.HMaster(952): HMaster
initialized on 127.0.0.1:60000
[junit] 2008-01-12 15:07:41,081 DEBUG [RegionServer:0]
hbase.HRegionServer(1060): Telling master at 127.0.0.1:60000 that we are up
[junit] 2008-01-12 15:07:41,085 DEBUG [HMaster] hbase.HMaster(1185):
Started service threads
[junit] 2008-01-12 15:07:41,088 INFO [IPC Server handler 3 on 60000]
hbase.HMaster(1268): received start message from: 140.211.11.75:44182
[junit] 2008-01-12 15:07:41,089 INFO [main] shell.TestHBaseShell(161):
Running command: create table testInsertSelectDelete (testInsertSelectDelete);
[junit] 2008-01-12 15:07:41,090 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-12 15:07:41,092 DEBUG [RegionServer:0]
hbase.HRegionServer(1077): Done telling master we are up
[junit] 2008-01-12 15:07:41,093 DEBUG [RegionServer:0]
hbase.HRegionServer(913): Config from master: fs.default.name=localhost:44150
[junit] 2008-01-12 15:07:41,094 DEBUG [RegionServer:0]
hbase.HRegionServer(913): Config from master: hbase.rootdir=/hbase
[junit] 2008-01-12 15:07:41,094 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for .META.. Doing a
find...
[junit] 2008-01-12 15:07:41,094 INFO [RegionServer:0]
hbase.HRegionServer(933): Root dir: /hbase
[junit] 2008-01-12 15:07:41,097 DEBUG [main]
hbase.HConnectionManager$TableServers(581): Sleeping. Waiting for root region.
[junit] 2008-01-12 15:07:41,101 DEBUG [RegionServer:0]
hbase.HRegionServer(938): Log dir /hbase/log_140.211.11.75_1200150461080_44182
[junit] 2008-01-12 15:07:41,877 INFO [RegionServer:0] hbase.HLog(313): new
log writer created at /hbase/log_140.211.11.75_1200150461080_44182/hlog.dat.000
[junit] 2008-01-12 15:07:41,881 INFO [RegionServer:0]
hbase.HRegionServer(992): HRegionServer started at: 140.211.11.75:44182
[junit] 2008-01-12 15:07:41,883 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1863): assigning region -ROOT-,,0 to the only server
140.211.11.75:44182
[junit] 2008-01-12 15:07:41,885 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN : -ROOT-,,0
[junit] 2008-01-12 15:07:41,890 DEBUG [RegionServer:0.worker]
hbase.HStore(599): starting -ROOT-,,0/info (70236052/info) (no reconstruction
log)
[junit] 2008-01-12 15:07:41,903 DEBUG [RegionServer:0.worker]
hbase.HStore(627): maximum sequence id for hstore -ROOT-,,0/info
(70236052/info) is 1
[junit] 2008-01-12 15:07:41,923 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region -ROOT-,,0 is 2
[junit] 2008-01-12 15:07:41,925 INFO [RegionServer:0.worker]
hbase.HRegion(328): region -ROOT-,,0 available
[junit] 2008-01-12 15:07:41,925 DEBUG [RegionServer:0.worker]
hbase.HLog(265): changing sequence number from 0 to 2
[junit] 2008-01-12 15:07:42,891 DEBUG [IPC Server handler 0 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN : -ROOT-,,0 from
140.211.11.75:44182
[junit] 2008-01-12 15:07:42,892 DEBUG [IPC Server handler 0 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_OPEN : -ROOT-,,0 from
140.211.11.75:44182
[junit] 2008-01-12 15:07:42,893 INFO [IPC Server handler 0 on 60000]
hbase.HMaster(1582): 140.211.11.75:44182 serving -ROOT-,,0
[junit] 2008-01-12 15:07:42,894 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:44182}
[junit] 2008-01-12 15:07:42,913 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: , startCode: -1
[junit] 2008-01-12 15:07:42,913 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(469): Current assignment of .META.,,1 is no good:
storedInfo: null, startCode: -1, storedInfo.startCode: -1, unassignedRegions:
false, pendingRegions: false
[junit] 2008-01-12 15:07:42,916 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:44182} complete
[junit] 2008-01-12 15:07:42,917 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:44182}
[junit] 2008-01-12 15:07:42,933 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: , startCode: -1
[junit] 2008-01-12 15:07:42,936 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:44182} complete
[junit] 2008-01-12 15:07:43,901 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(1863): assigning region .META.,,1 to the only server
140.211.11.75:44182
[junit] 2008-01-12 15:07:43,902 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN : .META.,,1
[junit] 2008-01-12 15:07:43,908 DEBUG [RegionServer:0.worker]
hbase.HStore(599): starting .META.,,1/info (1028785192/info) (no reconstruction
log)
[junit] 2008-01-12 15:07:43,913 DEBUG [RegionServer:0.worker]
hbase.HStore(627): maximum sequence id for hstore .META.,,1/info
(1028785192/info) is -1
[junit] 2008-01-12 15:07:43,915 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region .META.,,1 is 0
[junit] 2008-01-12 15:07:43,917 INFO [RegionServer:0.worker]
hbase.HRegion(328): region .META.,,1 available
[junit] 2008-01-12 15:07:44,911 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN : .META.,,1 from
140.211.11.75:44182
[junit] 2008-01-12 15:07:44,912 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_OPEN : .META.,,1 from
140.211.11.75:44182
[junit] 2008-01-12 15:07:44,912 INFO [IPC Server handler 3 on 60000]
hbase.HMaster(1582): 140.211.11.75:44182 serving .META.,,1
[junit] 2008-01-12 15:07:44,913 DEBUG [HMaster] hbase.HMaster(1057): Main
processing loop: PendingOpenOperation from 140.211.11.75:44182
[junit] 2008-01-12 15:07:44,914 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2463): 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}}} open on 140.211.11.75:44182
[junit] 2008-01-12 15:07:44,914 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2477): updating row .META.,,1 in table
-ROOT-,,0 with startcode 1200150461080 and server 140.211.11.75:44182
[junit] 2008-01-12 15:07:44,917 DEBUG [HMaster]
hbase.HMaster$ProcessRegionOpen(2495): Adding {regionname: .META.,,1, startKey:
<>, server: 140.211.11.75:44182} to regions to scan
[junit] 2008-01-12 15:07:44,918 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:44182}
[junit] 2008-01-12 15:07:44,922 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:44182} complete
[junit] 2008-01-12 15:07:44,923 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:44182}
[junit] 2008-01-12 15:07:44,926 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:44182} complete
[junit] 2008-01-12 15:07:44,927 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(768): all meta regions scanned
[junit] 2008-01-12 15:07:46,099 DEBUG [main]
hbase.HConnectionManager$TableServers(585): Wake. Retry finding root region.
[junit] 2008-01-12 15:07:46,124 DEBUG [main]
hbase.HConnectionManager$TableServers(728): Found 1 region(s) for .META. at
address: 140.211.11.75:44182, regioninfo: regionname: -ROOT-,,0, startKey: <>,
encodedName(70236052) tableDesc: {name: -ROOT-, families: {info:={name: info,
max versions: 1, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}}}
[junit] 2008-01-12 15:07:47,692 INFO [IPC Server handler 0 on 60000]
hbase.HLog(313): new log writer created at
/hbase/hregion_1218645802/log/hlog.dat.000
[junit] 2008-01-12 15:07:48,903 DEBUG [IPC Server handler 0 on 60000]
hbase.HStore(599): starting
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
(1218645802/testInsertSelectDelete) (no reconstruction log)
[junit] 2008-01-12 15:07:48,907 DEBUG [IPC Server handler 0 on 60000]
hbase.HStore(627): maximum sequence id for hstore
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
(1218645802/testInsertSelectDelete) is -1
[junit] 2008-01-12 15:07:48,908 DEBUG [IPC Server handler 0 on 60000]
hbase.HRegion(300): Next sequence id for region
testInsertSelectDelete,,1200150466130 is 0
[junit] 2008-01-12 15:07:48,912 INFO [IPC Server handler 0 on 60000]
hbase.HRegion(328): region testInsertSelectDelete,,1200150466130 available
[junit] 2008-01-12 15:07:48,915 DEBUG [IPC Server handler 0 on 60000]
hbase.HStore(878): closed
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
(1218645802/testInsertSelectDelete)
[junit] 2008-01-12 15:07:48,916 INFO [IPC Server handler 0 on 60000]
hbase.HRegion(430): closed testInsertSelectDelete,,1200150466130
[junit] 2008-01-12 15:07:48,917 DEBUG [IPC Server handler 0 on 60000]
hbase.HLog(399): closing log writer in /hbase/hregion_1218645802/log
[junit] 2008-01-12 15:07:49,804 INFO [IPC Server handler 0 on 60000]
hbase.HMaster(2552): created table testInsertSelectDelete
[junit] 2008-01-12 15:07:49,805 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for
testInsertSelectDelete. Doing a find...
[junit] 2008-01-12 15:07:49,809 DEBUG [main]
hbase.HConnectionManager$TableServers(778): no server address for regionname:
testInsertSelectDelete,,1200150466130, startKey: <>, encodedName(1218645802)
tableDesc: {name: testInsertSelectDelete, families:
{testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}
[junit] 2008-01-12 15:07:49,811 DEBUG [main]
hbase.HConnectionManager$TableServers(676): Sleeping. Table
testInsertSelectDelete not currently being served.
[junit] 2008-01-12 15:07:49,960 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1863): assigning region testInsertSelectDelete,,1200150466130 to
the only server 140.211.11.75:44182
[junit] 2008-01-12 15:07:49,961 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN :
testInsertSelectDelete,,1200150466130
[junit] 2008-01-12 15:07:49,966 DEBUG [RegionServer:0.worker]
hbase.HStore(599): starting
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
(1218645802/testInsertSelectDelete) (no reconstruction log)
[junit] 2008-01-12 15:07:49,969 DEBUG [RegionServer:0.worker]
hbase.HStore(627): maximum sequence id for hstore
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
(1218645802/testInsertSelectDelete) is -1
[junit] 2008-01-12 15:07:49,971 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region
testInsertSelectDelete,,1200150466130 is 0
[junit] 2008-01-12 15:07:49,973 INFO [RegionServer:0.worker]
hbase.HRegion(328): region testInsertSelectDelete,,1200150466130 available
[junit] 2008-01-12 15:07:50,970 DEBUG [IPC Server handler 2 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN :
testInsertSelectDelete,,1200150466130 from 140.211.11.75:44182
[junit] 2008-01-12 15:07:50,971 DEBUG [IPC Server handler 2 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_OPEN :
testInsertSelectDelete,,1200150466130 from 140.211.11.75:44182
[junit] 2008-01-12 15:07:50,971 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(1582): 140.211.11.75:44182 serving
testInsertSelectDelete,,1200150466130
[junit] 2008-01-12 15:07:50,972 DEBUG [HMaster] hbase.HMaster(1057): Main
processing loop: PendingOpenOperation from 140.211.11.75:44182
[junit] 2008-01-12 15:07:50,973 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2463): regionname:
testInsertSelectDelete,,1200150466130, startKey: <>, encodedName(1218645802)
tableDesc: {name: testInsertSelectDelete, families:
{testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}} open on 140.211.11.75:44182
[junit] 2008-01-12 15:07:50,973 DEBUG [HMaster]
hbase.HMaster$RegionServerOperation(1912): numberOfMetaRegions: 1,
onlineMetaRegions.size(): 1
[junit] 2008-01-12 15:07:50,974 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2477): updating row
testInsertSelectDelete,,1200150466130 in table .META.,,1 with startcode
1200150461080 and server 140.211.11.75:44182
[junit] 2008-01-12 15:07:51,929 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0
[junit] 2008-01-12 15:07:52,918 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:44182}
[junit] 2008-01-12 15:07:52,935 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: , startCode: -1
[junit] 2008-01-12 15:07:52,936 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(469): Current assignment of .META.,,1 is no good:
storedInfo: null, startCode: -1, storedInfo.startCode: -1, unassignedRegions:
false, pendingRegions: false
[junit] 2008-01-12 15:07:52,939 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:44182} complete
[junit] 2008-01-12 15:07:52,989 INFO [IPC Server handler 4 on 60000]
hbase.HMaster(1863): assigning region .META.,,1 to the only server
140.211.11.75:44182
[junit] 2008-01-12 15:07:52,990 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN : .META.,,1
[junit] 2008-01-12 15:07:53,999 DEBUG [IPC Server handler 0 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN : .META.,,1 from
140.211.11.75:44182
[junit] 2008-01-12 15:07:54,819 DEBUG [main]
hbase.HConnectionManager$TableServers(685): Wake. Retry finding table
testInsertSelectDelete
[junit] 2008-01-12 15:07:54,825 DEBUG [main]
hbase.HConnectionManager$TableServers(728): Found 1 region(s) for
testInsertSelectDelete at address: 140.211.11.75:44182, 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-12 15:07:54,827 INFO [main] shell.TestHBaseShell(172):
STDOUT: Creating table... Please wait.
[junit] 2008-01-12 15:07:54,836 INFO [main] shell.TestHBaseShell(161):
Running command: insert into testInsertSelectDelete (testInsertSelectDelete)
values ('testInsertSelectDelete') where row='testInsertSelectDelete';
[junit] 2008-01-12 15:07:54,837 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-12 15:07:54,852 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-12 15:07:54,853 INFO [main] shell.TestHBaseShell(161):
Running command: insert into testInsertSelectDelete (testInsertSelectDelete)
values ('testInsertSelectDelete') where row="testInsertSelectDelete";
[junit] 2008-01-12 15:07:54,854 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-12 15:07:54,862 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-12 15:07:54,863 INFO [main] shell.TestHBaseShell(161):
Running command: insert into testInsertSelectDelete (testInsertSelectDelete)
values ("testInsertSelectDelete") where row="testInsertSelectDelete";
[junit] 2008-01-12 15:07:54,864 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-12 15:07:54,870 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-12 15:07:54,871 INFO [main] shell.TestHBaseShell(161):
Running command: select "testInsertSelectDelete" from "testInsertSelectDelete"
where row="testInsertSelectDelete";
[junit] 2008-01-12 15:07:54,872 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-12 15:07:54,928 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:44182}
[junit] 2008-01-12 15:07:54,932 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
testInsertSelectDelete,,1200150466130, startKey: <>, encodedName(1218645802)
tableDesc: {name: testInsertSelectDelete, families:
{testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:44182, startCode: 1200150461080
[junit] 2008-01-12 15:07:54,935 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:44182} complete
[junit] 2008-01-12 15:07:54,935 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(768): all meta regions scanned
[junit] 2008-01-12 15:07:55,065 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-12 15:07:55,066 INFO [main] shell.TestHBaseShell(161):
Running command: delete "testInsertSelectDelete:" from "testInsertSelectDelete"
where row="testInsertSelectDelete";
[junit] 2008-01-12 15:07:55,067 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-12 15:07:55,074 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-12 15:07:55,076 DEBUG [main] hbase.LocalHBaseCluster(214):
Shutting down HBase Cluster
[junit] 2008-01-12 15:07:55,076 INFO [main] hbase.HMaster(2531): Cluster
shutdown requested. Starting to quiesce servers
[junit] 2008-01-12 15:07:55,176 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 70236052/info/6895486429554573376 with 2 entries,
sequence id 7, and size 230.0 for -ROOT-,,0/info
[junit] 2008-01-12 15:07:55,176 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 3248ms,
sequenceid=7
[junit] 2008-01-12 15:07:55,177 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 330.0
[junit] 2008-01-12 15:07:55,178 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore -ROOT-,,0/info not needed.
[junit] 2008-01-12 15:07:55,179 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region -ROOT-,,0 does not need compaction
[junit] 2008-01-12 15:07:56,019 INFO [RegionServer:0]
hbase.HRegionServer(774): Got quiesce server message
[junit] 2008-01-12 15:07:56,019 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGIONSERVER_QUIESCE :
[junit] 2008-01-12 15:07:56,020 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1296): closing region testInsertSelectDelete,,1200150466130
[junit] 2008-01-12 15:07:56,021 DEBUG [RegionServer:0.worker]
hbase.HRegion(837): Started memcache flush for region
testInsertSelectDelete,,1200150466130. Size 294.0
[junit] 2008-01-12 15:08:00,029 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 1028785192/info/2640255908871884391 with 3 entries,
sequence id 12, and size 482.0 for .META.,,1/info
[junit] 2008-01-12 15:08:00,029 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 4852ms,
sequenceid=12
[junit] 2008-01-12 15:08:00,030 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore .META.,,1/info not needed.
[junit] 2008-01-12 15:08:00,031 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region .META.,,1 does not need compaction
[junit] 2008-01-12 15:08:02,146 INFO [HMaster.leaseChecker]
hbase.Leases$Lease(342): HMaster.leaseChecker lease expired
1438016832/1438016832
[junit] 2008-01-12 15:08:02,147 INFO [HMaster.leaseChecker]
hbase.HMaster$ServerExpirer(3152): 140.211.11.75:44182 lease expired
[junit] 2008-01-12 15:08:03,087 INFO [IPC Server handler 4 on 60000]
hbase.HMaster(1385): All user tables quiesced. Proceeding with shutdown
[junit] 2008-01-12 15:08:03,088 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1211): telling root scanner to stop
[junit] 2008-01-12 15:08:03,089 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1219): telling meta scanner to stop
[junit] 2008-01-12 15:08:03,089 INFO [HMaster.rootScanner]
hbase.Chore(66): HMaster.rootScanner exiting
[junit] 2008-01-12 15:08:03,089 INFO [HMaster.metaScanner]
hbase.Chore(66): HMaster.metaScanner exiting
[junit] 2008-01-12 15:08:03,089 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1227): meta and root scanners notified
[junit] 2008-01-12 15:08:03,092 INFO [RegionServer:0]
hbase.HRegionServer(768): Got regionserver stop message
[junit] 2008-01-12 15:08:03,092 INFO [RegionServer:0] hbase.Leases(109):
RegionServer:0 closing leases
[junit] 2008-01-12 15:08:03,093 INFO [RegionServer:0.leaseChecker]
hbase.Chore(66): RegionServer:0.leaseChecker exiting
[junit] 2008-01-12 15:08:03,093 INFO [RegionServer:0] hbase.Leases(125):
RegionServer:0 closed leases
[junit] 2008-01-12 15:08:03,094 DEBUG [RegionServer:0]
hbase.HRegionServer(1262): closing region -ROOT-,,0
[junit] 2008-01-12 15:08:03,095 INFO [RegionServer:0.compactor]
hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
[junit] 2008-01-12 15:08:03,096 INFO [RegionServer:0.cacheFlusher]
hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting
[junit] 2008-01-12 15:08:03,096 INFO [RegionServer:0.splitter]
hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
[junit] 2008-01-12 15:08:03,095 DEBUG [RegionServer:0] hbase.HStore(878):
closed -ROOT-,,0/info (70236052/info)
[junit] 2008-01-12 15:08:03,098 INFO [RegionServer:0] hbase.HRegion(430):
closed -ROOT-,,0
[junit] 2008-01-12 15:08:03,100 DEBUG [RegionServer:0]
hbase.HRegionServer(1262): closing region .META.,,1
[junit] 2008-01-12 15:08:03,100 DEBUG [RegionServer:0] hbase.HStore(878):
closed .META.,,1/info (1028785192/info)
[junit] 2008-01-12 15:08:03,101 INFO [RegionServer:0] hbase.HRegion(430):
closed .META.,,1
[junit] 2008-01-12 15:08:03,116 DEBUG [HMaster] hbase.HMaster(1211):
telling root scanner to stop
[junit] 2008-01-12 15:08:03,117 DEBUG [HMaster] hbase.HMaster(1219):
telling meta scanner to stop
[junit] 2008-01-12 15:08:03,117 DEBUG [HMaster] hbase.HMaster(1227): meta
and root scanners notified
[junit] 2008-01-12 15:08:03,118 INFO [HMaster] hbase.Leases(109): HMaster
closing leases
[junit] 2008-01-12 15:08:03,119 INFO [HMaster.leaseChecker]
hbase.Chore(66): HMaster.leaseChecker exiting
[junit] 2008-01-12 15:08:03,120 INFO [HMaster] hbase.Leases(125): HMaster
closed leases
[junit] 2008-01-12 15:08:03,121 INFO [HMaster] hbase.HMaster(1141):
HMaster main thread exiting
[junit] 2008-01-12 15:08:04,394 DEBUG [RegionServer:0.worker]
hbase.HStore(970): Added 1218645802/testInsertSelectDelete/4902467048170007798
with 3 entries, sequence id 13, and size 371.0 for
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
[junit] 2008-01-12 15:08:04,397 DEBUG [RegionServer:0.worker]
hbase.HRegion(943): Finished memcache flush for region
testInsertSelectDelete,,1200150466130 in 8377ms, sequenceid=13
[junit] 2008-01-12 15:08:04,397 DEBUG [RegionServer:0] hbase.HLog(399):
closing log writer in /hbase/log_140.211.11.75_1200150461080_44182
[junit] 2008-01-12 15:08:04,398 DEBUG [RegionServer:0.worker]
hbase.HStore(878): closed
testInsertSelectDelete,,1200150466130/testInsertSelectDelete
(1218645802/testInsertSelectDelete)
[junit] 2008-01-12 15:08:04,398 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed testInsertSelectDelete,,1200150466130
[junit] 2008-01-12 15:08:04,399 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1203): worker thread exiting
[junit] 2008-01-12 15:08:05,768 INFO [RegionServer:0]
hbase.HRegionServer(888): telling master that region server is shutting down
at: 140.211.11.75:44182
[junit] 2008-01-12 15:08:15,865 WARN [RegionServer:0]
hbase.HRegionServer(892): Failed to send exiting message to master:
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
[junit] at
java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
[junit] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit] at java.net.Socket.connect(Socket.java:520)
[junit] at
org.apache.hadoop.ipc.Client$Connection.setupIOstreams(Client.java:159)
[junit] at org.apache.hadoop.ipc.Client.getConnection(Client.java:575)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:498)
[junit] at
org.apache.hadoop.hbase.ipc.HbaseRPC$Invoker.invoke(HbaseRPC.java:210)
[junit] at $Proxy6.regionServerReport(Unknown Source)
[junit] at
org.apache.hadoop.hbase.HRegionServer.run(HRegionServer.java:890)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2008-01-12 15:08:15,866 INFO [RegionServer:0]
hbase.HRegionServer(895): stopping server at: 140.211.11.75:44182
[junit] 2008-01-12 15:08:15,867 INFO [RegionServer:0]
hbase.HRegionServer(900): RegionServer:0 exiting
[junit] 2008-01-12 15:08:15,868 INFO [main] hbase.LocalHBaseCluster(243):
Shutdown HMaster 1 region server(s)
[junit] 2008-01-12 15:08:15,869 INFO [main]
hbase.StaticTestEnvironment(135): Shutting down FileSystem
[junit] 2008-01-12 15:08:16,394 INFO [main]
hbase.StaticTestEnvironment(142): Shutting down Mini DFS
[junit] 2008-01-12 15:08:16,914 WARN [DataNode:
[http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/contrib/hbase/test/data/dfs/data/data3,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/contrib/hbase/test/data/dfs/data/data4]]
dfs.DataNode(658): java.io.InterruptedIOException
[junit] at java.net.SocketOutputStream.socketWrite0(Native Method)
[junit] at
java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:92)
[junit] at
java.net.SocketOutputStream.write(SocketOutputStream.java:136)
[junit] at
org.apache.hadoop.ipc.Client$Connection$2.write(Client.java:199)
[junit] at
java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65)
[junit] at
java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123)
[junit] at java.io.DataOutputStream.flush(DataOutputStream.java:106)
[junit] at
org.apache.hadoop.ipc.Client$Connection.sendParam(Client.java:344)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:501)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
[junit] at org.apache.hadoop.dfs.$Proxy1.sendHeartbeat(Unknown Source)
[junit] at
org.apache.hadoop.dfs.DataNode.offerService(DataNode.java:562)
[junit] at org.apache.hadoop.dfs.DataNode.run(DataNode.java:1736)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 217.811 sec
[junit] 2008-01-12 15:08:19,239 INFO [main]
hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
[junit] 2008-01-12 15:08:19,240 INFO [main]
hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
[junit] 2008-01-12 15:08:19,240 INFO [main]
hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
[junit] 2008-01-12 15:08:19,240 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.399 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.076 sec
[junit] Running org.onelab.test.TestFilter
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.074 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml
:516: 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: 213 minutes 23 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2562
Updating HADOOP-2131
Updating HADOOP-1873
Updating HADOOP-2540
Updating HADOOP-2389
Updating HADOOP-2558
Updating HADOOP-2567
Updating HADOOP-2464
Updating HADOOP-1965
Updating HADOOP-2487
