hbase-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Jean-Daniel Cryans <jdcry...@apache.org>
Subject Re: Build failed in Hudson: HBase-Patch #1114
Date Thu, 18 Feb 2010 23:54:30 GMT
TestHLog works for me, let's dig to see why Hudson complains...

J-D

On Thu, Feb 18, 2010 at 3:48 PM, Apache Hudson Server
<hudson@hudson.zones.apache.org> wrote:
> See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1114/changes>
>
> Changes:
>
> [jdcryans] HBASE-2221  MR to copy a table
>
> ------------------------------------------
> [...truncated 149783 lines...]
>    [junit] 2010-02-18 23:48:06,965 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:06,966 DEBUG [main] regionserver.Store(482): closed contents
>    [junit] 2010-02-18 23:48:06,966 INFO  [main] regionserver.HRegion(491): Closed
TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:06,966 DEBUG [main] regionserver.HRegion(455): Closing
-ROOT-,,0: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:06,966 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on -ROOT-,,0
>    [junit] 2010-02-18 23:48:06,966 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region -ROOT-,,0
>    [junit] 2010-02-18 23:48:06,967 DEBUG [main] regionserver.Store(482): closed info
>    [junit] 2010-02-18 23:48:06,967 INFO  [main] regionserver.HRegion(491): Closed
-ROOT-,,0
>    [junit] 2010-02-18 23:48:06,967 INFO  [main] util.MetaUtils(151): CLOSING META
.META.,,1
>    [junit] 2010-02-18 23:48:06,967 DEBUG [main] regionserver.HRegion(455): Closing
.META.,,1: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:06,967 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on .META.,,1
>    [junit] 2010-02-18 23:48:06,968 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region .META.,,1
>    [junit] 2010-02-18 23:48:06,968 DEBUG [main] regionserver.HRegion(896): Started
memstore flush for region .META.,,1. Current region memstore size 1.0k
>    [junit] 2010-02-18 23:48:07,021 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:47694/user/hudson/.META./1028785192/historian/409668627159345743,
entries=2, sequenceid=27, memsize=288, filesize=521 to .META.,,1
>    [junit] 2010-02-18 23:48:07,186 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:47694/user/hudson/.META./1028785192/info/6875943967741515652,
entries=3, sequenceid=27, memsize=752, filesize=900 to .META.,,1
>    [junit] 2010-02-18 23:48:07,187 DEBUG [main] regionserver.HRegion(972): Finished
memstore flush of ~1.0k for region .META.,,1 in 219ms, sequence id=27, compaction requested=true
>    [junit] 2010-02-18 23:48:07,187 DEBUG [main] regionserver.Store(482): closed historian
>    [junit] 2010-02-18 23:48:07,187 DEBUG [main] regionserver.Store(482): closed info
>    [junit] 2010-02-18 23:48:07,187 INFO  [main] regionserver.HRegion(491): Closed
.META.,,1
>    [junit] 2010-02-18 23:48:07,214 WARN  [main] conf.Configuration(617): fs.checkpoint.size
is deprecated. Instead, use dfs.namenode.checkpoint.size
>    [junit] 2010-02-18 23:48:07,214 WARN  [main] conf.Configuration(617): fs.checkpoint.dir
is deprecated. Instead, use dfs.namenode.checkpoint.dir
>    [junit] 2010-02-18 23:48:07,215 WARN  [main] conf.Configuration(617): fs.checkpoint.edits.dir
is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
>    [junit] 2010-02-18 23:48:07,215 WARN  [main] conf.Configuration(617): fs.checkpoint.period
is deprecated. Instead, use dfs.namenode.checkpoint.period
>    [junit] 2010-02-18 23:48:07,226 INFO  [main] wal.HLog(364): Roll /user/hudson/.logs_1266536886009/hlog.dat.1266536886028,
entries=6, calcsize=1180, filesize=1131. New hlog /user/hudson/.logs_1266536886009/hlog.dat.1266536887203
>    [junit] 2010-02-18 23:48:07,226 DEBUG [main] wal.HLog(373): Last sequence written
is empty. Deleting all old hlogs
>    [junit] 2010-02-18 23:48:07,226 INFO  [main] wal.HLog(562): moving old hlog file
/user/hudson/.logs_1266536886009/hlog.dat.1266536886028 whose highest sequence/edit id is
26 to /user/hudson/.oldlogs/1266536887226.hlog.dat.1266536886028
>    [junit] 2010-02-18 23:48:07,235 DEBUG [main.logSyncer] wal.HLog$LogSyncer(788):
main.logSyncerinterrupted while waiting for sync requests
>    [junit] 2010-02-18 23:48:07,235 INFO  [main.logSyncer] wal.HLog$LogSyncer(792):
main.logSyncer exiting
>    [junit] 2010-02-18 23:48:07,235 DEBUG [main] wal.HLog(615): closing hlog writer
in hdfs://localhost:47694/user/hudson/.logs_1266536886009
>    [junit] 2010-02-18 23:48:07,263 DEBUG [main] wal.HLog(591): Moved 1 log files to
/user/hudson/.oldlogs
>    [junit] 2010-02-18 23:48:07,273 DEBUG [main] regionserver.HRegion(1887): Opening
region: REGION => {NAME => 'TestMergeTool,row_0100,1266536886671', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 258576959, TABLE => {{NAME => 'TestMergeTool',
FAMILIES => [{NAME => 'contents', REPLICATION_SCOPE => '0', VERSIONS => '3', COMPRESSION
=> 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
>    [junit] 2010-02-18 23:48:07,273 DEBUG [main] regionserver.HRegion(266): Opening
region TestMergeTool,row_0100,1266536886671, encoded=258576959
>    [junit] 2010-02-18 23:48:07,280 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/258576959/contents/4074684400220280349,
isReference=false, sequence id=6, length=791, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,282 INFO  [main] regionserver.HRegion(344): region
TestMergeTool,row_0100,1266536886671/258576959 available; sequence id is 7
>    [junit] 2010-02-18 23:48:07,287 DEBUG [main] regionserver.HRegion(455): Closing
TestMergeTool,row_0100,1266536886671: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:07,287 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,287 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,288 DEBUG [main] regionserver.Store(482): closed contents
>    [junit] 2010-02-18 23:48:07,288 INFO  [main] regionserver.HRegion(491): Closed
TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,288 INFO  [main] util.TestMergeTool(188): Verified
merging regions 0+1+2 and 3
>    [junit] 2010-02-18 23:48:07,289 INFO  [main] util.TestMergeTool(174): merging regions
0+1+2+3 and 4
>    [junit] fs2=hdfs://localhost:47694
>    [junit] 2010-02-18 23:48:07,289 WARN  [main] conf.Configuration(617): mapred.used.genericoptionsparser
is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
>    [junit] 2010-02-18 23:48:07,289 WARN  [main] conf.Configuration(617): mapred.used.genericoptionsparser
is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
>    [junit] 2010-02-18 23:48:07,290 INFO  [main] util.Merge(81): Verifying that file
system is available...
>    [junit] 2010-02-18 23:48:07,291 INFO  [main] util.Merge(90): Verifying that HBase
is not running...
>    [junit] 2010-02-18 23:48:07,302 WARN  [main] conf.Configuration(617): fs.checkpoint.size
is deprecated. Instead, use dfs.namenode.checkpoint.size
>    [junit] 2010-02-18 23:48:07,302 WARN  [main] conf.Configuration(617): fs.checkpoint.dir
is deprecated. Instead, use dfs.namenode.checkpoint.dir
>    [junit] 2010-02-18 23:48:07,302 WARN  [main] conf.Configuration(617): fs.checkpoint.edits.dir
is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
>    [junit] 2010-02-18 23:48:07,303 WARN  [main] conf.Configuration(617): fs.checkpoint.period
is deprecated. Instead, use dfs.namenode.checkpoint.period
>    [junit] 2010-02-18 23:48:07,304 INFO  [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1266536886671
and TestMergeTool,,1266536878236 in table TestMergeTool
>    [junit] 2010-02-18 23:48:07,317 INFO  [main] wal.HLog(286): HLog configuration:
blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
>    [junit] 2010-02-18 23:48:07,328 WARN  [main] conf.Configuration(617): fs.checkpoint.size
is deprecated. Instead, use dfs.namenode.checkpoint.size
>    [junit] 2010-02-18 23:48:07,328 WARN  [main] conf.Configuration(617): fs.checkpoint.dir
is deprecated. Instead, use dfs.namenode.checkpoint.dir
>    [junit] 2010-02-18 23:48:07,328 WARN  [main] conf.Configuration(617): fs.checkpoint.edits.dir
is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
>    [junit] 2010-02-18 23:48:07,329 WARN  [main] conf.Configuration(617): fs.checkpoint.period
is deprecated. Instead, use dfs.namenode.checkpoint.period
>    [junit] 2010-02-18 23:48:07,339 INFO  [main] wal.HLog(364): New hlog /user/hudson/.logs_1266536887304/hlog.dat.1266536887317
>    [junit] 2010-02-18 23:48:07,339 DEBUG [main] regionserver.HRegion(1887): Opening
region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED
=> 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true',
FAMILIES => [{NAME => 'info', REPLICATION_SCOPE => '0', COMPRESSION => 'NONE',
VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false',
BLOCKCACHE => 'true'}]}}
>    [junit] 2010-02-18 23:48:07,340 DEBUG [main] regionserver.HRegion(266): Opening
region -ROOT-,,0, encoded=70236052
>    [junit] 2010-02-18 23:48:07,347 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/1973129290690910886,
isReference=false, sequence id=2, length=861, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,349 INFO  [main] regionserver.HRegion(344): region
-ROOT-,,0/70236052 available; sequence id is 3
>    [junit] 2010-02-18 23:48:07,349 INFO  [main] regionserver.HRegion(772): Starting
compaction on region -ROOT-,,0
>    [junit] 2010-02-18 23:48:07,351 INFO  [main] regionserver.HRegion(787): compaction
completed on region -ROOT-,,0 in 0sec
>    [junit] 2010-02-18 23:48:07,351 INFO  [main] util.Merge(210): Found meta for region1
.META.,,1, meta for region2 .META.,,1
>    [junit] 2010-02-18 23:48:07,352 DEBUG [main] regionserver.HRegion(1887): Opening
region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED
=> 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES =>
[{NAME => 'historian', REPLICATION_SCOPE => '0', VERSIONS => '2147483647', COMPRESSION
=> 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE
=> 'false'}, {NAME => 'info', REPLICATION_SCOPE => '0', VERSIONS => '10', COMPRESSION
=> 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
>    [junit] 2010-02-18 23:48:07,353 DEBUG [main] regionserver.HRegion(266): Opening
region .META.,,1, encoded=1028785192
>    [junit] 2010-02-18 23:48:07,359 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/2001587314376246358,
isReference=false, sequence id=20, length=651, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,363 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/409668627159345743,
isReference=false, sequence id=27, length=521, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,369 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/6875943967741515652,
isReference=false, sequence id=27, length=900, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,373 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/774533984354751081,
isReference=false, sequence id=20, length=1020, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,377 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/8537934917464226378,
isReference=false, sequence id=6, length=2356, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,380 INFO  [main] regionserver.HRegion(344): region
.META.,,1/1028785192 available; sequence id is 28
>    [junit] 2010-02-18 23:48:07,380 INFO  [main] regionserver.HRegion(772): Starting
compaction on region .META.,,1
>    [junit] 2010-02-18 23:48:07,392 DEBUG [main] regionserver.Store(750): Compaction
size of historian: 1.1k; Skipped 0 file(s), size: 0
>    [junit] 2010-02-18 23:48:07,392 DEBUG [main] regionserver.Store(757): Started compaction
of 2 file(s)  into /user/hudson/.META./compaction.dir/1028785192, seqid=27
>    [junit] 2010-02-18 23:48:07,452 DEBUG [main] regionserver.Store(764): Completed
compaction of historian; new storefile is hdfs://localhost:47694/user/hudson/.META./1028785192/historian/5064641853431416626;
store size is 781
>    [junit] 2010-02-18 23:48:07,453 DEBUG [main] regionserver.Store(750): Compaction
size of info: 4.2k; Skipped 1 file(s), size: 2356
>    [junit] 2010-02-18 23:48:07,454 DEBUG [main] regionserver.Store(757): Started compaction
of 2 file(s)  into /user/hudson/.META./compaction.dir/1028785192, seqid=27
>    [junit] 2010-02-18 23:48:07,523 DEBUG [main] regionserver.Store(764): Completed
compaction of info; new storefile is hdfs://localhost:47694/user/hudson/.META./1028785192/info/5386583736874733695;
store size is 3.4k
>    [junit] 2010-02-18 23:48:07,534 INFO  [main] regionserver.HRegion(787): compaction
completed on region .META.,,1 in 0sec
>    [junit] 2010-02-18 23:48:07,534 INFO  [main] util.MetaUtils(128): OPENING META
.META.,,1
>    [junit] 2010-02-18 23:48:07,536 DEBUG [main] regionserver.HRegion(1887): Opening
region: REGION => {NAME => 'TestMergeTool,row_0100,1266536886671', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 258576959, TABLE => {{NAME => 'TestMergeTool',
FAMILIES => [{NAME => 'contents', REPLICATION_SCOPE => '0', COMPRESSION => 'NONE',
VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false',
BLOCKCACHE => 'true'}]}}
>    [junit] 2010-02-18 23:48:07,537 DEBUG [main] regionserver.HRegion(266): Opening
region TestMergeTool,row_0100,1266536886671, encoded=258576959
>    [junit] 2010-02-18 23:48:07,543 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/258576959/contents/4074684400220280349,
isReference=false, sequence id=6, length=791, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,545 INFO  [main] regionserver.HRegion(344): region
TestMergeTool,row_0100,1266536886671/258576959 available; sequence id is 7
>    [junit] 2010-02-18 23:48:07,545 DEBUG [main] regionserver.HRegion(1887): Opening
region: REGION => {NAME => 'TestMergeTool,,1266536878236', STARTKEY => '', ENDKEY
=> '', ENCODED => 1028693225, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
[{NAME => 'contents', REPLICATION_SCOPE => '0', VERSIONS => '3', COMPRESSION =>
'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
>    [junit] 2010-02-18 23:48:07,546 DEBUG [main] regionserver.HRegion(266): Opening
region TestMergeTool,,1266536878236, encoded=1028693225
>    [junit] 2010-02-18 23:48:07,552 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1028693225/contents/5626545645376005898,
isReference=false, sequence id=7, length=471, majorCompaction=false
>    [junit] 2010-02-18 23:48:07,554 INFO  [main] regionserver.HRegion(344): region
TestMergeTool,,1266536878236/1028693225 available; sequence id is 8
>    [junit] 2010-02-18 23:48:07,555 INFO  [main] regionserver.HRegion(772): Starting
major compaction on region TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,563 DEBUG [main] regionserver.Store(757): Started compaction
of 1 file(s)  into /user/hudson/TestMergeTool/compaction.dir/258576959, seqid=6
>    [junit] 2010-02-18 23:48:07,614 DEBUG [main] regionserver.Store(764): Completed
major compaction of contents; new storefile is hdfs://localhost:47694/user/hudson/TestMergeTool/258576959/contents/2418035053595597387;
store size is 791
>    [junit] 2010-02-18 23:48:07,623 INFO  [main] regionserver.HRegion(787): compaction
completed on region TestMergeTool,row_0100,1266536886671 in 0sec
>    [junit] 2010-02-18 23:48:07,623 DEBUG [main] regionserver.HRegion(2120): Files for
region: TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,624 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/258576959/.regioninfo
size=683
>    [junit] 2010-02-18 23:48:07,624 DEBUG [main] regionserver.HRegion(2264): d hdfs://localhost:47694/user/hudson/TestMergeTool/258576959/contents
>    [junit] 2010-02-18 23:48:07,625 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/258576959/contents/2418035053595597387
size=791
>    [junit] 2010-02-18 23:48:07,625 INFO  [main] regionserver.HRegion(772): Starting
major compaction on region TestMergeTool,,1266536878236
>    [junit] 2010-02-18 23:48:07,635 DEBUG [main] regionserver.Store(757): Started compaction
of 1 file(s)  into /user/hudson/TestMergeTool/compaction.dir/1028693225, seqid=7
>    [junit] 2010-02-18 23:48:07,805 DEBUG [main] regionserver.Store(764): Completed
major compaction of contents; new storefile is hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225/contents/4037490353603699837;
store size is 471
>    [junit] 2010-02-18 23:48:07,813 INFO  [main] regionserver.HRegion(787): compaction
completed on region TestMergeTool,,1266536878236 in 0sec
>    [junit] 2010-02-18 23:48:07,813 DEBUG [main] regionserver.HRegion(2125): Files for
region: TestMergeTool,,1266536878236
>    [junit] 2010-02-18 23:48:07,814 DEBUG [main] regionserver.HRegion(2264): d hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225/.oldlogs
>    [junit] 2010-02-18 23:48:07,815 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225/.oldlogs/1266536883477.hlog.dat.1266536882620
size=970
>    [junit] 2010-02-18 23:48:07,816 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225/.regioninfo
size=636
>    [junit] 2010-02-18 23:48:07,816 DEBUG [main] regionserver.HRegion(2264): d hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225/contents
>    [junit] 2010-02-18 23:48:07,817 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225/contents/4037490353603699837
size=471
>    [junit] 2010-02-18 23:48:07,817 INFO  [main] regionserver.HRegion(2154): Creating
new region REGION => {NAME => 'TestMergeTool,,1266536887817', STARTKEY => '', ENDKEY
=> '', ENCODED => 1229871099, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
[{NAME => 'contents', REPLICATION_SCOPE => '0', COMPRESSION => 'NONE', VERSIONS =>
'3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
>    [junit] 2010-02-18 23:48:07,825 INFO  [main] regionserver.HRegion(2163): starting
merge of regions: TestMergeTool,row_0100,1266536886671 and TestMergeTool,,1266536878236 into
new region REGION => {NAME => 'TestMergeTool,,1266536887817', STARTKEY => '', ENDKEY
=> '', ENCODED => 1229871099, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
[{NAME => 'contents', REPLICATION_SCOPE => '0', COMPRESSION => 'NONE', VERSIONS =>
'3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}} with start key <> and end key <>
>    [junit] 2010-02-18 23:48:07,825 DEBUG [main] regionserver.HRegion(455): Closing
TestMergeTool,row_0100,1266536886671: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:07,826 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,826 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,826 DEBUG [main] regionserver.Store(482): closed contents
>    [junit] 2010-02-18 23:48:07,826 INFO  [main] regionserver.HRegion(491): Closed
TestMergeTool,row_0100,1266536886671
>    [junit] 2010-02-18 23:48:07,827 DEBUG [main] regionserver.HRegion(455): Closing
TestMergeTool,,1266536878236: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:07,827 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on TestMergeTool,,1266536878236
>    [junit] 2010-02-18 23:48:07,827 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region TestMergeTool,,1266536878236
>    [junit] 2010-02-18 23:48:07,828 DEBUG [main] regionserver.Store(482): closed contents
>    [junit] 2010-02-18 23:48:07,828 INFO  [main] regionserver.HRegion(491): Closed
TestMergeTool,,1266536878236
>    [junit] 2010-02-18 23:48:07,861 DEBUG [main] regionserver.HRegion(2196): Files for
new region
>    [junit] 2010-02-18 23:48:07,862 DEBUG [main] regionserver.HRegion(2264): d hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/contents
>    [junit] 2010-02-18 23:48:07,863 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/contents/2193916411242360354
size=791
>    [junit] 2010-02-18 23:48:07,863 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/contents/6703457139540669353
size=471
>    [junit] 2010-02-18 23:48:07,863 DEBUG [main] regionserver.HRegion(266): Opening
region TestMergeTool,,1266536887817, encoded=1229871099
>    [junit] 2010-02-18 23:48:07,890 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1229871099/contents/2193916411242360354,
isReference=false, sequence id=6, length=791, majorCompaction=true
>    [junit] 2010-02-18 23:48:07,894 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1229871099/contents/6703457139540669353,
isReference=false, sequence id=7, length=471, majorCompaction=true
>    [junit] 2010-02-18 23:48:07,896 INFO  [main] regionserver.HRegion(344): region
TestMergeTool,,1266536887817/1229871099 available; sequence id is 8
>    [junit] 2010-02-18 23:48:07,897 INFO  [main] regionserver.HRegion(772): Starting
compaction on region TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:07,905 DEBUG [main] regionserver.Store(750): Compaction
size of contents: 1.2k; Skipped 0 file(s), size: 0
>    [junit] 2010-02-18 23:48:07,905 DEBUG [main] regionserver.Store(757): Started compaction
of 2 file(s)  into /user/hudson/TestMergeTool/compaction.dir/1229871099, seqid=7
>    [junit] 2010-02-18 23:48:07,960 DEBUG [main] regionserver.Store(764): Completed
compaction of contents; new storefile is hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/contents/8717755776195324955;
store size is 929
>    [junit] 2010-02-18 23:48:07,968 INFO  [main] regionserver.HRegion(787): compaction
completed on region TestMergeTool,,1266536887817 in 0sec
>    [junit] 2010-02-18 23:48:07,970 DEBUG [main] regionserver.HRegion(2203): Files for
new region
>    [junit] 2010-02-18 23:48:07,971 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/.regioninfo
size=636
>    [junit] 2010-02-18 23:48:07,971 DEBUG [main] regionserver.HRegion(2264): d hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/contents
>    [junit] 2010-02-18 23:48:07,972 DEBUG [main] regionserver.HRegion(2267): f hdfs://localhost:47694/user/hudson/TestMergeTool/1229871099/contents/8717755776195324955
size=929
>    [junit] 2010-02-18 23:48:07,972 DEBUG [main] regionserver.HRegion(1999): DELETING
region hdfs://localhost:47694/user/hudson/TestMergeTool/258576959
>    [junit] 2010-02-18 23:48:07,980 DEBUG [main] regionserver.HRegion(1999): DELETING
region hdfs://localhost:47694/user/hudson/TestMergeTool/1028693225
>    [junit] 2010-02-18 23:48:07,988 INFO  [main] regionserver.HRegion(2209): merge
completed. New region is TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:07,989 DEBUG [main] util.Merge(318): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0100,1266536886671', STARTKEY => 'row_0100', ENDKEY
=> 'row_0600', ENCODED => 258576959, TABLE => {{NAME => 'TestMergeTool', FAMILIES
=> [{NAME => 'contents', REPLICATION_SCOPE => '0', COMPRESSION => 'NONE', VERSIONS
=> '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}} from .META.,,1
>    [junit] 2010-02-18 23:48:07,989 DEBUG [main] util.Merge(318): Removing region: REGION
=> {NAME => 'TestMergeTool,,1266536878236', STARTKEY => '', ENDKEY => '', ENCODED
=> 1028693225, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents',
REPLICATION_SCOPE => '0', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
>    [junit] 2010-02-18 23:48:07,991 INFO  [main] util.Merge(254): Adding REGION =>
{NAME => 'TestMergeTool,,1266536887817', STARTKEY => '', ENDKEY => '', ENCODED =>
1229871099, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents',
REPLICATION_SCOPE => '0', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION =>
{NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE
=> {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian',
REPLICATION_SCOPE => '0', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL =>
'604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME
=> 'info', REPLICATION_SCOPE => '0', VERSIONS => '10', COMPRESSION => 'NONE',
TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE =>
'true'}]}}
>    [junit] 2010-02-18 23:48:07,991 DEBUG [main] regionserver.HRegion(455): Closing
TestMergeTool,,1266536887817: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:07,991 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:07,991 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:07,992 DEBUG [main] regionserver.Store(482): closed contents
>    [junit] 2010-02-18 23:48:07,992 INFO  [main] regionserver.HRegion(491): Closed
TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:07,992 DEBUG [main] regionserver.HRegion(455): Closing
-ROOT-,,0: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:07,992 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on -ROOT-,,0
>    [junit] 2010-02-18 23:48:07,993 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region -ROOT-,,0
>    [junit] 2010-02-18 23:48:07,993 DEBUG [main] regionserver.Store(482): closed info
>    [junit] 2010-02-18 23:48:07,993 INFO  [main] regionserver.HRegion(491): Closed
-ROOT-,,0
>    [junit] 2010-02-18 23:48:07,993 INFO  [main] util.MetaUtils(151): CLOSING META
.META.,,1
>    [junit] 2010-02-18 23:48:07,994 DEBUG [main] regionserver.HRegion(455): Closing
.META.,,1: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:07,994 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on .META.,,1
>    [junit] 2010-02-18 23:48:07,994 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region .META.,,1
>    [junit] 2010-02-18 23:48:07,994 DEBUG [main] regionserver.HRegion(896): Started
memstore flush for region .META.,,1. Current region memstore size 992
>    [junit] 2010-02-18 23:48:08,019 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:47694/user/hudson/.META./1028785192/historian/2977056769647652175,
entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
>    [junit] 2010-02-18 23:48:08,043 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:47694/user/hudson/.META./1028785192/info/165693408957883380,
entries=3, sequenceid=34, memsize=712, filesize=852 to .META.,,1
>    [junit] 2010-02-18 23:48:08,043 DEBUG [main] regionserver.HRegion(972): Finished
memstore flush of ~992 for region .META.,,1 in 49ms, sequence id=34, compaction requested=true
>    [junit] 2010-02-18 23:48:08,044 DEBUG [main] regionserver.Store(482): closed historian
>    [junit] 2010-02-18 23:48:08,044 DEBUG [main] regionserver.Store(482): closed info
>    [junit] 2010-02-18 23:48:08,044 INFO  [main] regionserver.HRegion(491): Closed
.META.,,1
>    [junit] 2010-02-18 23:48:08,067 WARN  [main] conf.Configuration(617): fs.checkpoint.size
is deprecated. Instead, use dfs.namenode.checkpoint.size
>    [junit] 2010-02-18 23:48:08,067 WARN  [main] conf.Configuration(617): fs.checkpoint.dir
is deprecated. Instead, use dfs.namenode.checkpoint.dir
>    [junit] 2010-02-18 23:48:08,068 WARN  [main] conf.Configuration(617): fs.checkpoint.edits.dir
is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
>    [junit] 2010-02-18 23:48:08,068 WARN  [main] conf.Configuration(617): fs.checkpoint.period
is deprecated. Instead, use dfs.namenode.checkpoint.period
>    [junit] 2010-02-18 23:48:08,077 INFO  [main] wal.HLog(364): Roll /user/hudson/.logs_1266536887304/hlog.dat.1266536887317,
entries=6, calcsize=1132, filesize=1063. New hlog /user/hudson/.logs_1266536887304/hlog.dat.1266536888056
>    [junit] 2010-02-18 23:48:08,078 DEBUG [main] wal.HLog(373): Last sequence written
is empty. Deleting all old hlogs
>    [junit] 2010-02-18 23:48:08,078 INFO  [main] wal.HLog(562): moving old hlog file
/user/hudson/.logs_1266536887304/hlog.dat.1266536887317 whose highest sequence/edit id is
33 to /user/hudson/.oldlogs/1266536888078.hlog.dat.1266536887317
>    [junit] 2010-02-18 23:48:08,088 DEBUG [main.logSyncer] wal.HLog$LogSyncer(788):
main.logSyncerinterrupted while waiting for sync requests
>    [junit] 2010-02-18 23:48:08,089 INFO  [main.logSyncer] wal.HLog$LogSyncer(792):
main.logSyncer exiting
>    [junit] 2010-02-18 23:48:08,090 DEBUG [main] wal.HLog(615): closing hlog writer
in hdfs://localhost:47694/user/hudson/.logs_1266536887304
>    [junit] 2010-02-18 23:48:08,112 DEBUG [main] wal.HLog(591): Moved 1 log files to
/user/hudson/.oldlogs
>    [junit] 2010-02-18 23:48:08,120 DEBUG [main] regionserver.HRegion(1887): Opening
region: REGION => {NAME => 'TestMergeTool,,1266536887817', STARTKEY => '', ENDKEY
=> '', ENCODED => 1229871099, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
[{NAME => 'contents', REPLICATION_SCOPE => '0', COMPRESSION => 'NONE', VERSIONS =>
'3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
>    [junit] 2010-02-18 23:48:08,120 DEBUG [main] regionserver.HRegion(266): Opening
region TestMergeTool,,1266536887817, encoded=1229871099
>    [junit] 2010-02-18 23:48:08,126 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1229871099/contents/8717755776195324955,
isReference=false, sequence id=7, length=929, majorCompaction=false
>    [junit] 2010-02-18 23:48:08,128 INFO  [main] regionserver.HRegion(344): region
TestMergeTool,,1266536887817/1229871099 available; sequence id is 8
>    [junit] 2010-02-18 23:48:08,133 DEBUG [main] regionserver.HRegion(455): Closing
TestMergeTool,,1266536887817: compactions & flushes disabled
>    [junit] 2010-02-18 23:48:08,133 DEBUG [main] regionserver.HRegion(472): Updates
disabled for region, no outstanding scanners on TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:08,134 DEBUG [main] regionserver.HRegion(479): No more
row locks outstanding on region TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:08,134 DEBUG [main] regionserver.Store(482): closed contents
>    [junit] 2010-02-18 23:48:08,134 INFO  [main] regionserver.HRegion(491): Closed
TestMergeTool,,1266536887817
>    [junit] 2010-02-18 23:48:08,134 INFO  [main] util.TestMergeTool(188): Verified
merging regions 0+1+2+3 and 4
>    [junit] 2010-02-18 23:48:08,135 DEBUG [main.logSyncer] wal.HLog$LogSyncer(788):
main.logSyncerinterrupted while waiting for sync requests
>    [junit] 2010-02-18 23:48:08,135 INFO  [main.logSyncer] wal.HLog$LogSyncer(792):
main.logSyncer exiting
>    [junit] 2010-02-18 23:48:08,135 DEBUG [main] wal.HLog(615): closing hlog writer
in /tmp/.logs_1266536883664
>    [junit] 2010-02-18 23:48:08,155 DEBUG [main] wal.HLog(591): Moved 1 log files to
/tmp/.oldlogs
>    [junit] 2010-02-18 23:48:08,164 INFO  [main] hbase.HBaseTestCase(628): Shutting
down Mini DFS
>    [junit] Shutting down the Mini HDFS Cluster
>    [junit] Shutting down DataNode 1
>    [junit] 2010-02-18 23:48:08,267 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1958cc2]
datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:56524, storageID=DS-122831863-67.195.138.9-56524-1266536881113,
infoPort=60273, ipcPort=50149):DataXceiveServer: java.nio.channels.AsynchronousCloseException
>    [junit]     at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
>    [junit]     at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
>    [junit]     at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
>    [junit]     at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:133)
>    [junit]     at java.lang.Thread.run(Thread.java:619)
>    [junit]
>    [junit] Shutting down DataNode 0
>    [junit] 2010-02-18 23:48:08,411 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@9300cc]
datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:57539, storageID=DS-673610128-67.195.138.9-57539-1266536880314,
infoPort=33320, ipcPort=35354):DataXceiveServer: java.nio.channels.AsynchronousCloseException
>    [junit]     at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
>    [junit]     at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
>    [junit]     at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
>    [junit]     at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:133)
>    [junit]     at java.lang.Thread.run(Thread.java:619)
>    [junit]
>    [junit] 2010-02-18 23:48:08,562 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@ebcdbb]
namenode.FSNamesystem$ReplicationMonitor(2328): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
>    [junit] 2010-02-18 23:48:08,563 WARN  [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@174d93a]
namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException:
sleep interrupted
>    [junit] 2010-02-18 23:48:08,575 INFO  [main] hbase.HBaseTestCase(639): Shutting
down FileSystem
>    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 10.521 sec
>    [junit] 2010-02-18 23:48:09,833 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(623):
Closed connection with ZooKeeper
>    [junit] Running org.apache.hadoop.hbase.util.TestRootPath
>    [junit] 2010-02-18 23:48:10,219 INFO  [main] util.TestRootPath(60): Got expected
exception when checking invalid path:
>    [junit] java.io.IOException: Root directory does not have a scheme
>    [junit]     at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
>    [junit]     at org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
>    [junit]     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>    [junit]     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>    [junit]     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>    [junit]     at java.lang.reflect.Method.invoke(Method.java:597)
>    [junit]     at junit.framework.TestCase.runTest(TestCase.java:168)
>    [junit]     at junit.framework.TestCase.runBare(TestCase.java:134)
>    [junit]     at junit.framework.TestResult$1.protect(TestResult.java:110)
>    [junit]     at junit.framework.TestResult.runProtected(TestResult.java:128)
>    [junit]     at junit.framework.TestResult.run(TestResult.java:113)
>    [junit]     at junit.framework.TestCase.run(TestCase.java:124)
>    [junit]     at junit.framework.TestSuite.runTest(TestSuite.java:232)
>    [junit]     at junit.framework.TestSuite.run(TestSuite.java:227)
>    [junit]     at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
>    [junit]     at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
>    [junit]     at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
>    [junit]     at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
>    [junit]     at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
>    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.091 sec
>    [junit] 2010-02-18 23:48:10,685 WARN  [main] hbase.HBaseConfiguration(45): instantinating
HBaseConfiguration() is deprecated. Please use HBaseConfiguration#create() to construct a
plain Configuration
>    [junit] 2010-02-18 23:48:10,747 WARN  [main] hbase.HBaseConfiguration(45): instantinating
HBaseConfiguration() is deprecated. Please use HBaseConfiguration#create() to construct a
plain Configuration
>    [junit] Running org.apache.hadoop.hbase.zookeeper.TestHQuorumPeer
>    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.256 sec
>
> BUILD FAILED
> <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:539:
Tests failed!
>
> Total time: 29 minutes 33 seconds
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released
> Archiving artifacts
> Recording test results
> Publishing Clover coverage report...
> No Clover report will be published due to a Build Failure
>
>

Mime
View raw message